{"level":"warn","ts":"2026-09-15T11:17:15.981557Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T11:17:15.981642Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.181: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.181:2380","--initial-cluster=instance=https://162.253.55.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.181: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-09-15T11:17:15.981731Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-15T11:17:15.981742Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.181:2380"]} {"level":"info","ts":"2026-09-15T11:17:15.981773Z","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-09-15T11:17:15.982525Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"]} {"level":"info","ts":"2026-09-15T11:17:15.982621Z","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.181:2380"],"listen-peer-urls":["https://162.253.55.181:2380"],"advertise-client-urls":["https://162.253.55.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.181: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-09-15T11:17:15.986779Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.911663ms"} {"level":"info","ts":"2026-09-15T11:17:16.008199Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"865c2c1f02504fb9","cluster-id":"c3b098aaa499ad51"} {"level":"info","ts":"2026-09-15T11:17:16.008301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T11:17:16.008327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became follower at term 0"} {"level":"info","ts":"2026-09-15T11:17:16.008337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 865c2c1f02504fb9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T11:17:16.008354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became follower at term 1"} {"level":"info","ts":"2026-09-15T11:17:16.008389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=(9681661810634149817)"} {"level":"warn","ts":"2026-09-15T11:17:16.016898Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T11:17:16.018306Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T11:17:16.020421Z","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-09-15T11:17:16.023527Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"865c2c1f02504fb9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T11:17:16.033513Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"865c2c1f02504fb9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T11:17:16.028115Z","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-09-15T11:17:16.033594Z","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-09-15T11:17:16.034306Z","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-09-15T11:17:16.034386Z","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-09-15T11:17:16.034566Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.181:2380"} {"level":"info","ts":"2026-09-15T11:17:16.034605Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.181:2380"} {"level":"info","ts":"2026-09-15T11:17:16.034733Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"865c2c1f02504fb9","initial-advertise-peer-urls":["https://162.253.55.181:2380"],"listen-peer-urls":["https://162.253.55.181:2380"],"advertise-client-urls":["https://162.253.55.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T11:17:16.034773Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T11:17:16.034985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 switched to configuration voters=(9681661810634149817)"} {"level":"info","ts":"2026-09-15T11:17:16.035104Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c3b098aaa499ad51","local-member-id":"865c2c1f02504fb9","added-peer-id":"865c2c1f02504fb9","added-peer-peer-urls":["https://162.253.55.181:2380"]} {"level":"info","ts":"2026-09-15T11:17:16.109391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T11:17:16.109446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T11:17:16.109483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 received MsgPreVoteResp from 865c2c1f02504fb9 at term 1"} {"level":"info","ts":"2026-09-15T11:17:16.109501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became candidate at term 2"} {"level":"info","ts":"2026-09-15T11:17:16.109516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 received MsgVoteResp from 865c2c1f02504fb9 at term 2"} {"level":"info","ts":"2026-09-15T11:17:16.109535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"865c2c1f02504fb9 became leader at term 2"} {"level":"info","ts":"2026-09-15T11:17:16.109552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 865c2c1f02504fb9 elected leader 865c2c1f02504fb9 at term 2"} {"level":"info","ts":"2026-09-15T11:17:16.11044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"865c2c1f02504fb9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.181:2379]}","request-path":"/0/members/865c2c1f02504fb9/attributes","cluster-id":"c3b098aaa499ad51","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T11:17:16.110487Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T11:17:16.110658Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T11:17:16.11094Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T11:17:16.110964Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T11:17:16.11096Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:17:16.112704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T11:17:16.11328Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c3b098aaa499ad51","local-member-id":"865c2c1f02504fb9","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:17:16.113363Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:17:16.113351Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.181:2379"} {"level":"info","ts":"2026-09-15T11:17:16.113385Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:17:18.486905Z","caller":"traceutil/trace.go:171","msg":"trace[574561110] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"261.267267ms","start":"2026-09-15T11:17:18.225613Z","end":"2026-09-15T11:17:18.48688Z","steps":["trace[574561110] 'process raft request' (duration: 261.112162ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:17:18.801069Z","caller":"traceutil/trace.go:171","msg":"trace[1363974782] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"100.018598ms","start":"2026-09-15T11:17:18.701011Z","end":"2026-09-15T11:17:18.80103Z","steps":["trace[1363974782] 'range keys from in-memory index tree' (duration: 99.76903ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:17:19.011517Z","caller":"traceutil/trace.go:171","msg":"trace[737704264] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"148.300039ms","start":"2026-09-15T11:17:18.863192Z","end":"2026-09-15T11:17:19.011492Z","steps":["trace[737704264] 'read index received' (duration: 143.863053ms)","trace[737704264] 'applied index is now lower than readState.Index' (duration: 4.435836ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:17:19.011614Z","caller":"traceutil/trace.go:171","msg":"trace[1221791749] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"148.963771ms","start":"2026-09-15T11:17:18.862592Z","end":"2026-09-15T11:17:19.011556Z","steps":["trace[1221791749] 'process raft request' (duration: 144.460243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:17:19.011727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.501525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-15T11:17:19.011792Z","caller":"traceutil/trace.go:171","msg":"trace[1027030767] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"148.622929ms","start":"2026-09-15T11:17:18.863157Z","end":"2026-09-15T11:17:19.01178Z","steps":["trace[1027030767] 'agreement among raft nodes before linearized reading' (duration: 148.435463ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:17:56.604352Z","caller":"traceutil/trace.go:171","msg":"trace[1779346973] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"140.462043ms","start":"2026-09-15T11:17:56.463869Z","end":"2026-09-15T11:17:56.604331Z","steps":["trace[1779346973] 'process raft request' (duration: 138.180487ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:17:56.604712Z","caller":"traceutil/trace.go:171","msg":"trace[1160728256] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"137.609444ms","start":"2026-09-15T11:17:56.467094Z","end":"2026-09-15T11:17:56.604703Z","steps":["trace[1160728256] 'process raft request' (duration: 137.205874ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:17:56.604915Z","caller":"traceutil/trace.go:171","msg":"trace[2099131969] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"110.716062ms","start":"2026-09-15T11:17:56.494159Z","end":"2026-09-15T11:17:56.604876Z","steps":["trace[2099131969] 'process raft request' (duration: 110.6499ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:17:56.604952Z","caller":"traceutil/trace.go:171","msg":"trace[923545512] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"110.854975ms","start":"2026-09-15T11:17:56.494076Z","end":"2026-09-15T11:17:56.604931Z","steps":["trace[923545512] 'process raft request' (duration: 110.471476ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:04.929926Z","caller":"traceutil/trace.go:171","msg":"trace[1451807109] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"182.588192ms","start":"2026-09-15T11:18:04.747316Z","end":"2026-09-15T11:18:04.929904Z","steps":["trace[1451807109] 'process raft request' (duration: 144.058407ms)","trace[1451807109] 'compare' (duration: 38.430373ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:18:05.537143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.622726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:18:05.537373Z","caller":"traceutil/trace.go:171","msg":"trace[742396386] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"576.295813ms","start":"2026-09-15T11:18:04.961035Z","end":"2026-09-15T11:18:05.537331Z","steps":["trace[742396386] 'process raft request' (duration: 214.834837ms)","trace[742396386] 'compare' (duration: 360.33003ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:18:05.537387Z","caller":"traceutil/trace.go:171","msg":"trace[1013939169] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"535.101347ms","start":"2026-09-15T11:18:05.002251Z","end":"2026-09-15T11:18:05.537352Z","steps":["trace[1013939169] 'process raft request' (duration: 534.980105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:05.537548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:04.961014Z","time spent":"576.437567ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:18:05.537528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:18:05.002227Z","time spent":"535.22695ms","remote":"127.0.0.1:41548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T11:18:07.275579Z","caller":"traceutil/trace.go:171","msg":"trace[180981599] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"138.830057ms","start":"2026-09-15T11:18:07.136719Z","end":"2026-09-15T11:18:07.275549Z","steps":["trace[180981599] 'process raft request' (duration: 131.184245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:22.135144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.006348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:18:22.135487Z","caller":"traceutil/trace.go:171","msg":"trace[134362539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"177.331344ms","start":"2026-09-15T11:18:21.958106Z","end":"2026-09-15T11:18:22.135437Z","steps":["trace[134362539] 'range keys from in-memory index tree' (duration: 176.927176ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.643075Z","caller":"traceutil/trace.go:171","msg":"trace[789549103] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"106.025944ms","start":"2026-09-15T11:18:42.537015Z","end":"2026-09-15T11:18:42.643041Z","steps":["trace[789549103] 'process raft request' (duration: 105.049087ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.643583Z","caller":"traceutil/trace.go:171","msg":"trace[227680118] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"106.416112ms","start":"2026-09-15T11:18:42.53715Z","end":"2026-09-15T11:18:42.643566Z","steps":["trace[227680118] 'process raft request' (duration: 105.146628ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.643752Z","caller":"traceutil/trace.go:171","msg":"trace[1289256822] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"106.467323ms","start":"2026-09-15T11:18:42.537272Z","end":"2026-09-15T11:18:42.643739Z","steps":["trace[1289256822] 'process raft request' (duration: 105.091617ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.644106Z","caller":"traceutil/trace.go:171","msg":"trace[609032515] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"106.784228ms","start":"2026-09-15T11:18:42.537308Z","end":"2026-09-15T11:18:42.644092Z","steps":["trace[609032515] 'process raft request' (duration: 105.097937ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.64432Z","caller":"traceutil/trace.go:171","msg":"trace[223626081] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"106.972252ms","start":"2026-09-15T11:18:42.537334Z","end":"2026-09-15T11:18:42.644306Z","steps":["trace[223626081] 'process raft request' (duration: 105.115338ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.64451Z","caller":"traceutil/trace.go:171","msg":"trace[1655466820] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"107.121625ms","start":"2026-09-15T11:18:42.537373Z","end":"2026-09-15T11:18:42.644495Z","steps":["trace[1655466820] 'process raft request' (duration: 105.144588ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.64467Z","caller":"traceutil/trace.go:171","msg":"trace[875836451] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"107.208818ms","start":"2026-09-15T11:18:42.537448Z","end":"2026-09-15T11:18:42.644657Z","steps":["trace[875836451] 'process raft request' (duration: 105.108498ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.645005Z","caller":"traceutil/trace.go:171","msg":"trace[1005303016] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"107.072915ms","start":"2026-09-15T11:18:42.537916Z","end":"2026-09-15T11:18:42.644989Z","steps":["trace[1005303016] 'process raft request' (duration: 104.678559ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.645181Z","caller":"traceutil/trace.go:171","msg":"trace[1902940681] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"105.068767ms","start":"2026-09-15T11:18:42.5401Z","end":"2026-09-15T11:18:42.645169Z","steps":["trace[1902940681] 'process raft request' (duration: 102.540409ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:18:42.645364Z","caller":"traceutil/trace.go:171","msg":"trace[48730198] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"104.796072ms","start":"2026-09-15T11:18:42.540557Z","end":"2026-09-15T11:18:42.645353Z","steps":["trace[48730198] 'process raft request' (duration: 102.119262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:18:42.645532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.190443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:18:42.645573Z","caller":"traceutil/trace.go:171","msg":"trace[768546493] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1068; }","duration":"102.286585ms","start":"2026-09-15T11:18:42.543275Z","end":"2026-09-15T11:18:42.645561Z","steps":["trace[768546493] 'agreement among raft nodes before linearized reading' (duration: 102.193023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.056969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051984918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-101d9bf8-844c-4dfc-8d77-693f9f9a2b13\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-15T11:19:47.05706Z","caller":"traceutil/trace.go:171","msg":"trace[2049671842] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-101d9bf8-844c-4dfc-8d77-693f9f9a2b13; range_end:; response_count:1; response_revision:1869; }","duration":"1.052130699s","start":"2026-09-15T11:19:46.00491Z","end":"2026-09-15T11:19:47.057041Z","steps":["trace[2049671842] 'range keys from in-memory index tree' (duration: 1.051784555s)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.057124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:46.00489Z","time spent":"1.05222202s","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-101d9bf8-844c-4dfc-8d77-693f9f9a2b13\" "} {"level":"warn","ts":"2026-09-15T11:19:47.057168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.164292ms","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-09-15T11:19:47.057862Z","caller":"traceutil/trace.go:171","msg":"trace[1553103026] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1869; }","duration":"564.875723ms","start":"2026-09-15T11:19:46.492954Z","end":"2026-09-15T11:19:47.05783Z","steps":["trace[1553103026] 'range keys from in-memory index tree' (duration: 564.034851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.058005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:46.492931Z","time spent":"565.052126ms","remote":"127.0.0.1:38972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-15T11:19:47.057877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.188622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:19:47.058376Z","caller":"traceutil/trace.go:171","msg":"trace[474400289] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1944; }","duration":"279.873227ms","start":"2026-09-15T11:19:46.778472Z","end":"2026-09-15T11:19:47.058346Z","steps":["trace[474400289] 'read index received' (duration: 102.177436ms)","trace[474400289] 'applied index is now lower than readState.Index' (duration: 177.693571ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:19:47.058437Z","caller":"traceutil/trace.go:171","msg":"trace[821285223] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"510.775879ms","start":"2026-09-15T11:19:46.547644Z","end":"2026-09-15T11:19:47.05842Z","steps":["trace[821285223] 'process raft request' (duration: 332.884406ms)","trace[821285223] 'compare' (duration: 176.670854ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:19:47.058603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:46.54762Z","time spent":"510.89002ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:19:47.0572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041696749s","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-09-15T11:19:47.058667Z","caller":"traceutil/trace.go:171","msg":"trace[1101416827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1869; }","duration":"1.043250613s","start":"2026-09-15T11:19:46.015397Z","end":"2026-09-15T11:19:47.058648Z","steps":["trace[1101416827] 'range keys from in-memory index tree' (duration: 1.041433335s)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.058726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:46.015365Z","time spent":"1.043344224s","remote":"127.0.0.1:41486","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-09-15T11:19:47.057009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.98317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-101d9bf8-844c-4dfc-8d77-693f9f9a2b13\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-15T11:19:47.058921Z","caller":"traceutil/trace.go:171","msg":"trace[807658724] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-101d9bf8-844c-4dfc-8d77-693f9f9a2b13; range_end:; response_count:1; response_revision:1869; }","duration":"602.93206ms","start":"2026-09-15T11:19:46.455974Z","end":"2026-09-15T11:19:47.058906Z","steps":["trace[807658724] 'range keys from in-memory index tree' (duration: 600.865448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.058966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:46.455953Z","time spent":"602.99767ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-101d9bf8-844c-4dfc-8d77-693f9f9a2b13\" "} {"level":"warn","ts":"2026-09-15T11:19:47.058872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.389304ms","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-09-15T11:19:47.05915Z","caller":"traceutil/trace.go:171","msg":"trace[1107321600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1871; }","duration":"280.691449ms","start":"2026-09-15T11:19:46.778439Z","end":"2026-09-15T11:19:47.059131Z","steps":["trace[1107321600] 'agreement among raft nodes before linearized reading' (duration: 280.186961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.057041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098592218s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:19:47.05938Z","caller":"traceutil/trace.go:171","msg":"trace[801901919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1869; }","duration":"1.100960615s","start":"2026-09-15T11:19:45.958376Z","end":"2026-09-15T11:19:47.059337Z","steps":["trace[801901919] 'range keys from in-memory index tree' (duration: 1.098496797s)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.059474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:45.958352Z","time spent":"1.101071086s","remote":"127.0.0.1:41216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-15T11:19:47.059631Z","caller":"traceutil/trace.go:171","msg":"trace[609288489] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"504.995109ms","start":"2026-09-15T11:19:46.554618Z","end":"2026-09-15T11:19:47.059613Z","steps":["trace[609288489] 'process raft request' (duration: 503.556127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.059763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:46.554583Z","time spent":"505.132232ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:19:47.13467Z","caller":"traceutil/trace.go:171","msg":"trace[1601858586] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"214.918661ms","start":"2026-09-15T11:19:46.919701Z","end":"2026-09-15T11:19:47.13462Z","steps":["trace[1601858586] 'process raft request' (duration: 214.695348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.134858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.510395ms","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-09-15T11:19:47.134954Z","caller":"traceutil/trace.go:171","msg":"trace[889331930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1872; }","duration":"236.792639ms","start":"2026-09-15T11:19:46.898139Z","end":"2026-09-15T11:19:47.134932Z","steps":["trace[889331930] 'agreement among raft nodes before linearized reading' (duration: 236.450083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.634956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5744799429296289561,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-15T11:19:47.683215Z","caller":"traceutil/trace.go:171","msg":"trace[1923598409] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"619.279755ms","start":"2026-09-15T11:19:47.063889Z","end":"2026-09-15T11:19:47.683169Z","steps":["trace[1923598409] 'process raft request' (duration: 618.96859ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:47.683315Z","caller":"traceutil/trace.go:171","msg":"trace[394750293] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"618.231579ms","start":"2026-09-15T11:19:47.065077Z","end":"2026-09-15T11:19:47.683309Z","steps":["trace[394750293] 'process raft request' (duration: 617.981515ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:47.683217Z","caller":"traceutil/trace.go:171","msg":"trace[797697000] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"613.75587ms","start":"2026-09-15T11:19:47.069431Z","end":"2026-09-15T11:19:47.683187Z","steps":["trace[797697000] 'process raft request' (duration: 613.667139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.683464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:47.065061Z","time spent":"618.29698ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:19:47.683576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:47.069414Z","time spent":"614.059384ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:19:47.684174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:47.063871Z","time spent":"619.475087ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:19:47.733196Z","caller":"traceutil/trace.go:171","msg":"trace[1159930211] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"593.417557ms","start":"2026-09-15T11:19:47.139738Z","end":"2026-09-15T11:19:47.733155Z","steps":["trace[1159930211] 'process raft request' (duration: 593.064662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.733396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:47.139711Z","time spent":"593.5648ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:19:47.733391Z","caller":"traceutil/trace.go:171","msg":"trace[2088127938] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1947; }","duration":"598.80059ms","start":"2026-09-15T11:19:47.13456Z","end":"2026-09-15T11:19:47.733361Z","steps":["trace[2088127938] 'read index received' (duration: 548.343183ms)","trace[2088127938] 'applied index is now lower than readState.Index' (duration: 50.456247ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:19:47.733604Z","caller":"traceutil/trace.go:171","msg":"trace[1069149757] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"323.491881ms","start":"2026-09-15T11:19:47.410103Z","end":"2026-09-15T11:19:47.733595Z","steps":["trace[1069149757] 'process raft request' (duration: 322.858152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.73374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:47.410071Z","time spent":"323.560032ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:19:47.733888Z","caller":"traceutil/trace.go:171","msg":"trace[1289325328] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"164.548123ms","start":"2026-09-15T11:19:47.56933Z","end":"2026-09-15T11:19:47.733878Z","steps":["trace[1289325328] 'process raft request' (duration: 163.892144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.73395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.268416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:19:47.733985Z","caller":"traceutil/trace.go:171","msg":"trace[1320672908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1879; }","duration":"673.299417ms","start":"2026-09-15T11:19:47.060674Z","end":"2026-09-15T11:19:47.733974Z","steps":["trace[1320672908] 'agreement among raft nodes before linearized reading' (duration: 673.249616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.734006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:47.060622Z","time spent":"673.380578ms","remote":"127.0.0.1:41226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-15T11:19:47.850579Z","caller":"traceutil/trace.go:171","msg":"trace[1294792782] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"117.100144ms","start":"2026-09-15T11:19:47.733461Z","end":"2026-09-15T11:19:47.850561Z","steps":["trace[1294792782] 'read index received' (duration: 112.474123ms)","trace[1294792782] 'applied index is now lower than readState.Index' (duration: 4.625411ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:19:47.850809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.698378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:19:47.850874Z","caller":"traceutil/trace.go:171","msg":"trace[378798580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1880; }","duration":"107.78116ms","start":"2026-09-15T11:19:47.743086Z","end":"2026-09-15T11:19:47.850867Z","steps":["trace[378798580] 'agreement among raft nodes before linearized reading' (duration: 107.672408ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:19:47.850796Z","caller":"traceutil/trace.go:171","msg":"trace[1172559215] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"135.573448ms","start":"2026-09-15T11:19:47.715179Z","end":"2026-09-15T11:19:47.850752Z","steps":["trace[1172559215] 'process raft request' (duration: 130.821075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.851058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.891347ms","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-09-15T11:19:47.8511Z","caller":"traceutil/trace.go:171","msg":"trace[1202303208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1880; }","duration":"500.109761ms","start":"2026-09-15T11:19:47.350979Z","end":"2026-09-15T11:19:47.851089Z","steps":["trace[1202303208] 'agreement among raft nodes before linearized reading' (duration: 499.761766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:19:47.851128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:19:47.350958Z","time spent":"500.163411ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-15T11:20:01.760337Z","caller":"traceutil/trace.go:171","msg":"trace[385590847] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"386.963425ms","start":"2026-09-15T11:20:01.373347Z","end":"2026-09-15T11:20:01.76031Z","steps":["trace[385590847] 'process raft request' (duration: 386.762902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:01.760457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:20:01.373332Z","time spent":"387.062068ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5918,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:20:17.984571Z","caller":"traceutil/trace.go:171","msg":"trace[570477303] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"235.989188ms","start":"2026-09-15T11:20:17.748557Z","end":"2026-09-15T11:20:17.984547Z","steps":["trace[570477303] 'process raft request' (duration: 235.825686ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:18.130418Z","caller":"traceutil/trace.go:171","msg":"trace[623363006] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"371.035071ms","start":"2026-09-15T11:20:17.759348Z","end":"2026-09-15T11:20:18.130383Z","steps":["trace[623363006] 'process raft request' (duration: 370.766498ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:18.130727Z","caller":"traceutil/trace.go:171","msg":"trace[1571878040] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2178; }","duration":"342.408221ms","start":"2026-09-15T11:20:17.788294Z","end":"2026-09-15T11:20:18.130702Z","steps":["trace[1571878040] 'read index received' (duration: 196.152443ms)","trace[1571878040] 'applied index is now lower than readState.Index' (duration: 146.254858ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:20:18.130758Z","caller":"traceutil/trace.go:171","msg":"trace[1290417396] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"186.977826ms","start":"2026-09-15T11:20:17.943697Z","end":"2026-09-15T11:20:18.130675Z","steps":["trace[1290417396] 'process raft request' (duration: 186.627561ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:18.130752Z","caller":"traceutil/trace.go:171","msg":"trace[723625960] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"164.627938ms","start":"2026-09-15T11:20:17.966095Z","end":"2026-09-15T11:20:18.130723Z","steps":["trace[723625960] 'process raft request' (duration: 164.494345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:18.130997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.780411ms","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-09-15T11:20:18.131033Z","caller":"traceutil/trace.go:171","msg":"trace[884564404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2100; }","duration":"262.880261ms","start":"2026-09-15T11:20:17.868144Z","end":"2026-09-15T11:20:18.131024Z","steps":["trace[884564404] 'agreement among raft nodes before linearized reading' (duration: 262.738129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:18.131096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.448587ms","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-09-15T11:20:18.131164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.866087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:20:18.131171Z","caller":"traceutil/trace.go:171","msg":"trace[1010155594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2100; }","duration":"175.533618ms","start":"2026-09-15T11:20:17.95562Z","end":"2026-09-15T11:20:18.131154Z","steps":["trace[1010155594] 'agreement among raft nodes before linearized reading' (duration: 175.326175ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:18.131183Z","caller":"traceutil/trace.go:171","msg":"trace[427120385] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2100; }","duration":"342.926407ms","start":"2026-09-15T11:20:17.788251Z","end":"2026-09-15T11:20:18.131177Z","steps":["trace[427120385] 'agreement among raft nodes before linearized reading' (duration: 342.887067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:18.131204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:20:17.788236Z","time spent":"342.962919ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-09-15T11:20:18.13132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.908294ms","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-09-15T11:20:18.131376Z","caller":"traceutil/trace.go:171","msg":"trace[1007897271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2100; }","duration":"257.012136ms","start":"2026-09-15T11:20:17.874352Z","end":"2026-09-15T11:20:18.131364Z","steps":["trace[1007897271] 'agreement among raft nodes before linearized reading' (duration: 256.630541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:18.13167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:20:17.759324Z","time spent":"371.198613ms","remote":"127.0.0.1:41486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:20:19.423648Z","caller":"traceutil/trace.go:171","msg":"trace[209482474] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"221.507508ms","start":"2026-09-15T11:20:19.202111Z","end":"2026-09-15T11:20:19.423619Z","steps":["trace[209482474] 'process raft request' (duration: 221.357356ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:19.437131Z","caller":"traceutil/trace.go:171","msg":"trace[1420824013] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"153.892965ms","start":"2026-09-15T11:20:19.283218Z","end":"2026-09-15T11:20:19.437111Z","steps":["trace[1420824013] 'read index received' (duration: 140.397458ms)","trace[1420824013] 'applied index is now lower than readState.Index' (duration: 13.494587ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:20:19.437246Z","caller":"traceutil/trace.go:171","msg":"trace[1028308217] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"168.830855ms","start":"2026-09-15T11:20:19.268402Z","end":"2026-09-15T11:20:19.437233Z","steps":["trace[1028308217] 'process raft request' (duration: 168.586102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:19.437401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.16538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d57a969f52c2d2\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-09-15T11:20:19.437471Z","caller":"traceutil/trace.go:171","msg":"trace[650660003] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d57a969f52c2d2; range_end:; response_count:1; response_revision:2107; }","duration":"154.284531ms","start":"2026-09-15T11:20:19.283172Z","end":"2026-09-15T11:20:19.437456Z","steps":["trace[650660003] 'agreement among raft nodes before linearized reading' (duration: 154.111289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:19.43746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.991507ms","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-09-15T11:20:19.437519Z","caller":"traceutil/trace.go:171","msg":"trace[911266032] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2107; }","duration":"154.107609ms","start":"2026-09-15T11:20:19.283395Z","end":"2026-09-15T11:20:19.437503Z","steps":["trace[911266032] 'agreement among raft nodes before linearized reading' (duration: 153.876766ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.102006Z","caller":"traceutil/trace.go:171","msg":"trace[1049823619] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"270.533275ms","start":"2026-09-15T11:20:19.831439Z","end":"2026-09-15T11:20:20.101973Z","steps":["trace[1049823619] 'read index received' (duration: 270.374322ms)","trace[1049823619] 'applied index is now lower than readState.Index' (duration: 157.263µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:20:20.102383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.475676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:20:20.102499Z","caller":"traceutil/trace.go:171","msg":"trace[867483133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"144.638059ms","start":"2026-09-15T11:20:19.957844Z","end":"2026-09-15T11:20:20.102482Z","steps":["trace[867483133] 'agreement among raft nodes before linearized reading' (duration: 144.457446ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:20.102378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.903811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-09-15T11:20:20.102625Z","caller":"traceutil/trace.go:171","msg":"trace[700933569] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2108; }","duration":"271.207014ms","start":"2026-09-15T11:20:19.831396Z","end":"2026-09-15T11:20:20.102603Z","steps":["trace[700933569] 'agreement among raft nodes before linearized reading' (duration: 270.738568ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.214912Z","caller":"traceutil/trace.go:171","msg":"trace[1157107509] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"111.32893ms","start":"2026-09-15T11:20:20.103561Z","end":"2026-09-15T11:20:20.21489Z","steps":["trace[1157107509] 'read index received' (duration: 79.95163ms)","trace[1157107509] 'applied index is now lower than readState.Index' (duration: 31.37671ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:20:20.214964Z","caller":"traceutil/trace.go:171","msg":"trace[46916977] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"111.584094ms","start":"2026-09-15T11:20:20.103345Z","end":"2026-09-15T11:20:20.214929Z","steps":["trace[46916977] 'process raft request' (duration: 80.138334ms)","trace[46916977] 'compare' (duration: 31.288808ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:20:20.215052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.480151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:20:20.215074Z","caller":"traceutil/trace.go:171","msg":"trace[214352147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2109; }","duration":"111.549883ms","start":"2026-09-15T11:20:20.103516Z","end":"2026-09-15T11:20:20.215066Z","steps":["trace[214352147] 'agreement among raft nodes before linearized reading' (duration: 111.481252ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.369908Z","caller":"traceutil/trace.go:171","msg":"trace[1130945958] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"232.378112ms","start":"2026-09-15T11:20:20.137493Z","end":"2026-09-15T11:20:20.369871Z","steps":["trace[1130945958] 'process raft request' (duration: 232.100758ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.370054Z","caller":"traceutil/trace.go:171","msg":"trace[1718101715] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2192; }","duration":"155.04313ms","start":"2026-09-15T11:20:20.214973Z","end":"2026-09-15T11:20:20.370016Z","steps":["trace[1718101715] 'read index received' (duration: 154.533293ms)","trace[1718101715] 'applied index is now lower than readState.Index' (duration: 507.647µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:20:20.370248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.173249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-15T11:20:20.370296Z","caller":"traceutil/trace.go:171","msg":"trace[1450905529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2111; }","duration":"193.2479ms","start":"2026-09-15T11:20:20.177034Z","end":"2026-09-15T11:20:20.370282Z","steps":["trace[1450905529] 'agreement among raft nodes before linearized reading' (duration: 193.016897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:20.370317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.178103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-09-15T11:20:20.37034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.126082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-15T11:20:20.370361Z","caller":"traceutil/trace.go:171","msg":"trace[1018893461] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2111; }","duration":"153.309754ms","start":"2026-09-15T11:20:20.21704Z","end":"2026-09-15T11:20:20.37035Z","steps":["trace[1018893461] 'agreement among raft nodes before linearized reading' (duration: 153.187212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:20.370363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.335541ms","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-09-15T11:20:20.370421Z","caller":"traceutil/trace.go:171","msg":"trace[1374285648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2111; }","duration":"193.421053ms","start":"2026-09-15T11:20:20.176983Z","end":"2026-09-15T11:20:20.370404Z","steps":["trace[1374285648] 'agreement among raft nodes before linearized reading' (duration: 193.188469ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.370441Z","caller":"traceutil/trace.go:171","msg":"trace[683205145] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"232.394493ms","start":"2026-09-15T11:20:20.138035Z","end":"2026-09-15T11:20:20.37043Z","steps":["trace[683205145] 'process raft request' (duration: 231.788295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:20.37051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.475333ms","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-09-15T11:20:20.370378Z","caller":"traceutil/trace.go:171","msg":"trace[2125226984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2111; }","duration":"209.216533ms","start":"2026-09-15T11:20:20.161149Z","end":"2026-09-15T11:20:20.370366Z","steps":["trace[2125226984] 'agreement among raft nodes before linearized reading' (duration: 209.01589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:20.370554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.450513ms","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-09-15T11:20:20.370562Z","caller":"traceutil/trace.go:171","msg":"trace[1181573392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2111; }","duration":"193.553324ms","start":"2026-09-15T11:20:20.176997Z","end":"2026-09-15T11:20:20.37055Z","steps":["trace[1181573392] 'agreement among raft nodes before linearized reading' (duration: 193.401512ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.370591Z","caller":"traceutil/trace.go:171","msg":"trace[1569875867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2111; }","duration":"193.480033ms","start":"2026-09-15T11:20:20.177094Z","end":"2026-09-15T11:20:20.370574Z","steps":["trace[1569875867] 'agreement among raft nodes before linearized reading' (duration: 193.395532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:20.370644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.233211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:20:20.370674Z","caller":"traceutil/trace.go:171","msg":"trace[1617067661] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2111; }","duration":"258.289781ms","start":"2026-09-15T11:20:20.112375Z","end":"2026-09-15T11:20:20.370665Z","steps":["trace[1617067661] 'agreement among raft nodes before linearized reading' (duration: 258.234451ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.604278Z","caller":"traceutil/trace.go:171","msg":"trace[544416548] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"228.244602ms","start":"2026-09-15T11:20:20.375998Z","end":"2026-09-15T11:20:20.604243Z","steps":["trace[544416548] 'process raft request' (duration: 225.449521ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.604313Z","caller":"traceutil/trace.go:171","msg":"trace[241923814] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"228.07013ms","start":"2026-09-15T11:20:20.37622Z","end":"2026-09-15T11:20:20.60429Z","steps":["trace[241923814] 'process raft request' (duration: 227.952228ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.604574Z","caller":"traceutil/trace.go:171","msg":"trace[2001758423] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2194; }","duration":"220.359316ms","start":"2026-09-15T11:20:20.384192Z","end":"2026-09-15T11:20:20.604551Z","steps":["trace[2001758423] 'read index received' (duration: 31.981538ms)","trace[2001758423] 'applied index is now lower than readState.Index' (duration: 188.376228ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:20:20.604613Z","caller":"traceutil/trace.go:171","msg":"trace[1691408170] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"227.222968ms","start":"2026-09-15T11:20:20.377366Z","end":"2026-09-15T11:20:20.604589Z","steps":["trace[1691408170] 'process raft request' (duration: 226.860293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:20.60493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.831811ms","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-09-15T11:20:20.605022Z","caller":"traceutil/trace.go:171","msg":"trace[1926714906] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2116; }","duration":"159.994524ms","start":"2026-09-15T11:20:20.445012Z","end":"2026-09-15T11:20:20.605007Z","steps":["trace[1926714906] 'agreement among raft nodes before linearized reading' (duration: 159.786081ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:20:20.604301Z","caller":"traceutil/trace.go:171","msg":"trace[1769373201] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"228.151431ms","start":"2026-09-15T11:20:20.376104Z","end":"2026-09-15T11:20:20.604255Z","steps":["trace[1769373201] 'process raft request' (duration: 228.003819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:20:20.606619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.316061ms","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-09-15T11:20:20.606739Z","caller":"traceutil/trace.go:171","msg":"trace[1046403567] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2116; }","duration":"222.593789ms","start":"2026-09-15T11:20:20.384118Z","end":"2026-09-15T11:20:20.606712Z","steps":["trace[1046403567] 'agreement among raft nodes before linearized reading' (duration: 220.535009ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:24.97989Z","caller":"traceutil/trace.go:171","msg":"trace[1390249309] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"110.967244ms","start":"2026-09-15T11:22:24.868892Z","end":"2026-09-15T11:22:24.979859Z","steps":["trace[1390249309] 'process raft request' (duration: 110.750568ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:24.982619Z","caller":"traceutil/trace.go:171","msg":"trace[692201136] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"112.094938ms","start":"2026-09-15T11:22:24.870478Z","end":"2026-09-15T11:22:24.982573Z","steps":["trace[692201136] 'process raft request' (duration: 111.457838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:25.114693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.61594ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-15T11:26:50.667249Z","caller":"traceutil/trace.go:171","msg":"trace[22540461] linearizableReadLoop","detail":"{readStateIndex:5032; appliedIndex:5031; }","duration":"196.969025ms","start":"2026-09-15T11:26:50.470249Z","end":"2026-09-15T11:26:50.667218Z","steps":["trace[22540461] 'read index received' (duration: 196.619478ms)","trace[22540461] 'applied index is now lower than readState.Index' (duration: 348.027µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:26:50.667324Z","caller":"traceutil/trace.go:171","msg":"trace[594353628] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"198.687208ms","start":"2026-09-15T11:26:50.468587Z","end":"2026-09-15T11:26:50.667275Z","steps":["trace[594353628] 'process raft request' (duration: 198.344681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:26:50.667549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.272622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-15T11:26:50.667602Z","caller":"traceutil/trace.go:171","msg":"trace[1469379887] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4842; }","duration":"197.436315ms","start":"2026-09-15T11:26:50.470155Z","end":"2026-09-15T11:26:50.667592Z","steps":["trace[1469379887] 'agreement among raft nodes before linearized reading' (duration: 197.20105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:26:50.667885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.930405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-15T11:26:50.667953Z","caller":"traceutil/trace.go:171","msg":"trace[130169993] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4842; }","duration":"158.061748ms","start":"2026-09-15T11:26:50.509872Z","end":"2026-09-15T11:26:50.667934Z","steps":["trace[130169993] 'agreement among raft nodes before linearized reading' (duration: 157.694701ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:00.894384Z","caller":"traceutil/trace.go:171","msg":"trace[1091825071] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"106.869157ms","start":"2026-09-15T11:27:00.787488Z","end":"2026-09-15T11:27:00.894357Z","steps":["trace[1091825071] 'process raft request' (duration: 34.769702ms)","trace[1091825071] 'compare' (duration: 71.638326ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:27:02.544622Z","caller":"traceutil/trace.go:171","msg":"trace[94995030] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"244.429502ms","start":"2026-09-15T11:27:02.300175Z","end":"2026-09-15T11:27:02.544605Z","steps":["trace[94995030] 'process raft request' (duration: 219.614153ms)","trace[94995030] 'compare' (duration: 24.599795ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:27:02.699055Z","caller":"traceutil/trace.go:171","msg":"trace[1240445769] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"150.848656ms","start":"2026-09-15T11:27:02.548187Z","end":"2026-09-15T11:27:02.699035Z","steps":["trace[1240445769] 'process raft request' (duration: 119.851637ms)","trace[1240445769] 'compare' (duration: 30.883177ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:27:02.699048Z","caller":"traceutil/trace.go:171","msg":"trace[1844959566] linearizableReadLoop","detail":"{readStateIndex:5245; appliedIndex:5244; }","duration":"148.642982ms","start":"2026-09-15T11:27:02.550382Z","end":"2026-09-15T11:27:02.699025Z","steps":["trace[1844959566] 'read index received' (duration: 117.837987ms)","trace[1844959566] 'applied index is now lower than readState.Index' (duration: 30.803955ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:27:02.699259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.855026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-15T11:27:02.699316Z","caller":"traceutil/trace.go:171","msg":"trace[539831060] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5047; }","duration":"148.948838ms","start":"2026-09-15T11:27:02.550351Z","end":"2026-09-15T11:27:02.6993Z","steps":["trace[539831060] 'agreement among raft nodes before linearized reading' (duration: 148.742414ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:02.721097Z","caller":"traceutil/trace.go:171","msg":"trace[1171329355] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"159.096764ms","start":"2026-09-15T11:27:02.561977Z","end":"2026-09-15T11:27:02.721073Z","steps":["trace[1171329355] 'process raft request' (duration: 158.948911ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:03.04303Z","caller":"traceutil/trace.go:171","msg":"trace[1453780459] linearizableReadLoop","detail":"{readStateIndex:5258; appliedIndex:5257; }","duration":"107.756481ms","start":"2026-09-15T11:27:02.935241Z","end":"2026-09-15T11:27:03.042997Z","steps":["trace[1453780459] 'read index received' (duration: 97.682907ms)","trace[1453780459] 'applied index is now lower than readState.Index' (duration: 10.072214ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:27:03.043191Z","caller":"traceutil/trace.go:171","msg":"trace[2027498924] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"139.489584ms","start":"2026-09-15T11:27:02.903691Z","end":"2026-09-15T11:27:03.043181Z","steps":["trace[2027498924] 'process raft request' (duration: 129.266596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:27:03.043832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.571228ms","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-09-15T11:27:03.044027Z","caller":"traceutil/trace.go:171","msg":"trace[229182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5060; }","duration":"108.755141ms","start":"2026-09-15T11:27:02.935196Z","end":"2026-09-15T11:27:03.043951Z","steps":["trace[229182] 'agreement among raft nodes before linearized reading' (duration: 108.523856ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:03.198416Z","caller":"traceutil/trace.go:171","msg":"trace[1411573459] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"147.838425ms","start":"2026-09-15T11:27:03.050555Z","end":"2026-09-15T11:27:03.198394Z","steps":["trace[1411573459] 'process raft request' (duration: 147.735083ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:03.198432Z","caller":"traceutil/trace.go:171","msg":"trace[141021595] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"149.319543ms","start":"2026-09-15T11:27:03.049081Z","end":"2026-09-15T11:27:03.198401Z","steps":["trace[141021595] 'process raft request' (duration: 148.385565ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:03.198811Z","caller":"traceutil/trace.go:171","msg":"trace[968273034] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"138.592046ms","start":"2026-09-15T11:27:03.060134Z","end":"2026-09-15T11:27:03.198726Z","steps":["trace[968273034] 'process raft request' (duration: 138.218448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:27:05.926937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.079215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fd349ef8-38b2-464d-8aab-82f646432626\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-15T11:27:05.926999Z","caller":"traceutil/trace.go:171","msg":"trace[1395661234] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fd349ef8-38b2-464d-8aab-82f646432626; range_end:; response_count:1; response_revision:5141; }","duration":"107.208767ms","start":"2026-09-15T11:27:05.81978Z","end":"2026-09-15T11:27:05.926989Z","steps":["trace[1395661234] 'range keys from in-memory index tree' (duration: 106.949342ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:07.817703Z","caller":"traceutil/trace.go:171","msg":"trace[1982331934] linearizableReadLoop","detail":"{readStateIndex:5381; appliedIndex:5380; }","duration":"203.859618ms","start":"2026-09-15T11:27:07.613819Z","end":"2026-09-15T11:27:07.817679Z","steps":["trace[1982331934] 'read index received' (duration: 165.556719ms)","trace[1982331934] 'applied index is now lower than readState.Index' (duration: 38.301979ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:27:07.817907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.068441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-15T11:27:07.817945Z","caller":"traceutil/trace.go:171","msg":"trace[1442187832] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5181; }","duration":"204.132032ms","start":"2026-09-15T11:27:07.613801Z","end":"2026-09-15T11:27:07.817933Z","steps":["trace[1442187832] 'agreement among raft nodes before linearized reading' (duration: 203.957109ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:27:07.818233Z","caller":"traceutil/trace.go:171","msg":"trace[2077297463] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"211.059006ms","start":"2026-09-15T11:27:07.60716Z","end":"2026-09-15T11:27:07.818219Z","steps":["trace[2077297463] 'process raft request' (duration: 172.313519ms)","trace[2077297463] 'compare' (duration: 38.081624ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:27:16.372961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2835} {"level":"info","ts":"2026-09-15T11:27:16.471923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2835,"took":"97.54365ms","hash":2461376760,"current-db-size-bytes":52948992,"current-db-size":"53 MB","current-db-size-in-use-bytes":27631616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-15T11:27:16.471995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2461376760,"revision":2835,"compact-revision":-1} {"level":"info","ts":"2026-09-15T11:32:16.384125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5295} {"level":"info","ts":"2026-09-15T11:32:16.562248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5295,"took":"176.818883ms","hash":2541274090,"current-db-size-bytes":52948992,"current-db-size":"53 MB","current-db-size-in-use-bytes":19345408,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-15T11:32:16.562361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2541274090,"revision":5295,"compact-revision":2835}