{"level":"warn","ts":"2026-10-03T09:06:47.858089Z","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-03T09:06:47.858192Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.38: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://162.253.55.38:2380","--initial-cluster=instance=https://162.253.55.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.38: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-03T09:06:47.858259Z","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-03T09:06:47.858274Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.38:2380"]} {"level":"info","ts":"2026-10-03T09:06:47.858289Z","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-03T09:06:47.858979Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"]} {"level":"info","ts":"2026-10-03T09:06:47.859059Z","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://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.38: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-03T09:06:47.863177Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.905856ms"} {"level":"info","ts":"2026-10-03T09:06:47.869759Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30b39d71f9e69e7c","cluster-id":"2afc6404f924db16"} {"level":"info","ts":"2026-10-03T09:06:47.869826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T09:06:47.869841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 0"} {"level":"info","ts":"2026-10-03T09:06:47.869855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30b39d71f9e69e7c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T09:06:47.869863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 1"} {"level":"info","ts":"2026-10-03T09:06:47.869895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"} {"level":"warn","ts":"2026-10-03T09:06:47.883558Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T09:06:47.890712Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T09:06:47.892282Z","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-03T09:06:47.895106Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30b39d71f9e69e7c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T09:06:47.895322Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30b39d71f9e69e7c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T09:06:47.89554Z","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-03T09:06:47.895664Z","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-03T09:06:47.895674Z","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-03T09:06:47.896049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"} {"level":"info","ts":"2026-10-03T09:06:47.89618Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","added-peer-id":"30b39d71f9e69e7c","added-peer-peer-urls":["https://162.253.55.38:2380"]} {"level":"info","ts":"2026-10-03T09:06:47.902358Z","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-03T09:06:47.903118Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.38:2380"} {"level":"info","ts":"2026-10-03T09:06:47.903152Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.38:2380"} {"level":"info","ts":"2026-10-03T09:06:47.903417Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30b39d71f9e69e7c","initial-advertise-peer-urls":["https://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T09:06:47.903448Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T09:06:48.570735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T09:06:48.570826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T09:06:48.570856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgPreVoteResp from 30b39d71f9e69e7c at term 1"} {"level":"info","ts":"2026-10-03T09:06:48.570877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became candidate at term 2"} {"level":"info","ts":"2026-10-03T09:06:48.570888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgVoteResp from 30b39d71f9e69e7c at term 2"} {"level":"info","ts":"2026-10-03T09:06:48.570904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became leader at term 2"} {"level":"info","ts":"2026-10-03T09:06:48.570917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30b39d71f9e69e7c elected leader 30b39d71f9e69e7c at term 2"} {"level":"info","ts":"2026-10-03T09:06:48.572123Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30b39d71f9e69e7c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.38:2379]}","request-path":"/0/members/30b39d71f9e69e7c/attributes","cluster-id":"2afc6404f924db16","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T09:06:48.572173Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T09:06:48.572184Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:06:48.572336Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T09:06:48.573553Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:06:48.573683Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T09:06:48.57374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:06:48.573809Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T09:06:48.575209Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.38:2379"} {"level":"info","ts":"2026-10-03T09:06:48.578839Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T09:06:48.578913Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-10-03T09:06:52.274555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.7737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:06:52.274827Z","caller":"traceutil/trace.go:171","msg":"trace[1966546029] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"332.861964ms","start":"2026-10-03T09:06:51.941928Z","end":"2026-10-03T09:06:52.27479Z","steps":["trace[1966546029] 'process raft request' (duration: 63.155365ms)","trace[1966546029] 'compare' (duration: 268.613825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:06:52.27503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:06:51.941907Z","time spent":"333.029889ms","remote":"127.0.0.1:53434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":646,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T09:06:52.542296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.562308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:06:52.542534Z","caller":"traceutil/trace.go:171","msg":"trace[1077374463] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:230; }","duration":"133.388419ms","start":"2026-10-03T09:06:52.409125Z","end":"2026-10-03T09:06:52.542513Z","steps":["trace[1077374463] 'read index received' (duration: 20.546223ms)","trace[1077374463] 'applied index is now lower than readState.Index' (duration: 112.807705ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:06:52.542777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.584975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:06:52.542839Z","caller":"traceutil/trace.go:171","msg":"trace[1534531550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:225; }","duration":"133.7381ms","start":"2026-10-03T09:06:52.409084Z","end":"2026-10-03T09:06:52.542822Z","steps":["trace[1534531550] 'agreement among raft nodes before linearized reading' (duration: 133.517373ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:06:52.542814Z","caller":"traceutil/trace.go:171","msg":"trace[820253377] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"259.406351ms","start":"2026-10-03T09:06:52.283358Z","end":"2026-10-03T09:06:52.542764Z","steps":["trace[820253377] 'process raft request' (duration: 146.297626ms)","trace[820253377] 'compare' (duration: 112.405963ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:07:28.814663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.181794ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:07:28.814891Z","caller":"traceutil/trace.go:171","msg":"trace[631019698] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:548; }","duration":"147.46486ms","start":"2026-10-03T09:07:28.6674Z","end":"2026-10-03T09:07:28.814865Z","steps":["trace[631019698] 'range keys from in-memory index tree' (duration: 147.146943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:28.815387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.610948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:07:28.815676Z","caller":"traceutil/trace.go:171","msg":"trace[528571612] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"340.5198ms","start":"2026-10-03T09:07:28.475124Z","end":"2026-10-03T09:07:28.815644Z","steps":["trace[528571612] 'process raft request' (duration: 167.266599ms)","trace[528571612] 'compare' (duration: 172.375262ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:07:28.815846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:28.475099Z","time spent":"340.637933ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:07:28.816158Z","caller":"traceutil/trace.go:171","msg":"trace[1495566350] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"328.915273ms","start":"2026-10-03T09:07:28.487221Z","end":"2026-10-03T09:07:28.816136Z","steps":["trace[1495566350] 'process raft request' (duration: 328.435182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:28.816274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:28.487193Z","time spent":"329.016576ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:07:28.816548Z","caller":"traceutil/trace.go:171","msg":"trace[1166487079] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"322.799608ms","start":"2026-10-03T09:07:28.493732Z","end":"2026-10-03T09:07:28.816531Z","steps":["trace[1166487079] 'process raft request' (duration: 322.129223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:28.816671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:28.493715Z","time spent":"322.870049ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:07:28.81689Z","caller":"traceutil/trace.go:171","msg":"trace[1805639101] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:567; }","duration":"270.13971ms","start":"2026-10-03T09:07:28.546729Z","end":"2026-10-03T09:07:28.816869Z","steps":["trace[1805639101] 'read index received' (duration: 95.59161ms)","trace[1805639101] 'applied index is now lower than readState.Index' (duration: 174.54637ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:07:28.817042Z","caller":"traceutil/trace.go:171","msg":"trace[1803964369] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"232.771151ms","start":"2026-10-03T09:07:28.584259Z","end":"2026-10-03T09:07:28.81703Z","steps":["trace[1803964369] 'process raft request' (duration: 232.532746ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:28.817076Z","caller":"traceutil/trace.go:171","msg":"trace[785516639] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"321.160912ms","start":"2026-10-03T09:07:28.495897Z","end":"2026-10-03T09:07:28.817058Z","steps":["trace[785516639] 'process raft request' (duration: 320.442376ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:28.817093Z","caller":"traceutil/trace.go:171","msg":"trace[1065906037] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"320.985858ms","start":"2026-10-03T09:07:28.496095Z","end":"2026-10-03T09:07:28.817081Z","steps":["trace[1065906037] 'process raft request' (duration: 320.412165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:28.81717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:28.49589Z","time spent":"321.228083ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T09:07:28.817179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:28.496082Z","time spent":"321.039589ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T09:07:28.81705Z","caller":"traceutil/trace.go:171","msg":"trace[1006749943] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"309.83535ms","start":"2026-10-03T09:07:28.507199Z","end":"2026-10-03T09:07:28.817034Z","steps":["trace[1006749943] 'process raft request' (duration: 309.460092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:28.817381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:28.507183Z","time spent":"310.126217ms","remote":"127.0.0.1:53148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T09:07:28.81743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.929536ms","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-03T09:07:28.81747Z","caller":"traceutil/trace.go:171","msg":"trace[1976403889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"159.964647ms","start":"2026-10-03T09:07:28.65749Z","end":"2026-10-03T09:07:28.817455Z","steps":["trace[1976403889] 'agreement among raft nodes before linearized reading' (duration: 159.797124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:28.817704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.966999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:07:28.817828Z","caller":"traceutil/trace.go:171","msg":"trace[1924626101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"271.049051ms","start":"2026-10-03T09:07:28.546694Z","end":"2026-10-03T09:07:28.817743Z","steps":["trace[1924626101] 'agreement among raft nodes before linearized reading' (duration: 270.962079ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:30.217973Z","caller":"traceutil/trace.go:171","msg":"trace[1266526400] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"117.236369ms","start":"2026-10-03T09:07:30.100703Z","end":"2026-10-03T09:07:30.217939Z","steps":["trace[1266526400] 'process raft request' (duration: 117.191648ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:30.21819Z","caller":"traceutil/trace.go:171","msg":"trace[699081533] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"212.18209ms","start":"2026-10-03T09:07:30.005868Z","end":"2026-10-03T09:07:30.21805Z","steps":["trace[699081533] 'read index received' (duration: 186.315436ms)","trace[699081533] 'applied index is now lower than readState.Index' (duration: 25.862104ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:07:30.218306Z","caller":"traceutil/trace.go:171","msg":"trace[1152119144] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"241.646238ms","start":"2026-10-03T09:07:29.976424Z","end":"2026-10-03T09:07:30.21807Z","steps":["trace[1152119144] 'process raft request' (duration: 215.832555ms)","trace[1152119144] 'compare' (duration: 24.995085ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:07:30.218513Z","caller":"traceutil/trace.go:171","msg":"trace[1029801196] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"162.016495ms","start":"2026-10-03T09:07:30.056082Z","end":"2026-10-03T09:07:30.218099Z","steps":["trace[1029801196] 'process raft request' (duration: 161.498584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:30.221808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.912942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-10-03T09:07:30.221905Z","caller":"traceutil/trace.go:171","msg":"trace[1290711606] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:628; }","duration":"216.092226ms","start":"2026-10-03T09:07:30.005786Z","end":"2026-10-03T09:07:30.221879Z","steps":["trace[1290711606] 'agreement among raft nodes before linearized reading' (duration: 212.369065ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:32.080412Z","caller":"traceutil/trace.go:171","msg":"trace[1565170837] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"105.107903ms","start":"2026-10-03T09:07:31.97528Z","end":"2026-10-03T09:07:32.080388Z","steps":["trace[1565170837] 'process raft request' (duration: 104.983621ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:32.523481Z","caller":"traceutil/trace.go:171","msg":"trace[568940049] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"152.580788ms","start":"2026-10-03T09:07:32.370873Z","end":"2026-10-03T09:07:32.523454Z","steps":["trace[568940049] 'process raft request' (duration: 146.927466ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:37.068498Z","caller":"traceutil/trace.go:171","msg":"trace[819965687] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"169.656604ms","start":"2026-10-03T09:07:36.898808Z","end":"2026-10-03T09:07:37.068464Z","steps":["trace[819965687] 'read index received' (duration: 169.340827ms)","trace[819965687] 'applied index is now lower than readState.Index' (duration: 314.567µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:07:37.068593Z","caller":"traceutil/trace.go:171","msg":"trace[742363535] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"202.131121ms","start":"2026-10-03T09:07:36.866436Z","end":"2026-10-03T09:07:37.068567Z","steps":["trace[742363535] 'process raft request' (duration: 201.853445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:37.068866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.054092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-03T09:07:37.068931Z","caller":"traceutil/trace.go:171","msg":"trace[358857874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:697; }","duration":"170.197275ms","start":"2026-10-03T09:07:36.898717Z","end":"2026-10-03T09:07:37.068914Z","steps":["trace[358857874] 'agreement among raft nodes before linearized reading' (duration: 169.95737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:37.068937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.079002ms","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-03T09:07:37.068999Z","caller":"traceutil/trace.go:171","msg":"trace[302234726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"157.153933ms","start":"2026-10-03T09:07:36.911819Z","end":"2026-10-03T09:07:37.068973Z","steps":["trace[302234726] 'agreement among raft nodes before linearized reading' (duration: 156.99122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:37.068974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.779202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-03T09:07:37.068871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.107002ms","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-03T09:07:37.069107Z","caller":"traceutil/trace.go:171","msg":"trace[466752049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:697; }","duration":"139.982007ms","start":"2026-10-03T09:07:36.929095Z","end":"2026-10-03T09:07:37.069077Z","steps":["trace[466752049] 'agreement among raft nodes before linearized reading' (duration: 139.67925ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:37.069112Z","caller":"traceutil/trace.go:171","msg":"trace[1839166412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:697; }","duration":"168.399678ms","start":"2026-10-03T09:07:36.900693Z","end":"2026-10-03T09:07:37.069093Z","steps":["trace[1839166412] 'agreement among raft nodes before linearized reading' (duration: 168.04026ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:37.389535Z","caller":"traceutil/trace.go:171","msg":"trace[154364407] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"313.012432ms","start":"2026-10-03T09:07:37.076485Z","end":"2026-10-03T09:07:37.389497Z","steps":["trace[154364407] 'process raft request' (duration: 312.794908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:37.389762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:37.076452Z","time spent":"313.202587ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:07:37.483846Z","caller":"traceutil/trace.go:171","msg":"trace[269176814] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"407.16444ms","start":"2026-10-03T09:07:37.07665Z","end":"2026-10-03T09:07:37.483814Z","steps":["trace[269176814] 'process raft request' (duration: 346.321442ms)","trace[269176814] 'compare' (duration: 60.520671ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:07:37.484204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:37.07662Z","time spent":"407.420065ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T09:07:37.485049Z","caller":"traceutil/trace.go:171","msg":"trace[168585874] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:717; }","duration":"317.584367ms","start":"2026-10-03T09:07:37.167443Z","end":"2026-10-03T09:07:37.485028Z","steps":["trace[168585874] 'read index received' (duration: 221.821897ms)","trace[168585874] 'applied index is now lower than readState.Index' (duration: 95.76106ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:07:37.485414Z","caller":"traceutil/trace.go:171","msg":"trace[1175308832] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"408.025757ms","start":"2026-10-03T09:07:37.077354Z","end":"2026-10-03T09:07:37.485379Z","steps":["trace[1175308832] 'process raft request' (duration: 407.585038ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:07:37.485526Z","caller":"traceutil/trace.go:171","msg":"trace[51955876] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"408.712552ms","start":"2026-10-03T09:07:37.07679Z","end":"2026-10-03T09:07:37.485503Z","steps":["trace[51955876] 'process raft request' (duration: 406.961265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:37.485811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:37.076771Z","time spent":"408.844454ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T09:07:37.48604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:37.077335Z","time spent":"408.12879ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T09:07:37.486028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.569908ms","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-10-03T09:07:37.486131Z","caller":"traceutil/trace.go:171","msg":"trace[1010979541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"318.70597ms","start":"2026-10-03T09:07:37.167399Z","end":"2026-10-03T09:07:37.486105Z","steps":["trace[1010979541] 'agreement among raft nodes before linearized reading' (duration: 317.71031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:07:37.486208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T09:07:37.167384Z","time spent":"318.792283ms","remote":"127.0.0.1:53366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-03T09:07:54.22692Z","caller":"traceutil/trace.go:171","msg":"trace[599338444] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"251.750959ms","start":"2026-10-03T09:07:53.975138Z","end":"2026-10-03T09:07:54.226889Z","steps":["trace[599338444] 'process raft request' (duration: 251.515634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:08:24.101013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.830467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T09:08:24.101176Z","caller":"traceutil/trace.go:171","msg":"trace[1152933331] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"127.784009ms","start":"2026-10-03T09:08:23.973373Z","end":"2026-10-03T09:08:24.101157Z","steps":["trace[1152933331] 'read index received' (duration: 62.881µs)","trace[1152933331] 'applied index is now lower than readState.Index' (duration: 127.719648ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:08:24.101399Z","caller":"traceutil/trace.go:171","msg":"trace[1382892132] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"201.748734ms","start":"2026-10-03T09:08:23.899605Z","end":"2026-10-03T09:08:24.101354Z","steps":["trace[1382892132] 'process raft request' (duration: 29.471991ms)","trace[1382892132] 'compare' (duration: 171.409699ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:08:24.101642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.215415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:08:24.101758Z","caller":"traceutil/trace.go:171","msg":"trace[1202227540] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1221; }","duration":"128.438329ms","start":"2026-10-03T09:08:23.973293Z","end":"2026-10-03T09:08:24.101732Z","steps":["trace[1202227540] 'agreement among raft nodes before linearized reading' (duration: 128.143904ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:08:52.426545Z","caller":"traceutil/trace.go:171","msg":"trace[967797496] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"168.218664ms","start":"2026-10-03T09:08:52.258278Z","end":"2026-10-03T09:08:52.426497Z","steps":["trace[967797496] 'process raft request' (duration: 156.3538ms)","trace[967797496] 'compare' (duration: 11.452988ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:09:14.27157Z","caller":"traceutil/trace.go:171","msg":"trace[2102451173] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"266.712265ms","start":"2026-10-03T09:09:14.00482Z","end":"2026-10-03T09:09:14.271533Z","steps":["trace[2102451173] 'process raft request' (duration: 265.784463ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:09:14.270793Z","caller":"traceutil/trace.go:171","msg":"trace[1022857888] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1966; }","duration":"262.44408ms","start":"2026-10-03T09:09:14.008325Z","end":"2026-10-03T09:09:14.270769Z","steps":["trace[1022857888] 'read index received' (duration: 262.322208ms)","trace[1022857888] 'applied index is now lower than readState.Index' (duration: 120.672µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T09:09:14.272262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.741262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-03T09:09:14.272467Z","caller":"traceutil/trace.go:171","msg":"trace[966612426] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1897; }","duration":"178.938755ms","start":"2026-10-03T09:09:14.093462Z","end":"2026-10-03T09:09:14.272401Z","steps":["trace[966612426] 'agreement among raft nodes before linearized reading' (duration: 178.58287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:09:14.278108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.704134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-03T09:09:14.279076Z","caller":"traceutil/trace.go:171","msg":"trace[1052046250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1897; }","duration":"270.716917ms","start":"2026-10-03T09:09:14.008293Z","end":"2026-10-03T09:09:14.27901Z","steps":["trace[1052046250] 'agreement among raft nodes before linearized reading' (duration: 262.588501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:09:14.566243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.661261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1f7ab9a3-402d-4f76-a3e9-31bde6d12d70\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-03T09:09:14.566322Z","caller":"traceutil/trace.go:171","msg":"trace[1119559507] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1f7ab9a3-402d-4f76-a3e9-31bde6d12d70; range_end:; response_count:1; response_revision:1901; }","duration":"215.783632ms","start":"2026-10-03T09:09:14.350519Z","end":"2026-10-03T09:09:14.566302Z","steps":["trace[1119559507] 'range keys from in-memory index tree' (duration: 215.428497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:09:14.566464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.763012ms","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-03T09:09:14.566597Z","caller":"traceutil/trace.go:171","msg":"trace[24321907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1901; }","duration":"176.025275ms","start":"2026-10-03T09:09:14.390545Z","end":"2026-10-03T09:09:14.56657Z","steps":["trace[24321907] 'range keys from in-memory index tree' (duration: 175.448018ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:09:53.890775Z","caller":"traceutil/trace.go:171","msg":"trace[1396712359] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"217.159288ms","start":"2026-10-03T09:09:53.673586Z","end":"2026-10-03T09:09:53.890745Z","steps":["trace[1396712359] 'process raft request' (duration: 216.964726ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:09:54.235658Z","caller":"traceutil/trace.go:171","msg":"trace[1161343890] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"100.05059ms","start":"2026-10-03T09:09:54.135582Z","end":"2026-10-03T09:09:54.235633Z","steps":["trace[1161343890] 'process raft request' (duration: 99.881788ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:09:54.611086Z","caller":"traceutil/trace.go:171","msg":"trace[2038572537] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"116.465817ms","start":"2026-10-03T09:09:54.494591Z","end":"2026-10-03T09:09:54.611056Z","steps":["trace[2038572537] 'process raft request' (duration: 98.612353ms)","trace[2038572537] 'compare' (duration: 17.474399ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:09:54.611359Z","caller":"traceutil/trace.go:171","msg":"trace[1892916605] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"116.124473ms","start":"2026-10-03T09:09:54.495223Z","end":"2026-10-03T09:09:54.611347Z","steps":["trace[1892916605] 'process raft request' (duration: 115.585947ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:09:54.804451Z","caller":"traceutil/trace.go:171","msg":"trace[459161006] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"187.731262ms","start":"2026-10-03T09:09:54.616684Z","end":"2026-10-03T09:09:54.804415Z","steps":["trace[459161006] 'process raft request' (duration: 106.036762ms)","trace[459161006] 'compare' (duration: 81.500828ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:10:44.643067Z","caller":"traceutil/trace.go:171","msg":"trace[1749617024] linearizableReadLoop","detail":"{readStateIndex:2631; appliedIndex:2630; }","duration":"159.372357ms","start":"2026-10-03T09:10:44.483673Z","end":"2026-10-03T09:10:44.643045Z","steps":["trace[1749617024] 'read index received' (duration: 159.185959ms)","trace[1749617024] 'applied index is now lower than readState.Index' (duration: 185.498µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:10:44.643183Z","caller":"traceutil/trace.go:171","msg":"trace[2131449455] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"162.425311ms","start":"2026-10-03T09:10:44.48073Z","end":"2026-10-03T09:10:44.643155Z","steps":["trace[2131449455] 'process raft request' (duration: 162.182831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:10:44.643293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.602177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-10-03T09:10:44.643332Z","caller":"traceutil/trace.go:171","msg":"trace[2076184979] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2541; }","duration":"159.68697ms","start":"2026-10-03T09:10:44.483634Z","end":"2026-10-03T09:10:44.643321Z","steps":["trace[2076184979] 'agreement among raft nodes before linearized reading' (duration: 159.518173ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:11:21.951832Z","caller":"traceutil/trace.go:171","msg":"trace[1152115463] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"126.301321ms","start":"2026-10-03T09:11:21.825499Z","end":"2026-10-03T09:11:21.9518Z","steps":["trace[1152115463] 'process raft request' (duration: 125.514652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:15:49.53745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.97883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-645d644c4b-sjv8c\" ","response":"range_response_count:1 size:11256"} {"level":"info","ts":"2026-10-03T09:15:49.537531Z","caller":"traceutil/trace.go:171","msg":"trace[1337213813] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-645d644c4b-sjv8c; range_end:; response_count:1; response_revision:4928; }","duration":"170.104022ms","start":"2026-10-03T09:15:49.367409Z","end":"2026-10-03T09:15:49.537513Z","steps":["trace[1337213813] 'range keys from in-memory index tree' (duration: 169.804986ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:49.551126Z","caller":"traceutil/trace.go:171","msg":"trace[1799903432] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"154.903903ms","start":"2026-10-03T09:15:49.396198Z","end":"2026-10-03T09:15:49.551102Z","steps":["trace[1799903432] 'read index received' (duration: 154.642308ms)","trace[1799903432] 'applied index is now lower than readState.Index' (duration: 260.255µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:15:49.55125Z","caller":"traceutil/trace.go:171","msg":"trace[1996321586] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"183.445397ms","start":"2026-10-03T09:15:49.367794Z","end":"2026-10-03T09:15:49.55124Z","steps":["trace[1996321586] 'process raft request' (duration: 183.169352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:15:49.551873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.650046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-10-03T09:15:49.551929Z","caller":"traceutil/trace.go:171","msg":"trace[1251386265] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4929; }","duration":"155.719728ms","start":"2026-10-03T09:15:49.396193Z","end":"2026-10-03T09:15:49.551913Z","steps":["trace[1251386265] 'agreement among raft nodes before linearized reading' (duration: 155.502584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:15:49.552123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.561678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T09:15:49.552157Z","caller":"traceutil/trace.go:171","msg":"trace[821854857] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4929; }","duration":"153.600039ms","start":"2026-10-03T09:15:49.398549Z","end":"2026-10-03T09:15:49.552149Z","steps":["trace[821854857] 'agreement among raft nodes before linearized reading' (duration: 153.538958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:15:49.552376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.249884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T09:15:49.552437Z","caller":"traceutil/trace.go:171","msg":"trace[1660521985] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:4929; }","duration":"140.332856ms","start":"2026-10-03T09:15:49.412092Z","end":"2026-10-03T09:15:49.552424Z","steps":["trace[1660521985] 'agreement among raft nodes before linearized reading' (duration: 140.251135ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:49.695929Z","caller":"traceutil/trace.go:171","msg":"trace[4906586] linearizableReadLoop","detail":"{readStateIndex:5112; appliedIndex:5111; }","duration":"126.883519ms","start":"2026-10-03T09:15:49.569021Z","end":"2026-10-03T09:15:49.695904Z","steps":["trace[4906586] 'read index received' (duration: 126.667985ms)","trace[4906586] 'applied index is now lower than readState.Index' (duration: 214.294µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:15:49.695974Z","caller":"traceutil/trace.go:171","msg":"trace[1908419769] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"133.430619ms","start":"2026-10-03T09:15:49.562514Z","end":"2026-10-03T09:15:49.695945Z","steps":["trace[1908419769] 'process raft request' (duration: 133.229916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:15:49.696169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.124063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8cf4ace3-9ac0-45d6-9754-84347164b6cb\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-10-03T09:15:49.696215Z","caller":"traceutil/trace.go:171","msg":"trace[1837890789] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8cf4ace3-9ac0-45d6-9754-84347164b6cb; range_end:; response_count:1; response_revision:4930; }","duration":"127.203275ms","start":"2026-10-03T09:15:49.56899Z","end":"2026-10-03T09:15:49.696193Z","steps":["trace[1837890789] 'agreement among raft nodes before linearized reading' (duration: 127.033092ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:49.967372Z","caller":"traceutil/trace.go:171","msg":"trace[991213546] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"148.201871ms","start":"2026-10-03T09:15:49.819138Z","end":"2026-10-03T09:15:49.967339Z","steps":["trace[991213546] 'process raft request' (duration: 144.812868ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:49.96765Z","caller":"traceutil/trace.go:171","msg":"trace[2007053335] linearizableReadLoop","detail":"{readStateIndex:5121; appliedIndex:5116; }","duration":"147.892865ms","start":"2026-10-03T09:15:49.819734Z","end":"2026-10-03T09:15:49.967627Z","steps":["trace[2007053335] 'read index received' (duration: 26.942255ms)","trace[2007053335] 'applied index is now lower than readState.Index' (duration: 120.94979ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:15:49.967742Z","caller":"traceutil/trace.go:171","msg":"trace[2141592491] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"148.351202ms","start":"2026-10-03T09:15:49.819359Z","end":"2026-10-03T09:15:49.967711Z","steps":["trace[2141592491] 'process raft request' (duration: 147.903834ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:49.967828Z","caller":"traceutil/trace.go:171","msg":"trace[1037108020] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"148.143209ms","start":"2026-10-03T09:15:49.819673Z","end":"2026-10-03T09:15:49.967816Z","steps":["trace[1037108020] 'process raft request' (duration: 147.903435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:15:49.967786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.036407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T09:15:49.967834Z","caller":"traceutil/trace.go:171","msg":"trace[1417897602] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"148.104539ms","start":"2026-10-03T09:15:49.819675Z","end":"2026-10-03T09:15:49.967779Z","steps":["trace[1417897602] 'process raft request' (duration: 147.833814ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:15:49.967913Z","caller":"traceutil/trace.go:171","msg":"trace[997606512] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4939; }","duration":"148.186619ms","start":"2026-10-03T09:15:49.819688Z","end":"2026-10-03T09:15:49.967875Z","steps":["trace[997606512] 'agreement among raft nodes before linearized reading' (duration: 148.028487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:01.887126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.160491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-j7fmv.18dafa3d5dd32e4e\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-10-03T09:16:01.887222Z","caller":"traceutil/trace.go:171","msg":"trace[1245999102] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-j7fmv.18dafa3d5dd32e4e; range_end:; response_count:1; response_revision:5104; }","duration":"187.291894ms","start":"2026-10-03T09:16:01.699906Z","end":"2026-10-03T09:16:01.887198Z","steps":["trace[1245999102] 'range keys from in-memory index tree' (duration: 187.001408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T09:16:30.322677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.795217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-03T09:16:30.322769Z","caller":"traceutil/trace.go:171","msg":"trace[269316695] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"148.804027ms","start":"2026-10-03T09:16:30.173932Z","end":"2026-10-03T09:16:30.322736Z","steps":["trace[269316695] 'process raft request' (duration: 119.394207ms)","trace[269316695] 'compare' (duration: 29.044683ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T09:16:30.32281Z","caller":"traceutil/trace.go:171","msg":"trace[585412612] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5376; }","duration":"156.972881ms","start":"2026-10-03T09:16:30.165817Z","end":"2026-10-03T09:16:30.32279Z","steps":["trace[585412612] 'range keys from in-memory index tree' (duration: 156.621994ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:30.348507Z","caller":"traceutil/trace.go:171","msg":"trace[1380886411] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"142.453654ms","start":"2026-10-03T09:16:30.206026Z","end":"2026-10-03T09:16:30.34848Z","steps":["trace[1380886411] 'process raft request' (duration: 142.309401ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T09:16:48.611492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2889} {"level":"info","ts":"2026-10-03T09:16:48.71371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2889,"took":"100.904816ms","hash":262323215,"current-db-size-bytes":51589120,"current-db-size":"52 MB","current-db-size-in-use-bytes":28491776,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-03T09:16:48.713809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":262323215,"revision":2889,"compact-revision":-1} {"level":"info","ts":"2026-10-03T09:21:48.618344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5512} {"level":"info","ts":"2026-10-03T09:21:48.729318Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5512,"took":"109.677282ms","hash":52804818,"current-db-size-bytes":51589120,"current-db-size":"52 MB","current-db-size-in-use-bytes":18550784,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-03T09:21:48.729382Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":52804818,"revision":5512,"compact-revision":2889}