{"level":"warn","ts":"2026-06-15T12:37:53.010306Z","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-15T12:37:53.010456Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-15T12:37:53.010529Z","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-15T12:37:53.010543Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-15T12:37:53.010562Z","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-15T12:37:53.011265Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-06-15T12:37:53.011361Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-15T12:37:53.016421Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.836977ms"} {"level":"info","ts":"2026-06-15T12:37:53.022908Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-06-15T12:37:53.023058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T12:37:53.023089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-06-15T12:37:53.023194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T12:37:53.023279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-06-15T12:37:53.023356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-06-15T12:37:53.030084Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T12:37:53.031433Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T12:37:53.032702Z","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-15T12:37:53.034719Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T12:37:53.034969Z","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-15T12:37:53.035093Z","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-15T12:37:53.035046Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T12:37:53.035174Z","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-15T12:37:53.035622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-06-15T12:37:53.040197Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-15T12:37:53.040886Z","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-15T12:37:53.040994Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-15T12:37:53.041027Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-15T12:37:53.041234Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T12:37:53.041262Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T12:37:53.924425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T12:37:53.924568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T12:37:53.924708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-06-15T12:37:53.924771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-06-15T12:37:53.92481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-15T12:37:53.924893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-06-15T12:37:53.924937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-15T12:37:53.926229Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T12:37:53.926351Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T12:37:53.92658Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T12:37:53.926614Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T12:37:53.926275Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T12:37:53.926486Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T12:37:53.928063Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T12:37:53.928188Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T12:37:53.928225Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T12:37:53.930235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T12:37:53.936955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"warn","ts":"2026-06-15T12:37:56.567682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.21532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-15T12:37:56.567815Z","caller":"traceutil/trace.go:171","msg":"trace[1100382577] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"359.440016ms","start":"2026-06-15T12:37:56.208358Z","end":"2026-06-15T12:37:56.567798Z","steps":["trace[1100382577] 'process raft request' (duration: 176.552176ms)","trace[1100382577] 'compare' (duration: 182.048654ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:37:56.567933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:37:56.208338Z","time spent":"359.522029ms","remote":"127.0.0.1:46320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T12:37:57.174556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.506337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:4"} {"level":"info","ts":"2026-06-15T12:37:57.174799Z","caller":"traceutil/trace.go:171","msg":"trace[1497550896] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:84; }","duration":"603.656162ms","start":"2026-06-15T12:37:56.571127Z","end":"2026-06-15T12:37:57.174783Z","steps":["trace[1497550896] 'read index received' (duration: 127.700336ms)","trace[1497550896] 'applied index is now lower than readState.Index' (duration: 475.954856ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:37:57.174824Z","caller":"traceutil/trace.go:171","msg":"trace[1155768636] transaction","detail":"{read_only:false; number_of_response:0; response_revision:80; }","duration":"940.038763ms","start":"2026-06-15T12:37:56.234744Z","end":"2026-06-15T12:37:57.174783Z","steps":["trace[1155768636] 'process raft request' (duration: 464.235714ms)","trace[1155768636] 'compare' (duration: 475.446054ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:37:57.174875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.747156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-15T12:37:57.175011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:37:56.234706Z","time spent":"940.231171ms","remote":"127.0.0.1:46154","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T12:37:57.17505Z","caller":"traceutil/trace.go:171","msg":"trace[1157230562] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:80; }","duration":"603.928384ms","start":"2026-06-15T12:37:56.57111Z","end":"2026-06-15T12:37:57.175038Z","steps":["trace[1157230562] 'agreement among raft nodes before linearized reading' (duration: 603.726965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:37:57.174939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:37:56.254701Z","time spent":"920.186826ms","remote":"127.0.0.1:46034","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-15T12:37:57.175093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:37:56.571051Z","time spent":"604.032379ms","remote":"127.0.0.1:46320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"info","ts":"2026-06-15T12:38:33.478197Z","caller":"traceutil/trace.go:171","msg":"trace[866691409] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"112.473896ms","start":"2026-06-15T12:38:33.365694Z","end":"2026-06-15T12:38:33.478167Z","steps":["trace[866691409] 'process raft request' (duration: 112.323749ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:33.484131Z","caller":"traceutil/trace.go:171","msg":"trace[695815079] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"118.10332ms","start":"2026-06-15T12:38:33.366007Z","end":"2026-06-15T12:38:33.484111Z","steps":["trace[695815079] 'process raft request' (duration: 117.545945ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:34.208188Z","caller":"traceutil/trace.go:171","msg":"trace[1609247237] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"112.547322ms","start":"2026-06-15T12:38:34.095591Z","end":"2026-06-15T12:38:34.208138Z","steps":["trace[1609247237] 'process raft request' (duration: 78.503025ms)","trace[1609247237] 'compare' (duration: 33.941373ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:38:34.893692Z","caller":"traceutil/trace.go:171","msg":"trace[92956250] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"105.592759ms","start":"2026-06-15T12:38:34.788079Z","end":"2026-06-15T12:38:34.893672Z","steps":["trace[92956250] 'read index received' (duration: 40.630725ms)","trace[92956250] 'applied index is now lower than readState.Index' (duration: 64.961064ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:38:34.894091Z","caller":"traceutil/trace.go:171","msg":"trace[1242194819] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"118.148275ms","start":"2026-06-15T12:38:34.775933Z","end":"2026-06-15T12:38:34.894081Z","steps":["trace[1242194819] 'process raft request' (duration: 52.850557ms)","trace[1242194819] 'compare' (duration: 64.775715ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:38:34.894935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.837115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-06-15T12:38:34.894976Z","caller":"traceutil/trace.go:171","msg":"trace[1139622912] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"106.920608ms","start":"2026-06-15T12:38:34.788045Z","end":"2026-06-15T12:38:34.894965Z","steps":["trace[1139622912] 'agreement among raft nodes before linearized reading' (duration: 106.274699ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:38:35.110993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.85993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-06-15T12:38:35.111063Z","caller":"traceutil/trace.go:171","msg":"trace[1011869595] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"112.966965ms","start":"2026-06-15T12:38:34.99808Z","end":"2026-06-15T12:38:35.111047Z","steps":["trace[1011869595] 'range keys from in-memory index tree' (duration: 112.712553ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:35.689491Z","caller":"traceutil/trace.go:171","msg":"trace[704296995] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"154.221398ms","start":"2026-06-15T12:38:35.535223Z","end":"2026-06-15T12:38:35.689444Z","steps":["trace[704296995] 'process raft request' (duration: 153.706995ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:39.952408Z","caller":"traceutil/trace.go:171","msg":"trace[823385657] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"224.623341ms","start":"2026-06-15T12:38:39.727752Z","end":"2026-06-15T12:38:39.952375Z","steps":["trace[823385657] 'read index received' (duration: 223.330952ms)","trace[823385657] 'applied index is now lower than readState.Index' (duration: 1.290999ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:38:39.952541Z","caller":"traceutil/trace.go:171","msg":"trace[890031030] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"229.160756ms","start":"2026-06-15T12:38:39.723332Z","end":"2026-06-15T12:38:39.952492Z","steps":["trace[890031030] 'process raft request' (duration: 228.033475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:38:39.952703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.912234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-15T12:38:39.952783Z","caller":"traceutil/trace.go:171","msg":"trace[1771030565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"225.046411ms","start":"2026-06-15T12:38:39.727721Z","end":"2026-06-15T12:38:39.952768Z","steps":["trace[1771030565] 'agreement among raft nodes before linearized reading' (duration: 224.861492ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:39.982991Z","caller":"traceutil/trace.go:171","msg":"trace[848539620] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"250.153727ms","start":"2026-06-15T12:38:39.73278Z","end":"2026-06-15T12:38:39.982934Z","steps":["trace[848539620] 'process raft request' (duration: 250.044342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:38:39.983693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.944647ms","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-06-15T12:38:39.983724Z","caller":"traceutil/trace.go:171","msg":"trace[674780716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"140.040701ms","start":"2026-06-15T12:38:39.843675Z","end":"2026-06-15T12:38:39.983716Z","steps":["trace[674780716] 'agreement among raft nodes before linearized reading' (duration: 139.970458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:38:39.984069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.503804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s65ld\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-15T12:38:39.984125Z","caller":"traceutil/trace.go:171","msg":"trace[71835431] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s65ld; range_end:; response_count:1; response_revision:626; }","duration":"108.611498ms","start":"2026-06-15T12:38:39.875506Z","end":"2026-06-15T12:38:39.984118Z","steps":["trace[71835431] 'agreement among raft nodes before linearized reading' (duration: 108.369447ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:40.235911Z","caller":"traceutil/trace.go:171","msg":"trace[490848271] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"247.870799ms","start":"2026-06-15T12:38:39.988008Z","end":"2026-06-15T12:38:40.235879Z","steps":["trace[490848271] 'process raft request' (duration: 247.543085ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:40.235906Z","caller":"traceutil/trace.go:171","msg":"trace[832974431] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"279.03565ms","start":"2026-06-15T12:38:39.956818Z","end":"2026-06-15T12:38:40.235853Z","steps":["trace[832974431] 'process raft request' (duration: 197.227594ms)","trace[832974431] 'compare' (duration: 80.953248ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:38:40.236563Z","caller":"traceutil/trace.go:171","msg":"trace[1161372644] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"241.021139ms","start":"2026-06-15T12:38:39.995527Z","end":"2026-06-15T12:38:40.236548Z","steps":["trace[1161372644] 'process raft request' (duration: 240.147679ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:40.237458Z","caller":"traceutil/trace.go:171","msg":"trace[1104267377] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"223.709124ms","start":"2026-06-15T12:38:40.013554Z","end":"2026-06-15T12:38:40.237263Z","steps":["trace[1104267377] 'process raft request' (duration: 222.237347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:38:40.788095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.927415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T12:38:40.78821Z","caller":"traceutil/trace.go:171","msg":"trace[592598012] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"517.393079ms","start":"2026-06-15T12:38:40.270798Z","end":"2026-06-15T12:38:40.788191Z","steps":["trace[592598012] 'process raft request' (duration: 374.123268ms)","trace[592598012] 'compare' (duration: 142.690404ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:38:40.788273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:38:40.270784Z","time spent":"517.458412ms","remote":"127.0.0.1:46258","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-15T12:38:40.88768Z","caller":"traceutil/trace.go:171","msg":"trace[115632126] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"241.48296ms","start":"2026-06-15T12:38:40.646173Z","end":"2026-06-15T12:38:40.887656Z","steps":["trace[115632126] 'read index received' (duration: 241.011519ms)","trace[115632126] 'applied index is now lower than readState.Index' (duration: 470.411µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:38:40.887726Z","caller":"traceutil/trace.go:171","msg":"trace[362683117] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"241.719341ms","start":"2026-06-15T12:38:40.64597Z","end":"2026-06-15T12:38:40.887689Z","steps":["trace[362683117] 'process raft request' (duration: 241.281311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:38:40.892889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.692466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:38:40.89297Z","caller":"traceutil/trace.go:171","msg":"trace[1919260042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"246.816742ms","start":"2026-06-15T12:38:40.646133Z","end":"2026-06-15T12:38:40.89295Z","steps":["trace[1919260042] 'agreement among raft nodes before linearized reading' (duration: 241.599215ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:38:40.893213Z","caller":"traceutil/trace.go:171","msg":"trace[1138357981] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"131.245557ms","start":"2026-06-15T12:38:40.761938Z","end":"2026-06-15T12:38:40.893184Z","steps":["trace[1138357981] 'process raft request' (duration: 127.429874ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:39:26.859523Z","caller":"traceutil/trace.go:171","msg":"trace[835241235] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"368.795487ms","start":"2026-06-15T12:39:26.490674Z","end":"2026-06-15T12:39:26.85947Z","steps":["trace[835241235] 'read index received' (duration: 90.901086ms)","trace[835241235] 'applied index is now lower than readState.Index' (duration: 277.890391ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:39:26.859677Z","caller":"traceutil/trace.go:171","msg":"trace[1423060375] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"206.453856ms","start":"2026-06-15T12:39:26.6532Z","end":"2026-06-15T12:39:26.859654Z","steps":["trace[1423060375] 'process raft request' (duration: 206.204513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:39:26.859897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.200122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-15T12:39:26.859682Z","caller":"traceutil/trace.go:171","msg":"trace[2141056960] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"373.74924ms","start":"2026-06-15T12:39:26.485914Z","end":"2026-06-15T12:39:26.859664Z","steps":["trace[2141056960] 'process raft request' (duration: 322.775207ms)","trace[2141056960] 'compare' (duration: 50.570478ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:39:26.859969Z","caller":"traceutil/trace.go:171","msg":"trace[1135622201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"369.301903ms","start":"2026-06-15T12:39:26.49065Z","end":"2026-06-15T12:39:26.859952Z","steps":["trace[1135622201] 'agreement among raft nodes before linearized reading' (duration: 369.07501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:39:26.860026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:39:26.490639Z","time spent":"369.372614ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T12:39:26.860059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:39:26.485903Z","time spent":"374.077244ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:39:26.860213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.286123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:39:26.860272Z","caller":"traceutil/trace.go:171","msg":"trace[227853313] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1206; }","duration":"316.386245ms","start":"2026-06-15T12:39:26.543867Z","end":"2026-06-15T12:39:26.860254Z","steps":["trace[227853313] 'agreement among raft nodes before linearized reading' (duration: 316.302504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:39:26.860322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:39:26.543844Z","time spent":"316.464065ms","remote":"127.0.0.1:46386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-06-15T12:39:26.860501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.358196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:39:26.860543Z","caller":"traceutil/trace.go:171","msg":"trace[1806091403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1206; }","duration":"217.437396ms","start":"2026-06-15T12:39:26.643094Z","end":"2026-06-15T12:39:26.860531Z","steps":["trace[1806091403] 'agreement among raft nodes before linearized reading' (duration: 217.378326ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:39:53.985486Z","caller":"traceutil/trace.go:171","msg":"trace[1592576145] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"111.863508ms","start":"2026-06-15T12:39:53.873587Z","end":"2026-06-15T12:39:53.98545Z","steps":["trace[1592576145] 'process raft request' (duration: 111.672334ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:39:54.293782Z","caller":"traceutil/trace.go:171","msg":"trace[321752275] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"101.21457ms","start":"2026-06-15T12:39:54.192548Z","end":"2026-06-15T12:39:54.293763Z","steps":["trace[321752275] 'process raft request' (duration: 101.113118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:40:16.549607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.10093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e842e5b1-7212-404b-90a9-9a08f5bca3c6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-15T12:40:16.549699Z","caller":"traceutil/trace.go:171","msg":"trace[1665079076] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e842e5b1-7212-404b-90a9-9a08f5bca3c6; range_end:; response_count:1; response_revision:1886; }","duration":"153.238414ms","start":"2026-06-15T12:40:16.39644Z","end":"2026-06-15T12:40:16.549679Z","steps":["trace[1665079076] 'range keys from in-memory index tree' (duration: 152.868494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:40:16.550325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.583987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-15T12:40:16.550445Z","caller":"traceutil/trace.go:171","msg":"trace[1901514936] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1886; }","duration":"308.741731ms","start":"2026-06-15T12:40:16.241672Z","end":"2026-06-15T12:40:16.550414Z","steps":["trace[1901514936] 'range keys from in-memory index tree' (duration: 308.354091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:40:16.550506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:40:16.241655Z","time spent":"308.833744ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-15T12:40:16.721352Z","caller":"traceutil/trace.go:171","msg":"trace[1484065276] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"163.466596ms","start":"2026-06-15T12:40:16.55786Z","end":"2026-06-15T12:40:16.721326Z","steps":["trace[1484065276] 'process raft request' (duration: 163.299612ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:40:16.721341Z","caller":"traceutil/trace.go:171","msg":"trace[303419770] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1957; }","duration":"129.42178ms","start":"2026-06-15T12:40:16.59188Z","end":"2026-06-15T12:40:16.721301Z","steps":["trace[303419770] 'read index received' (duration: 129.153242ms)","trace[303419770] 'applied index is now lower than readState.Index' (duration: 267.628µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:40:16.721582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.690987ms","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-15T12:40:16.721656Z","caller":"traceutil/trace.go:171","msg":"trace[1718912518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1887; }","duration":"129.79639ms","start":"2026-06-15T12:40:16.591825Z","end":"2026-06-15T12:40:16.721621Z","steps":["trace[1718912518] 'agreement among raft nodes before linearized reading' (duration: 129.567374ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:40:17.112941Z","caller":"traceutil/trace.go:171","msg":"trace[20663844] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"232.595258ms","start":"2026-06-15T12:40:16.88031Z","end":"2026-06-15T12:40:17.112906Z","steps":["trace[20663844] 'read index received' (duration: 227.918348ms)","trace[20663844] 'applied index is now lower than readState.Index' (duration: 4.6753ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:40:17.113003Z","caller":"traceutil/trace.go:171","msg":"trace[755992249] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"385.396961ms","start":"2026-06-15T12:40:16.727579Z","end":"2026-06-15T12:40:17.112976Z","steps":["trace[755992249] 'process raft request' (duration: 380.65883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:40:17.113176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:40:16.727538Z","time spent":"385.547275ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:40:17.113243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.917587ms","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-06-15T12:40:17.113289Z","caller":"traceutil/trace.go:171","msg":"trace[2145417794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1888; }","duration":"233.03633ms","start":"2026-06-15T12:40:16.88024Z","end":"2026-06-15T12:40:17.113277Z","steps":["trace[2145417794] 'agreement among raft nodes before linearized reading' (duration: 232.821344ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:40:45.498882Z","caller":"traceutil/trace.go:171","msg":"trace[1744571182] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2179; }","duration":"101.669763ms","start":"2026-06-15T12:40:45.397174Z","end":"2026-06-15T12:40:45.498843Z","steps":["trace[1744571182] 'read index received' (duration: 101.461346ms)","trace[1744571182] 'applied index is now lower than readState.Index' (duration: 206.997µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:40:45.498943Z","caller":"traceutil/trace.go:171","msg":"trace[307665225] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"117.624774ms","start":"2026-06-15T12:40:45.381289Z","end":"2026-06-15T12:40:45.498913Z","steps":["trace[307665225] 'process raft request' (duration: 117.334235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:40:45.499202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.011805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-15T12:40:45.499356Z","caller":"traceutil/trace.go:171","msg":"trace[1653135751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2102; }","duration":"102.128798ms","start":"2026-06-15T12:40:45.397123Z","end":"2026-06-15T12:40:45.499252Z","steps":["trace[1653135751] 'agreement among raft nodes before linearized reading' (duration: 101.902441ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:40:46.082715Z","caller":"traceutil/trace.go:171","msg":"trace[184171705] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"117.149758ms","start":"2026-06-15T12:40:45.965538Z","end":"2026-06-15T12:40:46.082688Z","steps":["trace[184171705] 'process raft request' (duration: 116.990693ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:42:32.241361Z","caller":"traceutil/trace.go:171","msg":"trace[1289688170] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2884; }","duration":"152.865238ms","start":"2026-06-15T12:42:32.088472Z","end":"2026-06-15T12:42:32.241338Z","steps":["trace[1289688170] 'read index received' (duration: 143.271321ms)","trace[1289688170] 'applied index is now lower than readState.Index' (duration: 9.592617ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:42:32.241478Z","caller":"traceutil/trace.go:171","msg":"trace[2113656547] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"278.567824ms","start":"2026-06-15T12:42:31.9629Z","end":"2026-06-15T12:42:32.241468Z","steps":["trace[2113656547] 'process raft request' (duration: 268.912784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:42:32.241636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.157999ms","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-15T12:42:32.241701Z","caller":"traceutil/trace.go:171","msg":"trace[900491137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2783; }","duration":"153.231622ms","start":"2026-06-15T12:42:32.088453Z","end":"2026-06-15T12:42:32.241685Z","steps":["trace[900491137] 'agreement among raft nodes before linearized reading' (duration: 153.054405ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:42:32.246377Z","caller":"traceutil/trace.go:171","msg":"trace[434662934] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"100.622166ms","start":"2026-06-15T12:42:32.145739Z","end":"2026-06-15T12:42:32.246362Z","steps":["trace[434662934] 'process raft request' (duration: 100.46283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:42:32.246451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.228047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-15T12:42:32.246519Z","caller":"traceutil/trace.go:171","msg":"trace[1804176531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2784; }","duration":"150.358592ms","start":"2026-06-15T12:42:32.096143Z","end":"2026-06-15T12:42:32.246501Z","steps":["trace[1804176531] 'agreement among raft nodes before linearized reading' (duration: 150.171255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:42:32.24657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.650598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-15T12:42:32.246604Z","caller":"traceutil/trace.go:171","msg":"trace[1264939401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2784; }","duration":"147.713771ms","start":"2026-06-15T12:42:32.098881Z","end":"2026-06-15T12:42:32.246594Z","steps":["trace[1264939401] 'agreement among raft nodes before linearized reading' (duration: 147.583396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:42:32.246914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.526708ms","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-06-15T12:42:32.246987Z","caller":"traceutil/trace.go:171","msg":"trace[119755262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2784; }","duration":"150.749267ms","start":"2026-06-15T12:42:32.096224Z","end":"2026-06-15T12:42:32.246973Z","steps":["trace[119755262] 'agreement among raft nodes before linearized reading' (duration: 150.385683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:46:04.367375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.599347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T12:46:04.36745Z","caller":"traceutil/trace.go:171","msg":"trace[1501233232] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:4445; }","duration":"110.732931ms","start":"2026-06-15T12:46:04.256702Z","end":"2026-06-15T12:46:04.367435Z","steps":["trace[1501233232] 'count revisions from in-memory index tree' (duration: 110.511395ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:46:05.482894Z","caller":"traceutil/trace.go:171","msg":"trace[1289006443] transaction","detail":"{read_only:false; response_revision:4491; number_of_response:1; }","duration":"106.916268ms","start":"2026-06-15T12:46:05.375936Z","end":"2026-06-15T12:46:05.482852Z","steps":["trace[1289006443] 'process raft request' (duration: 65.620495ms)","trace[1289006443] 'compare' (duration: 41.067007ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:46:05.483623Z","caller":"traceutil/trace.go:171","msg":"trace[1893168974] transaction","detail":"{read_only:false; response_revision:4492; number_of_response:1; }","duration":"106.755053ms","start":"2026-06-15T12:46:05.376842Z","end":"2026-06-15T12:46:05.483597Z","steps":["trace[1893168974] 'process raft request' (duration: 106.452275ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:46:05.483978Z","caller":"traceutil/trace.go:171","msg":"trace[563782041] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"103.684202ms","start":"2026-06-15T12:46:05.380272Z","end":"2026-06-15T12:46:05.483956Z","steps":["trace[563782041] 'process raft request' (duration: 103.160758ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:46:11.642324Z","caller":"traceutil/trace.go:171","msg":"trace[2078462881] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"105.647447ms","start":"2026-06-15T12:46:11.536642Z","end":"2026-06-15T12:46:11.64229Z","steps":["trace[2078462881] 'process raft request' (duration: 34.786974ms)","trace[2078462881] 'compare' (duration: 70.01495ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:46:16.913032Z","caller":"traceutil/trace.go:171","msg":"trace[1197469674] linearizableReadLoop","detail":"{readStateIndex:4920; appliedIndex:4919; }","duration":"270.160381ms","start":"2026-06-15T12:46:16.642845Z","end":"2026-06-15T12:46:16.913005Z","steps":["trace[1197469674] 'read index received' (duration: 261.522136ms)","trace[1197469674] 'applied index is now lower than readState.Index' (duration: 8.636565ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:46:16.913228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.354786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:46:16.913263Z","caller":"traceutil/trace.go:171","msg":"trace[116466715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4739; }","duration":"270.416008ms","start":"2026-06-15T12:46:16.642839Z","end":"2026-06-15T12:46:16.913255Z","steps":["trace[116466715] 'agreement among raft nodes before linearized reading' (duration: 270.267664ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:46:16.913588Z","caller":"traceutil/trace.go:171","msg":"trace[1836336932] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"323.496819ms","start":"2026-06-15T12:46:16.590082Z","end":"2026-06-15T12:46:16.913579Z","steps":["trace[1836336932] 'process raft request' (duration: 314.268619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:46:16.913688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:46:16.590042Z","time spent":"323.572291ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T12:46:17.248642Z","caller":"traceutil/trace.go:171","msg":"trace[2131336394] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"121.15518ms","start":"2026-06-15T12:46:17.127405Z","end":"2026-06-15T12:46:17.24856Z","steps":["trace[2131336394] 'process raft request' (duration: 91.938984ms)","trace[2131336394] 'compare' (duration: 28.810775ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:46:18.418875Z","caller":"traceutil/trace.go:171","msg":"trace[183106039] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"166.220567ms","start":"2026-06-15T12:46:18.252613Z","end":"2026-06-15T12:46:18.418833Z","steps":["trace[183106039] 'process raft request' (duration: 151.190157ms)","trace[183106039] 'compare' (duration: 14.701891ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:46:20.135525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.53244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9fd93d4a-4761-4f91-9cbd-ba5936ae47e0\" ","response":"range_response_count:1 size:3954"} {"level":"info","ts":"2026-06-15T12:46:20.135638Z","caller":"traceutil/trace.go:171","msg":"trace[1693372369] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9fd93d4a-4761-4f91-9cbd-ba5936ae47e0; range_end:; response_count:1; response_revision:4784; }","duration":"118.681494ms","start":"2026-06-15T12:46:20.016929Z","end":"2026-06-15T12:46:20.135611Z","steps":["trace[1693372369] 'range keys from in-memory index tree' (duration: 118.339364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:46:30.988053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.630942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T12:46:30.988158Z","caller":"traceutil/trace.go:171","msg":"trace[1421299140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4911; }","duration":"344.819547ms","start":"2026-06-15T12:46:30.643317Z","end":"2026-06-15T12:46:30.988137Z","steps":["trace[1421299140] 'range keys from in-memory index tree' (duration: 344.494588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:46:30.988208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:46:30.643291Z","time spent":"344.907729ms","remote":"127.0.0.1:45934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T12:46:31.024165Z","caller":"traceutil/trace.go:171","msg":"trace[288212764] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"263.10834ms","start":"2026-06-15T12:46:30.761031Z","end":"2026-06-15T12:46:31.024139Z","steps":["trace[288212764] 'process raft request' (duration: 262.932595ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:46:31.024451Z","caller":"traceutil/trace.go:171","msg":"trace[834453106] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5100; }","duration":"198.195732ms","start":"2026-06-15T12:46:30.826222Z","end":"2026-06-15T12:46:31.024418Z","steps":["trace[834453106] 'read index received' (duration: 198.188202ms)","trace[834453106] 'applied index is now lower than readState.Index' (duration: 6.04µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T12:46:31.024942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.693406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-15T12:46:31.02513Z","caller":"traceutil/trace.go:171","msg":"trace[1369609833] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4912; }","duration":"198.940073ms","start":"2026-06-15T12:46:30.826167Z","end":"2026-06-15T12:46:31.025107Z","steps":["trace[1369609833] 'agreement among raft nodes before linearized reading' (duration: 198.384058ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:46:31.404226Z","caller":"traceutil/trace.go:171","msg":"trace[2114787865] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"109.152403ms","start":"2026-06-15T12:46:31.295044Z","end":"2026-06-15T12:46:31.404197Z","steps":["trace[2114787865] 'process raft request' (duration: 59.308504ms)","trace[2114787865] 'compare' (duration: 49.417267ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:46:31.599165Z","caller":"traceutil/trace.go:171","msg":"trace[2033442076] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"110.575394ms","start":"2026-06-15T12:46:31.488556Z","end":"2026-06-15T12:46:31.599132Z","steps":["trace[2033442076] 'process raft request' (duration: 47.413661ms)","trace[2033442076] 'compare' (duration: 62.830403ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:47:30.497775Z","caller":"traceutil/trace.go:171","msg":"trace[221273347] linearizableReadLoop","detail":"{readStateIndex:5599; appliedIndex:5598; }","duration":"335.979306ms","start":"2026-06-15T12:47:30.16177Z","end":"2026-06-15T12:47:30.49775Z","steps":["trace[221273347] 'read index received' (duration: 335.750239ms)","trace[221273347] 'applied index is now lower than readState.Index' (duration: 227.977µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:47:30.497893Z","caller":"traceutil/trace.go:171","msg":"trace[406546050] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"364.478801ms","start":"2026-06-15T12:47:30.133388Z","end":"2026-06-15T12:47:30.497867Z","steps":["trace[406546050] 'process raft request' (duration: 364.201242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:47:30.498028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:47:30.133374Z","time spent":"364.581055ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T12:47:30.498054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.268405ms","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-06-15T12:47:30.498094Z","caller":"traceutil/trace.go:171","msg":"trace[1409585691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5397; }","duration":"336.365097ms","start":"2026-06-15T12:47:30.161717Z","end":"2026-06-15T12:47:30.498082Z","steps":["trace[1409585691] 'agreement among raft nodes before linearized reading' (duration: 336.215933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:47:30.498139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:47:30.161696Z","time spent":"336.43309ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T12:47:30.498309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.39927ms","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-15T12:47:30.498342Z","caller":"traceutil/trace.go:171","msg":"trace[371476561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5397; }","duration":"239.478642ms","start":"2026-06-15T12:47:30.258855Z","end":"2026-06-15T12:47:30.498333Z","steps":["trace[371476561] 'agreement among raft nodes before linearized reading' (duration: 239.390149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:47:30.498438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.513214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-15T12:47:30.498611Z","caller":"traceutil/trace.go:171","msg":"trace[336893936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5397; }","duration":"239.700889ms","start":"2026-06-15T12:47:30.258855Z","end":"2026-06-15T12:47:30.498556Z","steps":["trace[336893936] 'agreement among raft nodes before linearized reading' (duration: 239.308577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:47:30.498447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.532955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-15T12:47:30.498724Z","caller":"traceutil/trace.go:171","msg":"trace[182486380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5397; }","duration":"325.866846ms","start":"2026-06-15T12:47:30.172811Z","end":"2026-06-15T12:47:30.498678Z","steps":["trace[182486380] 'agreement among raft nodes before linearized reading' (duration: 325.301818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T12:47:30.498823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T12:47:30.172785Z","time spent":"326.010029ms","remote":"127.0.0.1:46258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-15T12:47:30.629516Z","caller":"traceutil/trace.go:171","msg":"trace[989483505] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"126.728301ms","start":"2026-06-15T12:47:30.502765Z","end":"2026-06-15T12:47:30.629493Z","steps":["trace[989483505] 'process raft request' (duration: 126.569546ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:47:30.629648Z","caller":"traceutil/trace.go:171","msg":"trace[1448206731] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"127.32912ms","start":"2026-06-15T12:47:30.502283Z","end":"2026-06-15T12:47:30.629612Z","steps":["trace[1448206731] 'process raft request' (duration: 122.44105ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:47:30.629788Z","caller":"traceutil/trace.go:171","msg":"trace[536165867] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"126.538366ms","start":"2026-06-15T12:47:30.503209Z","end":"2026-06-15T12:47:30.629747Z","steps":["trace[536165867] 'process raft request' (duration: 126.250187ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:47:30.629776Z","caller":"traceutil/trace.go:171","msg":"trace[307387706] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"125.868126ms","start":"2026-06-15T12:47:30.503897Z","end":"2026-06-15T12:47:30.629765Z","steps":["trace[307387706] 'process raft request' (duration: 125.666139ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:47:53.958446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2900} {"level":"info","ts":"2026-06-15T12:47:54.033931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2900,"took":"73.851283ms","hash":2015088172,"current-db-size-bytes":53207040,"current-db-size":"53 MB","current-db-size-in-use-bytes":29052928,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-15T12:47:54.034001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2015088172,"revision":2900,"compact-revision":-1} {"level":"info","ts":"2026-06-15T12:50:18.053566Z","caller":"traceutil/trace.go:171","msg":"trace[1948403380] transaction","detail":"{read_only:false; response_revision:6557; number_of_response:1; }","duration":"126.202121ms","start":"2026-06-15T12:50:17.927326Z","end":"2026-06-15T12:50:18.053528Z","steps":["trace[1948403380] 'process raft request' (duration: 100.273729ms)","trace[1948403380] 'compare' (duration: 25.674373ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T12:50:18.508525Z","caller":"traceutil/trace.go:171","msg":"trace[1303737519] transaction","detail":"{read_only:false; response_revision:6560; number_of_response:1; }","duration":"111.494101ms","start":"2026-06-15T12:50:18.397002Z","end":"2026-06-15T12:50:18.508496Z","steps":["trace[1303737519] 'process raft request' (duration: 111.296114ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:50:18.513442Z","caller":"traceutil/trace.go:171","msg":"trace[2127419655] transaction","detail":"{read_only:false; response_revision:6561; number_of_response:1; }","duration":"116.154639ms","start":"2026-06-15T12:50:18.397263Z","end":"2026-06-15T12:50:18.513418Z","steps":["trace[2127419655] 'process raft request' (duration: 116.008734ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T12:52:53.963752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5578} {"level":"info","ts":"2026-06-15T12:52:54.065053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5578,"took":"99.871516ms","hash":103320642,"current-db-size-bytes":53207040,"current-db-size":"53 MB","current-db-size-in-use-bytes":18329600,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-15T12:52:54.065104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":103320642,"revision":5578,"compact-revision":2900}