{"level":"warn","ts":"2026-10-02T09:09:56.036961Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T09:09:56.037235Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-10-02T09:09:56.037327Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T09:09:56.037335Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-10-02T09:09:56.037417Z","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-10-02T09:09:56.038326Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-10-02T09:09:56.038458Z","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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-10-02T09:09:56.044491Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.395886ms"} {"level":"info","ts":"2026-10-02T09:09:56.050719Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-10-02T09:09:56.050805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T09:09:56.05083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-10-02T09:09:56.050849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T09:09:56.050864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-10-02T09:09:56.050914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-10-02T09:09:56.056174Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T09:09:56.057492Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T09:09:56.058687Z","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-10-02T09:09:56.060682Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T09:09:56.062432Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T09:09:56.064047Z","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-10-02T09:09:56.064136Z","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-10-02T09:09:56.064452Z","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-10-02T09:09:56.064844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-10-02T09:09:56.064963Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-10-02T09:09:56.072459Z","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-10-02T09:09:56.072612Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-10-02T09:09:56.072648Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-10-02T09:09:56.072878Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T09:09:56.072995Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T09:09:56.552224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T09:09:56.552299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T09:09:56.552345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-10-02T09:09:56.552369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-10-02T09:09:56.552381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-10-02T09:09:56.552397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-10-02T09:09:56.552451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-10-02T09:09:56.553944Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:09:56.554377Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T09:09:56.554422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:09:56.554487Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:09:56.554653Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T09:09:56.554712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T09:09:56.555035Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:09:56.555221Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:09:56.555306Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:09:56.556745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-10-02T09:09:56.560134Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T09:09:58.212059Z","caller":"traceutil/trace.go:171","msg":"trace[202214410] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"100.633321ms","start":"2026-10-02T09:09:58.111346Z","end":"2026-10-02T09:09:58.211979Z","steps":["trace[202214410] 'process raft request' (duration: 86.268834ms)","trace[202214410] 'compare' (duration: 14.125415ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:09:58.212258Z","caller":"traceutil/trace.go:171","msg":"trace[52744093] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:10; }","duration":"100.239321ms","start":"2026-10-02T09:09:58.111986Z","end":"2026-10-02T09:09:58.212226Z","steps":["trace[52744093] 'read index received' (duration: 30.800747ms)","trace[52744093] 'applied index is now lower than readState.Index' (duration: 69.437684ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:09:58.212579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.492293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-02T09:09:58.212669Z","caller":"traceutil/trace.go:171","msg":"trace[1393607460] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:15; }","duration":"100.695563ms","start":"2026-10-02T09:09:58.111954Z","end":"2026-10-02T09:09:58.212649Z","steps":["trace[1393607460] 'agreement among raft nodes before linearized reading' (duration: 100.387358ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:04.068366Z","caller":"traceutil/trace.go:171","msg":"trace[1392891042] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"157.684617ms","start":"2026-10-02T09:10:03.910638Z","end":"2026-10-02T09:10:04.068322Z","steps":["trace[1392891042] 'process raft request' (duration: 157.515669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:04.574837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.311291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:10:04.574978Z","caller":"traceutil/trace.go:171","msg":"trace[101838869] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"490.381262ms","start":"2026-10-02T09:10:04.084575Z","end":"2026-10-02T09:10:04.574956Z","steps":["trace[101838869] 'read index received' (duration: 384.220937ms)","trace[101838869] 'applied index is now lower than readState.Index' (duration: 106.158805ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:10:04.575032Z","caller":"traceutil/trace.go:171","msg":"trace[1262629785] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"497.74108ms","start":"2026-10-02T09:10:04.077249Z","end":"2026-10-02T09:10:04.57499Z","steps":["trace[1262629785] 'process raft request' (duration: 391.628347ms)","trace[1262629785] 'compare' (duration: 105.106691ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:10:04.575176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.075305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-02T09:10:04.575203Z","caller":"traceutil/trace.go:171","msg":"trace[653288499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:322; }","duration":"304.145209ms","start":"2026-10-02T09:10:04.271049Z","end":"2026-10-02T09:10:04.575194Z","steps":["trace[653288499] 'agreement among raft nodes before linearized reading' (duration: 304.022013ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:04.575187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:04.077221Z","time spent":"497.888587ms","remote":"127.0.0.1:50206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:10:04.575245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:04.271027Z","time spent":"304.210222ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T09:10:04.575246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.645526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-02T09:10:04.575323Z","caller":"traceutil/trace.go:171","msg":"trace[1580082882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:322; }","duration":"490.809434ms","start":"2026-10-02T09:10:04.084493Z","end":"2026-10-02T09:10:04.575302Z","steps":["trace[1580082882] 'agreement among raft nodes before linearized reading' (duration: 490.527719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:04.575382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:04.084468Z","time spent":"490.90653ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-02T09:10:04.857113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.582282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:10:04.857287Z","caller":"traceutil/trace.go:171","msg":"trace[1405239799] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"276.809177ms","start":"2026-10-02T09:10:04.580453Z","end":"2026-10-02T09:10:04.857262Z","steps":["trace[1405239799] 'process raft request' (duration: 165.778473ms)","trace[1405239799] 'compare' (duration: 110.405482ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:10:04.85893Z","caller":"traceutil/trace.go:171","msg":"trace[852173904] linearizableReadLoop","detail":"{readStateIndex:332; appliedIndex:330; }","duration":"276.733585ms","start":"2026-10-02T09:10:04.582159Z","end":"2026-10-02T09:10:04.858892Z","steps":["trace[852173904] 'read index received' (duration: 164.054405ms)","trace[852173904] 'applied index is now lower than readState.Index' (duration: 112.67761ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:10:04.859275Z","caller":"traceutil/trace.go:171","msg":"trace[383287957] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"278.231821ms","start":"2026-10-02T09:10:04.581031Z","end":"2026-10-02T09:10:04.859263Z","steps":["trace[383287957] 'process raft request' (duration: 277.678973ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:04.859479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.314154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-10-02T09:10:04.859465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.574191ms","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-10-02T09:10:04.859696Z","caller":"traceutil/trace.go:171","msg":"trace[873994668] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:324; }","duration":"277.385208ms","start":"2026-10-02T09:10:04.582146Z","end":"2026-10-02T09:10:04.859531Z","steps":["trace[873994668] 'agreement among raft nodes before linearized reading' (duration: 277.164666ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:04.859744Z","caller":"traceutil/trace.go:171","msg":"trace[1513475203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:324; }","duration":"195.865646ms","start":"2026-10-02T09:10:04.663845Z","end":"2026-10-02T09:10:04.859711Z","steps":["trace[1513475203] 'agreement among raft nodes before linearized reading' (duration: 195.468935ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:34.713262Z","caller":"traceutil/trace.go:171","msg":"trace[1640740354] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"122.92978ms","start":"2026-10-02T09:10:34.590293Z","end":"2026-10-02T09:10:34.713223Z","steps":["trace[1640740354] 'process raft request' (duration: 91.033064ms)","trace[1640740354] 'compare' (duration: 31.409838ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:10:35.264661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.450198ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-10-02T09:10:35.264826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.286388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-02T09:10:35.264939Z","caller":"traceutil/trace.go:171","msg":"trace[1932428638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"160.441406ms","start":"2026-10-02T09:10:35.104472Z","end":"2026-10-02T09:10:35.264913Z","steps":["trace[1932428638] 'range keys from in-memory index tree' (duration: 146.943699ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:35.265459Z","caller":"traceutil/trace.go:171","msg":"trace[1241346545] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"148.091805ms","start":"2026-10-02T09:10:35.117353Z","end":"2026-10-02T09:10:35.265445Z","steps":["trace[1241346545] 'process raft request' (duration: 147.405616ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:35.26556Z","caller":"traceutil/trace.go:171","msg":"trace[1724294801] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"147.974258ms","start":"2026-10-02T09:10:35.117557Z","end":"2026-10-02T09:10:35.265531Z","steps":["trace[1724294801] 'read index received' (duration: 125.592308ms)","trace[1724294801] 'applied index is now lower than readState.Index' (duration: 22.38025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:10:35.269919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.202037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-02T09:10:35.26997Z","caller":"traceutil/trace.go:171","msg":"trace[1771063859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"165.278641ms","start":"2026-10-02T09:10:35.104677Z","end":"2026-10-02T09:10:35.269956Z","steps":["trace[1771063859] 'agreement among raft nodes before linearized reading' (duration: 160.932385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:35.270116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.813224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-02T09:10:35.270147Z","caller":"traceutil/trace.go:171","msg":"trace[388096423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"164.854966ms","start":"2026-10-02T09:10:35.105283Z","end":"2026-10-02T09:10:35.270138Z","steps":["trace[388096423] 'agreement among raft nodes before linearized reading' (duration: 160.270397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:35.270863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.826717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-10-02T09:10:35.270951Z","caller":"traceutil/trace.go:171","msg":"trace[755397808] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:632; }","duration":"151.959595ms","start":"2026-10-02T09:10:35.118971Z","end":"2026-10-02T09:10:35.27093Z","steps":["trace[755397808] 'agreement among raft nodes before linearized reading' (duration: 151.690629ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:37.794974Z","caller":"traceutil/trace.go:171","msg":"trace[1837870038] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"157.025714ms","start":"2026-10-02T09:10:37.63792Z","end":"2026-10-02T09:10:37.794946Z","steps":["trace[1837870038] 'process raft request' (duration: 150.355765ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:37.795087Z","caller":"traceutil/trace.go:171","msg":"trace[236241288] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"117.252276ms","start":"2026-10-02T09:10:37.677777Z","end":"2026-10-02T09:10:37.79503Z","steps":["trace[236241288] 'read index received' (duration: 109.976552ms)","trace[236241288] 'applied index is now lower than readState.Index' (duration: 7.272294ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:10:37.795331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.509922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:10:37.79539Z","caller":"traceutil/trace.go:171","msg":"trace[1772595232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:657; }","duration":"117.624727ms","start":"2026-10-02T09:10:37.677737Z","end":"2026-10-02T09:10:37.795362Z","steps":["trace[1772595232] 'agreement among raft nodes before linearized reading' (duration: 117.508361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:37.795499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.535492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:10:37.795639Z","caller":"traceutil/trace.go:171","msg":"trace[1162061007] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:657; }","duration":"117.810488ms","start":"2026-10-02T09:10:37.67775Z","end":"2026-10-02T09:10:37.795561Z","steps":["trace[1162061007] 'agreement among raft nodes before linearized reading' (duration: 117.524971ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:41.071353Z","caller":"traceutil/trace.go:171","msg":"trace[489079119] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"142.85895ms","start":"2026-10-02T09:10:40.928448Z","end":"2026-10-02T09:10:41.071307Z","steps":["trace[489079119] 'process raft request' (duration: 69.811388ms)","trace[489079119] 'compare' (duration: 72.823769ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:10:42.339814Z","caller":"traceutil/trace.go:171","msg":"trace[2090900160] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"313.830626ms","start":"2026-10-02T09:10:42.02596Z","end":"2026-10-02T09:10:42.339791Z","steps":["trace[2090900160] 'read index received' (duration: 313.674637ms)","trace[2090900160] 'applied index is now lower than readState.Index' (duration: 152.689µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:10:42.339966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.986965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:10:42.339919Z","caller":"traceutil/trace.go:171","msg":"trace[1156072005] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"435.366872ms","start":"2026-10-02T09:10:41.904521Z","end":"2026-10-02T09:10:42.339888Z","steps":["trace[1156072005] 'process raft request' (duration: 435.132198ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:10:42.34Z","caller":"traceutil/trace.go:171","msg":"trace[327808758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"314.077101ms","start":"2026-10-02T09:10:42.025911Z","end":"2026-10-02T09:10:42.339988Z","steps":["trace[327808758] 'agreement among raft nodes before linearized reading' (duration: 314.004316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:42.340029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:42.025887Z","time spent":"314.135084ms","remote":"127.0.0.1:50002","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-10-02T09:10:42.3401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:41.904488Z","time spent":"435.527961ms","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:10:42.340149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.173046ms","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-10-02T09:10:42.340216Z","caller":"traceutil/trace.go:171","msg":"trace[1902127866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:696; }","duration":"314.266991ms","start":"2026-10-02T09:10:42.025937Z","end":"2026-10-02T09:10:42.340204Z","steps":["trace[1902127866] 'agreement among raft nodes before linearized reading' (duration: 314.052999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:42.340274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:42.025923Z","time spent":"314.314954ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T09:10:58.889336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.21763ms","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-10-02T09:10:58.889436Z","caller":"traceutil/trace.go:171","msg":"trace[1772606615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:811; }","duration":"451.360799ms","start":"2026-10-02T09:10:58.438057Z","end":"2026-10-02T09:10:58.889418Z","steps":["trace[1772606615] 'range keys from in-memory index tree' (duration: 450.985666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:10:58.889474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:10:58.438039Z","time spent":"451.426033ms","remote":"127.0.0.1:50282","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-10-02T09:12:20.275521Z","caller":"traceutil/trace.go:171","msg":"trace[664594179] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"315.823923ms","start":"2026-10-02T09:12:19.959674Z","end":"2026-10-02T09:12:20.275498Z","steps":["trace[664594179] 'process raft request' (duration: 315.702096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:20.275889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:19.959651Z","time spent":"316.065318ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:12:20.335469Z","caller":"traceutil/trace.go:171","msg":"trace[1459806395] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1967; }","duration":"321.894954ms","start":"2026-10-02T09:12:20.013559Z","end":"2026-10-02T09:12:20.335454Z","steps":["trace[1459806395] 'read index received' (duration: 261.918457ms)","trace[1459806395] 'applied index is now lower than readState.Index' (duration: 59.975567ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:12:20.335564Z","caller":"traceutil/trace.go:171","msg":"trace[33267419] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"372.584793ms","start":"2026-10-02T09:12:19.962969Z","end":"2026-10-02T09:12:20.335553Z","steps":["trace[33267419] 'process raft request' (duration: 372.421416ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:20.335539Z","caller":"traceutil/trace.go:171","msg":"trace[1639753063] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"373.499686ms","start":"2026-10-02T09:12:19.961998Z","end":"2026-10-02T09:12:20.335498Z","steps":["trace[1639753063] 'process raft request' (duration: 373.308749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:20.335741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.200516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-02T09:12:20.335768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:19.961981Z","time spent":"373.644992ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:12:20.335875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.319216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-02T09:12:20.335975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.338209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1247afbf-95dd-4774-9d06-57f307b4ffa8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-02T09:12:20.336012Z","caller":"traceutil/trace.go:171","msg":"trace[417082172] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1247afbf-95dd-4774-9d06-57f307b4ffa8; range_end:; response_count:1; response_revision:1900; }","duration":"112.403667ms","start":"2026-10-02T09:12:20.223603Z","end":"2026-10-02T09:12:20.336006Z","steps":["trace[417082172] 'agreement among raft nodes before linearized reading' (duration: 112.332059ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:20.335979Z","caller":"traceutil/trace.go:171","msg":"trace[1895110989] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1900; }","duration":"322.433174ms","start":"2026-10-02T09:12:20.01351Z","end":"2026-10-02T09:12:20.335944Z","steps":["trace[1895110989] 'agreement among raft nodes before linearized reading' (duration: 322.171568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:20.336149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:20.013495Z","time spent":"322.61928ms","remote":"127.0.0.1:50036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-10-02T09:12:20.336343Z","caller":"traceutil/trace.go:171","msg":"trace[584508463] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"372.7053ms","start":"2026-10-02T09:12:19.963626Z","end":"2026-10-02T09:12:20.336332Z","steps":["trace[584508463] 'process raft request' (duration: 371.799438ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:20.335793Z","caller":"traceutil/trace.go:171","msg":"trace[532295791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1900; }","duration":"310.280915ms","start":"2026-10-02T09:12:20.025505Z","end":"2026-10-02T09:12:20.335786Z","steps":["trace[532295791] 'agreement among raft nodes before linearized reading' (duration: 310.212957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:20.336471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:19.963613Z","time spent":"372.75793ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:12:20.336517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:20.025493Z","time spent":"310.99298ms","remote":"127.0.0.1:50002","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-10-02T09:12:20.336812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:19.962957Z","time spent":"372.635292ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:12:20.754633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.247971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:12:20.7549Z","caller":"traceutil/trace.go:171","msg":"trace[753665571] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1973; }","duration":"346.478915ms","start":"2026-10-02T09:12:20.408405Z","end":"2026-10-02T09:12:20.754883Z","steps":["trace[753665571] 'read index received' (duration: 126.88234ms)","trace[753665571] 'applied index is now lower than readState.Index' (duration: 219.595205ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:12:20.755004Z","caller":"traceutil/trace.go:171","msg":"trace[607880816] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"358.409598ms","start":"2026-10-02T09:12:20.396584Z","end":"2026-10-02T09:12:20.754994Z","steps":["trace[607880816] 'process raft request' (duration: 358.222301ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:20.755041Z","caller":"traceutil/trace.go:171","msg":"trace[165375961] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"360.322021ms","start":"2026-10-02T09:12:20.3947Z","end":"2026-10-02T09:12:20.755022Z","steps":["trace[165375961] 'process raft request' (duration: 140.605719ms)","trace[165375961] 'compare' (duration: 219.131183ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:12:20.755147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:20.394685Z","time spent":"360.419899ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:12:20.755244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:20.396564Z","time spent":"358.514266ms","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:12:20.755401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.990365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-02T09:12:20.755681Z","caller":"traceutil/trace.go:171","msg":"trace[2048813438] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1903; }","duration":"347.284459ms","start":"2026-10-02T09:12:20.408382Z","end":"2026-10-02T09:12:20.755666Z","steps":["trace[2048813438] 'agreement among raft nodes before linearized reading' (duration: 346.976835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:20.755755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:12:20.408373Z","time spent":"347.370048ms","remote":"127.0.0.1:50184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-10-02T09:12:20.756295Z","caller":"traceutil/trace.go:171","msg":"trace[1795198534] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"232.309242ms","start":"2026-10-02T09:12:20.523967Z","end":"2026-10-02T09:12:20.756276Z","steps":["trace[1795198534] 'process raft request' (duration: 232.263963ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:20.756511Z","caller":"traceutil/trace.go:171","msg":"trace[1826753982] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"269.08444ms","start":"2026-10-02T09:12:20.487416Z","end":"2026-10-02T09:12:20.756501Z","steps":["trace[1826753982] 'process raft request' (duration: 268.670588ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:20.768347Z","caller":"traceutil/trace.go:171","msg":"trace[777295029] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"164.422735ms","start":"2026-10-02T09:12:20.603885Z","end":"2026-10-02T09:12:20.768308Z","steps":["trace[777295029] 'process raft request' (duration: 164.244968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:20.768793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.112988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:12:20.768828Z","caller":"traceutil/trace.go:171","msg":"trace[1608862941] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"187.84269ms","start":"2026-10-02T09:12:20.58097Z","end":"2026-10-02T09:12:20.768813Z","steps":["trace[1608862941] 'process raft request' (duration: 187.008315ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:20.768859Z","caller":"traceutil/trace.go:171","msg":"trace[1863298413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1907; }","duration":"116.223224ms","start":"2026-10-02T09:12:20.652618Z","end":"2026-10-02T09:12:20.768841Z","steps":["trace[1863298413] 'agreement among raft nodes before linearized reading' (duration: 116.122286ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:35.438753Z","caller":"traceutil/trace.go:171","msg":"trace[1314537775] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"148.698956ms","start":"2026-10-02T09:12:35.290025Z","end":"2026-10-02T09:12:35.438724Z","steps":["trace[1314537775] 'process raft request' (duration: 148.512628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:55.535688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.381618ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-02T09:12:56.63437Z","caller":"traceutil/trace.go:171","msg":"trace[556834612] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"123.04299ms","start":"2026-10-02T09:12:56.511301Z","end":"2026-10-02T09:12:56.634344Z","steps":["trace[556834612] 'read index received' (duration: 122.732371ms)","trace[556834612] 'applied index is now lower than readState.Index' (duration: 309.619µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:12:56.634423Z","caller":"traceutil/trace.go:171","msg":"trace[1195545974] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"126.783486ms","start":"2026-10-02T09:12:56.507617Z","end":"2026-10-02T09:12:56.634401Z","steps":["trace[1195545974] 'process raft request' (duration: 126.534526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:56.634737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.451396ms","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-10-02T09:12:56.634772Z","caller":"traceutil/trace.go:171","msg":"trace[1064442818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2159; }","duration":"115.521855ms","start":"2026-10-02T09:12:56.51924Z","end":"2026-10-02T09:12:56.634761Z","steps":["trace[1064442818] 'agreement among raft nodes before linearized reading' (duration: 115.447365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:56.634661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.34641ms","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-10-02T09:12:56.634943Z","caller":"traceutil/trace.go:171","msg":"trace[690348679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2159; }","duration":"123.63869ms","start":"2026-10-02T09:12:56.51124Z","end":"2026-10-02T09:12:56.634879Z","steps":["trace[690348679] 'agreement among raft nodes before linearized reading' (duration: 123.29107ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:56.920687Z","caller":"traceutil/trace.go:171","msg":"trace[1594574866] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"280.382434ms","start":"2026-10-02T09:12:56.64027Z","end":"2026-10-02T09:12:56.920653Z","steps":["trace[1594574866] 'process raft request' (duration: 280.324754ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:56.921195Z","caller":"traceutil/trace.go:171","msg":"trace[855722354] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"281.509863ms","start":"2026-10-02T09:12:56.639652Z","end":"2026-10-02T09:12:56.921162Z","steps":["trace[855722354] 'process raft request' (duration: 250.512095ms)","trace[855722354] 'compare' (duration: 30.233508ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:12:57.176062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.829739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:12:57.176332Z","caller":"traceutil/trace.go:171","msg":"trace[1308148571] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"229.650947ms","start":"2026-10-02T09:12:56.946649Z","end":"2026-10-02T09:12:57.1763Z","steps":["trace[1308148571] 'process raft request' (duration: 124.443908ms)","trace[1308148571] 'compare' (duration: 104.601589ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:12:57.176586Z","caller":"traceutil/trace.go:171","msg":"trace[1856705625] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"161.277494ms","start":"2026-10-02T09:12:57.015277Z","end":"2026-10-02T09:12:57.176554Z","steps":["trace[1856705625] 'process raft request' (duration: 160.967583ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:12:57.176608Z","caller":"traceutil/trace.go:171","msg":"trace[1999996391] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2241; }","duration":"121.194275ms","start":"2026-10-02T09:12:57.055391Z","end":"2026-10-02T09:12:57.176585Z","steps":["trace[1999996391] 'read index received' (duration: 15.619815ms)","trace[1999996391] 'applied index is now lower than readState.Index' (duration: 105.57316ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:12:57.176818Z","caller":"traceutil/trace.go:171","msg":"trace[535171304] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"158.842625ms","start":"2026-10-02T09:12:57.017964Z","end":"2026-10-02T09:12:57.176807Z","steps":["trace[535171304] 'process raft request' (duration: 158.502084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:12:57.17753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.113134ms","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-10-02T09:12:57.177586Z","caller":"traceutil/trace.go:171","msg":"trace[1853327784] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"122.233024ms","start":"2026-10-02T09:12:57.055337Z","end":"2026-10-02T09:12:57.17757Z","steps":["trace[1853327784] 'agreement among raft nodes before linearized reading' (duration: 121.348615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:40.37396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.612366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:14:40.374033Z","caller":"traceutil/trace.go:171","msg":"trace[301309734] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2824; }","duration":"147.73107ms","start":"2026-10-02T09:14:40.226286Z","end":"2026-10-02T09:14:40.374017Z","steps":["trace[301309734] 'count revisions from in-memory index tree' (duration: 147.498033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:40.374658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.339692ms","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-10-02T09:14:40.374846Z","caller":"traceutil/trace.go:171","msg":"trace[2072614910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2824; }","duration":"105.552199ms","start":"2026-10-02T09:14:40.269247Z","end":"2026-10-02T09:14:40.374799Z","steps":["trace[2072614910] 'range keys from in-memory index tree' (duration: 105.158437ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:12.919446Z","caller":"traceutil/trace.go:171","msg":"trace[1521457937] linearizableReadLoop","detail":"{readStateIndex:5221; appliedIndex:5218; }","duration":"293.644086ms","start":"2026-10-02T09:19:12.62577Z","end":"2026-10-02T09:19:12.919414Z","steps":["trace[1521457937] 'read index received' (duration: 8.866975ms)","trace[1521457937] 'applied index is now lower than readState.Index' (duration: 284.776031ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:19:12.919683Z","caller":"traceutil/trace.go:171","msg":"trace[1753323456] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"313.918811ms","start":"2026-10-02T09:19:12.605743Z","end":"2026-10-02T09:19:12.919662Z","steps":["trace[1753323456] 'process raft request' (duration: 255.03987ms)","trace[1753323456] 'compare' (duration: 58.440018ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:19:12.919718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.928661ms","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-10-02T09:19:12.919796Z","caller":"traceutil/trace.go:171","msg":"trace[908301519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5031; }","duration":"294.042428ms","start":"2026-10-02T09:19:12.625739Z","end":"2026-10-02T09:19:12.919782Z","steps":["trace[908301519] 'agreement among raft nodes before linearized reading' (duration: 293.836557ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:19:12.919812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:19:12.605735Z","time spent":"313.988095ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:19:12.920291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.748072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-988df500-4717-400f-8556-60aafa743252\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-02T09:19:12.92035Z","caller":"traceutil/trace.go:171","msg":"trace[1560412875] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-988df500-4717-400f-8556-60aafa743252; range_end:; response_count:1; response_revision:5031; }","duration":"217.839637ms","start":"2026-10-02T09:19:12.702495Z","end":"2026-10-02T09:19:12.920334Z","steps":["trace[1560412875] 'agreement among raft nodes before linearized reading' (duration: 217.094038ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:12.919723Z","caller":"traceutil/trace.go:171","msg":"trace[1995537704] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"313.835917ms","start":"2026-10-02T09:19:12.605833Z","end":"2026-10-02T09:19:12.919669Z","steps":["trace[1995537704] 'process raft request' (duration: 313.51847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:19:12.92154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:19:12.605805Z","time spent":"315.633821ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:19:13.358798Z","caller":"traceutil/trace.go:171","msg":"trace[717064064] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"294.823226ms","start":"2026-10-02T09:19:13.063949Z","end":"2026-10-02T09:19:13.358772Z","steps":["trace[717064064] 'process raft request' (duration: 294.683179ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:13.384041Z","caller":"traceutil/trace.go:171","msg":"trace[1370037560] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"210.344738ms","start":"2026-10-02T09:19:13.17364Z","end":"2026-10-02T09:19:13.383985Z","steps":["trace[1370037560] 'process raft request' (duration: 209.828071ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:20.420544Z","caller":"traceutil/trace.go:171","msg":"trace[1457924112] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5339; }","duration":"114.795807ms","start":"2026-10-02T09:19:20.305725Z","end":"2026-10-02T09:19:20.42052Z","steps":["trace[1457924112] 'read index received' (duration: 40.160115ms)","trace[1457924112] 'applied index is now lower than readState.Index' (duration: 74.634722ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:19:20.420666Z","caller":"traceutil/trace.go:171","msg":"trace[2086886866] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"119.832884ms","start":"2026-10-02T09:19:20.300821Z","end":"2026-10-02T09:19:20.420654Z","steps":["trace[2086886866] 'process raft request' (duration: 45.078335ms)","trace[2086886866] 'compare' (duration: 74.486624ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:19:20.420943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.203849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61689"} {"level":"info","ts":"2026-10-02T09:19:20.421003Z","caller":"traceutil/trace.go:171","msg":"trace[11760457] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5147; }","duration":"115.279963ms","start":"2026-10-02T09:19:20.30571Z","end":"2026-10-02T09:19:20.42099Z","steps":["trace[11760457] 'agreement among raft nodes before linearized reading' (duration: 115.053201ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:28.248717Z","caller":"traceutil/trace.go:171","msg":"trace[164679499] linearizableReadLoop","detail":"{readStateIndex:5424; appliedIndex:5423; }","duration":"316.685115ms","start":"2026-10-02T09:19:27.932006Z","end":"2026-10-02T09:19:28.248691Z","steps":["trace[164679499] 'read index received' (duration: 316.456863ms)","trace[164679499] 'applied index is now lower than readState.Index' (duration: 227.422µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:19:28.248835Z","caller":"traceutil/trace.go:171","msg":"trace[1515526605] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"344.060752ms","start":"2026-10-02T09:19:27.904764Z","end":"2026-10-02T09:19:28.248825Z","steps":["trace[1515526605] 'process raft request' (duration: 343.754176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:19:28.248944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:19:27.904744Z","time spent":"344.113735ms","remote":"127.0.0.1:50188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11650,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:19:28.24921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.195941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-02T09:19:28.249264Z","caller":"traceutil/trace.go:171","msg":"trace[1048017535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5229; }","duration":"317.278816ms","start":"2026-10-02T09:19:27.931971Z","end":"2026-10-02T09:19:28.24925Z","steps":["trace[1048017535] 'agreement among raft nodes before linearized reading' (duration: 317.122838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:19:28.249305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:19:27.931958Z","time spent":"317.334918ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-02T09:19:28.24943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.559553ms","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-10-02T09:19:28.249491Z","caller":"traceutil/trace.go:171","msg":"trace[1655656910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5229; }","duration":"159.69197ms","start":"2026-10-02T09:19:28.089783Z","end":"2026-10-02T09:19:28.249475Z","steps":["trace[1655656910] 'agreement among raft nodes before linearized reading' (duration: 159.486839ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:19:28.378633Z","caller":"traceutil/trace.go:171","msg":"trace[1289061477] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"115.023609ms","start":"2026-10-02T09:19:28.263579Z","end":"2026-10-02T09:19:28.378603Z","steps":["trace[1289061477] 'process raft request' (duration: 80.166708ms)","trace[1289061477] 'compare' (duration: 34.685412ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:19:28.81396Z","caller":"traceutil/trace.go:171","msg":"trace[1496769909] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"110.168813ms","start":"2026-10-02T09:19:28.70376Z","end":"2026-10-02T09:19:28.813928Z","steps":["trace[1496769909] 'process raft request' (duration: 50.734442ms)","trace[1496769909] 'compare' (duration: 59.288323ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:19:56.59438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2909} {"level":"info","ts":"2026-10-02T09:19:56.66878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2909,"took":"72.767902ms","hash":1703665570,"current-db-size-bytes":51191808,"current-db-size":"51 MB","current-db-size-in-use-bytes":27627520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T09:19:56.668987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1703665570,"revision":2909,"compact-revision":-1} {"level":"info","ts":"2026-10-02T09:24:56.602388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5408} {"level":"info","ts":"2026-10-02T09:24:56.696793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5408,"took":"92.878761ms","hash":2371663755,"current-db-size-bytes":51191808,"current-db-size":"51 MB","current-db-size-in-use-bytes":18632704,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-02T09:24:56.69686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2371663755,"revision":5408,"compact-revision":2909}