{"level":"warn","ts":"2026-04-15T15:13:10.447525Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T15:13:10.447676Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.244:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.244:2380","--initial-cluster=instance=https://199.19.213.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.244: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-04-15T15:13:10.447772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-15T15:13:10.447791Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-04-15T15:13:10.447813Z","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-04-15T15:13:10.448673Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"]} {"level":"info","ts":"2026-04-15T15:13:10.448784Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.244: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-04-15T15:13:10.453307Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.170492ms"} {"level":"info","ts":"2026-04-15T15:13:10.46408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95a1d32286abc938","cluster-id":"f9243fb70f4df4f4"} {"level":"info","ts":"2026-04-15T15:13:10.464176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T15:13:10.464203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 0"} {"level":"info","ts":"2026-04-15T15:13:10.464217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95a1d32286abc938 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T15:13:10.464237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 1"} {"level":"info","ts":"2026-04-15T15:13:10.464282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"warn","ts":"2026-04-15T15:13:10.475499Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T15:13:10.476885Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T15:13:10.478458Z","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-04-15T15:13:10.48076Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95a1d32286abc938","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T15:13:10.481002Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95a1d32286abc938","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T15:13:10.481078Z","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-04-15T15:13:10.481682Z","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-04-15T15:13:10.481706Z","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-04-15T15:13:10.483022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"info","ts":"2026-04-15T15:13:10.483763Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","added-peer-id":"95a1d32286abc938","added-peer-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-04-15T15:13:10.486063Z","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-04-15T15:13:10.486402Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95a1d32286abc938","initial-advertise-peer-urls":["https://199.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T15:13:10.48648Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-04-15T15:13:10.486546Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-04-15T15:13:10.486527Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T15:13:10.566231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T15:13:10.566304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T15:13:10.566328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgPreVoteResp from 95a1d32286abc938 at term 1"} {"level":"info","ts":"2026-04-15T15:13:10.56634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became candidate at term 2"} {"level":"info","ts":"2026-04-15T15:13:10.566346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgVoteResp from 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-04-15T15:13:10.566356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became leader at term 2"} {"level":"info","ts":"2026-04-15T15:13:10.566363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95a1d32286abc938 elected leader 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-04-15T15:13:10.574434Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T15:13:10.578565Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T15:13:10.578575Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95a1d32286abc938","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.244:2379]}","request-path":"/0/members/95a1d32286abc938/attributes","cluster-id":"f9243fb70f4df4f4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T15:13:10.578644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T15:13:10.578958Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T15:13:10.579056Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T15:13:10.579121Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T15:13:10.57924Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T15:13:10.579343Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T15:13:10.581039Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T15:13:10.582538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.244:2379"} {"level":"info","ts":"2026-04-15T15:13:25.675478Z","caller":"traceutil/trace.go:171","msg":"trace[1791177614] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"183.641491ms","start":"2026-04-15T15:13:25.491798Z","end":"2026-04-15T15:13:25.67544Z","steps":["trace[1791177614] 'process raft request' (duration: 183.434225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:13:26.335313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.761773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-15T15:13:26.335378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.486361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:13:26.335445Z","caller":"traceutil/trace.go:171","msg":"trace[878883626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"213.911727ms","start":"2026-04-15T15:13:26.121512Z","end":"2026-04-15T15:13:26.335424Z","steps":["trace[878883626] 'range keys from in-memory index tree' (duration: 213.627678ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:13:26.335481Z","caller":"traceutil/trace.go:171","msg":"trace[171675966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"198.655635ms","start":"2026-04-15T15:13:26.136801Z","end":"2026-04-15T15:13:26.335457Z","steps":["trace[171675966] 'range keys from in-memory index tree' (duration: 198.388338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:13:26.335591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.445543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4379"} {"level":"info","ts":"2026-04-15T15:13:26.335703Z","caller":"traceutil/trace.go:171","msg":"trace[100821866] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:405; }","duration":"323.615887ms","start":"2026-04-15T15:13:26.012061Z","end":"2026-04-15T15:13:26.335676Z","steps":["trace[100821866] 'range keys from in-memory index tree' (duration: 323.248688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:13:26.335756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:13:26.012025Z","time spent":"323.72174ms","remote":"127.0.0.1:39424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4403,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-15T15:13:26.335831Z","caller":"traceutil/trace.go:171","msg":"trace[431161003] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"170.520889ms","start":"2026-04-15T15:13:26.165293Z","end":"2026-04-15T15:13:26.335814Z","steps":["trace[431161003] 'process raft request' (duration: 145.155046ms)","trace[431161003] 'compare' (duration: 24.924801ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:13:26.660047Z","caller":"traceutil/trace.go:171","msg":"trace[1314567127] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"271.992959ms","start":"2026-04-15T15:13:26.38801Z","end":"2026-04-15T15:13:26.660003Z","steps":["trace[1314567127] 'read index received' (duration: 265.452163ms)","trace[1314567127] 'applied index is now lower than readState.Index' (duration: 6.539656ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:13:26.660192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.166973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:13:26.660218Z","caller":"traceutil/trace.go:171","msg":"trace[1738079716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"272.263635ms","start":"2026-04-15T15:13:26.387945Z","end":"2026-04-15T15:13:26.660209Z","steps":["trace[1738079716] 'agreement among raft nodes before linearized reading' (duration: 272.173773ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:13:26.660385Z","caller":"traceutil/trace.go:171","msg":"trace[1480251102] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"291.462482ms","start":"2026-04-15T15:13:26.368891Z","end":"2026-04-15T15:13:26.660354Z","steps":["trace[1480251102] 'process raft request' (duration: 284.604728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:13:46.088886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.117414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T15:13:46.089176Z","caller":"traceutil/trace.go:171","msg":"trace[741393011] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"288.938986ms","start":"2026-04-15T15:13:45.800199Z","end":"2026-04-15T15:13:46.089138Z","steps":["trace[741393011] 'process raft request' (duration: 183.003533ms)","trace[741393011] 'compare' (duration: 104.771076ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:13:46.347605Z","caller":"traceutil/trace.go:171","msg":"trace[1301868772] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"236.387719ms","start":"2026-04-15T15:13:46.111182Z","end":"2026-04-15T15:13:46.347569Z","steps":["trace[1301868772] 'process raft request' (duration: 236.071872ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:13:46.451759Z","caller":"traceutil/trace.go:171","msg":"trace[1508501528] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"306.160765ms","start":"2026-04-15T15:13:46.145557Z","end":"2026-04-15T15:13:46.451717Z","steps":["trace[1508501528] 'process raft request' (duration: 236.464542ms)","trace[1508501528] 'compare' (duration: 69.492309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:13:46.452116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:13:46.145539Z","time spent":"306.323909ms","remote":"127.0.0.1:39608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T15:13:47.352045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.003408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T15:13:47.352246Z","caller":"traceutil/trace.go:171","msg":"trace[166326413] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"478.426783ms","start":"2026-04-15T15:13:46.873752Z","end":"2026-04-15T15:13:47.352178Z","steps":["trace[166326413] 'read index received' (duration: 111.273211ms)","trace[166326413] 'applied index is now lower than readState.Index' (duration: 367.151442ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:13:47.352245Z","caller":"traceutil/trace.go:171","msg":"trace[1857989246] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"727.682007ms","start":"2026-04-15T15:13:46.624511Z","end":"2026-04-15T15:13:47.352193Z","steps":["trace[1857989246] 'process raft request' (duration: 360.402792ms)","trace[1857989246] 'compare' (duration: 366.153569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:13:47.352447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:13:46.624484Z","time spent":"727.862142ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:13:47.352487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.72389ms","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-04-15T15:13:47.352536Z","caller":"traceutil/trace.go:171","msg":"trace[1605041805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"478.775061ms","start":"2026-04-15T15:13:46.873744Z","end":"2026-04-15T15:13:47.352519Z","steps":["trace[1605041805] 'agreement among raft nodes before linearized reading' (duration: 478.553706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:13:47.352593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:13:46.873663Z","time spent":"478.918684ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T15:13:47.37832Z","caller":"traceutil/trace.go:171","msg":"trace[1294182027] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"503.180891ms","start":"2026-04-15T15:13:46.875099Z","end":"2026-04-15T15:13:47.37828Z","steps":["trace[1294182027] 'process raft request' (duration: 502.74196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:13:47.378458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:13:46.875082Z","time spent":"503.314863ms","remote":"127.0.0.1:39424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:13:47.379987Z","caller":"traceutil/trace.go:171","msg":"trace[1185901777] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"467.253328ms","start":"2026-04-15T15:13:46.912711Z","end":"2026-04-15T15:13:47.379965Z","steps":["trace[1185901777] 'process raft request' (duration: 466.203613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:13:47.380227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:13:46.912677Z","time spent":"467.452842ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:14:02.80591Z","caller":"traceutil/trace.go:171","msg":"trace[1185632874] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"186.535307ms","start":"2026-04-15T15:14:02.619343Z","end":"2026-04-15T15:14:02.805879Z","steps":["trace[1185632874] 'process raft request' (duration: 169.124924ms)","trace[1185632874] 'compare' (duration: 17.247449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:14:29.308122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.953666ms","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-04-15T15:14:29.308256Z","caller":"traceutil/trace.go:171","msg":"trace[1327117437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"122.11185ms","start":"2026-04-15T15:14:29.186119Z","end":"2026-04-15T15:14:29.308231Z","steps":["trace[1327117437] 'range keys from in-memory index tree' (duration: 121.680518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:16.627364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.114704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T15:15:16.627805Z","caller":"traceutil/trace.go:171","msg":"trace[1645628660] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"190.995273ms","start":"2026-04-15T15:15:16.436786Z","end":"2026-04-15T15:15:16.627781Z","steps":["trace[1645628660] 'process raft request' (duration: 90.349945ms)","trace[1645628660] 'compare' (duration: 99.896678ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:18.944136Z","caller":"traceutil/trace.go:171","msg":"trace[2135100634] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"310.539791ms","start":"2026-04-15T15:15:18.633566Z","end":"2026-04-15T15:15:18.944106Z","steps":["trace[2135100634] 'read index received' (duration: 310.405627ms)","trace[2135100634] 'applied index is now lower than readState.Index' (duration: 132.374µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:18.944274Z","caller":"traceutil/trace.go:171","msg":"trace[930927460] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"344.314906ms","start":"2026-04-15T15:15:18.599915Z","end":"2026-04-15T15:15:18.94423Z","steps":["trace[930927460] 'process raft request' (duration: 344.014498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:18.944383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.790198ms","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-04-15T15:15:18.94442Z","caller":"traceutil/trace.go:171","msg":"trace[1042756060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1858; }","duration":"310.921301ms","start":"2026-04-15T15:15:18.633491Z","end":"2026-04-15T15:15:18.944412Z","steps":["trace[1042756060] 'agreement among raft nodes before linearized reading' (duration: 310.755647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:18.944446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:15:18.633463Z","time spent":"310.978203ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T15:15:18.944494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:15:18.599887Z","time spent":"344.490751ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:15:18.97267Z","caller":"traceutil/trace.go:171","msg":"trace[1594206662] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"338.613045ms","start":"2026-04-15T15:15:18.634035Z","end":"2026-04-15T15:15:18.972648Z","steps":["trace[1594206662] 'process raft request' (duration: 338.42524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:18.972822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:15:18.634016Z","time spent":"338.728688ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:15:18.973109Z","caller":"traceutil/trace.go:171","msg":"trace[38616305] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"194.769882ms","start":"2026-04-15T15:15:18.778298Z","end":"2026-04-15T15:15:18.973068Z","steps":["trace[38616305] 'process raft request' (duration: 194.302349ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:19.374123Z","caller":"traceutil/trace.go:171","msg":"trace[2031439919] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"250.919732ms","start":"2026-04-15T15:15:19.123162Z","end":"2026-04-15T15:15:19.374082Z","steps":["trace[2031439919] 'process raft request' (duration: 238.542653ms)","trace[2031439919] 'compare' (duration: 12.206794ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:15:19.378357Z","caller":"traceutil/trace.go:171","msg":"trace[1556771676] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1934; }","duration":"226.949426ms","start":"2026-04-15T15:15:19.15138Z","end":"2026-04-15T15:15:19.378329Z","steps":["trace[1556771676] 'read index received' (duration: 210.336396ms)","trace[1556771676] 'applied index is now lower than readState.Index' (duration: 16.60897ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:15:19.378648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.480862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-15T15:15:19.378705Z","caller":"traceutil/trace.go:171","msg":"trace[699804654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1864; }","duration":"152.582935ms","start":"2026-04-15T15:15:19.226107Z","end":"2026-04-15T15:15:19.378689Z","steps":["trace[699804654] 'agreement among raft nodes before linearized reading' (duration: 152.386459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:15:19.378701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.289786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a210653-10ab-4de0-9c40-d41d5f50460d\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-15T15:15:19.378776Z","caller":"traceutil/trace.go:171","msg":"trace[1106904744] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a210653-10ab-4de0-9c40-d41d5f50460d; range_end:; response_count:1; response_revision:1864; }","duration":"227.439079ms","start":"2026-04-15T15:15:19.151319Z","end":"2026-04-15T15:15:19.378759Z","steps":["trace[1106904744] 'agreement among raft nodes before linearized reading' (duration: 227.157642ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:15:32.231361Z","caller":"traceutil/trace.go:171","msg":"trace[2048016106] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"233.459599ms","start":"2026-04-15T15:15:31.997854Z","end":"2026-04-15T15:15:32.231314Z","steps":["trace[2048016106] 'process raft request' (duration: 232.948885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:08.797132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.507596ms","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-04-15T15:16:08.797242Z","caller":"traceutil/trace.go:171","msg":"trace[1476778852] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2181; }","duration":"111.693182ms","start":"2026-04-15T15:16:08.685527Z","end":"2026-04-15T15:16:08.79722Z","steps":["trace[1476778852] 'range keys from in-memory index tree' (duration: 111.2893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:09.540554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.873254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T15:16:09.541001Z","caller":"traceutil/trace.go:171","msg":"trace[2099319229] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2265; }","duration":"205.577706ms","start":"2026-04-15T15:16:09.33538Z","end":"2026-04-15T15:16:09.540958Z","steps":["trace[2099319229] 'read index received' (duration: 11.047785ms)","trace[2099319229] 'applied index is now lower than readState.Index' (duration: 194.525121ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:16:09.541037Z","caller":"traceutil/trace.go:171","msg":"trace[762925592] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"328.98877ms","start":"2026-04-15T15:16:09.211975Z","end":"2026-04-15T15:16:09.540964Z","steps":["trace[762925592] 'process raft request' (duration: 134.585992ms)","trace[762925592] 'compare' (duration: 193.657589ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:16:09.541001Z","caller":"traceutil/trace.go:171","msg":"trace[1389316404] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"312.681215ms","start":"2026-04-15T15:16:09.228277Z","end":"2026-04-15T15:16:09.540958Z","steps":["trace[1389316404] 'process raft request' (duration: 312.424908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:09.541316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:16:09.228242Z","time spent":"312.951653ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:16:09.541319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:16:09.211961Z","time spent":"329.170765ms","remote":"127.0.0.1:39518","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-04-15T15:16:09.541341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.779397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:16:09.54146Z","caller":"traceutil/trace.go:171","msg":"trace[1852513994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2184; }","duration":"152.903901ms","start":"2026-04-15T15:16:09.388492Z","end":"2026-04-15T15:16:09.541396Z","steps":["trace[1852513994] 'agreement among raft nodes before linearized reading' (duration: 152.762917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:09.541377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.973727ms","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-04-15T15:16:09.541582Z","caller":"traceutil/trace.go:171","msg":"trace[1325826773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2184; }","duration":"206.236944ms","start":"2026-04-15T15:16:09.335318Z","end":"2026-04-15T15:16:09.541555Z","steps":["trace[1325826773] 'agreement among raft nodes before linearized reading' (duration: 205.71476ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:16:15.429498Z","caller":"traceutil/trace.go:171","msg":"trace[638471757] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2296; }","duration":"159.905608ms","start":"2026-04-15T15:16:15.269564Z","end":"2026-04-15T15:16:15.429469Z","steps":["trace[638471757] 'read index received' (duration: 159.683942ms)","trace[638471757] 'applied index is now lower than readState.Index' (duration: 220.316µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:16:15.429562Z","caller":"traceutil/trace.go:171","msg":"trace[1761110038] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"172.276568ms","start":"2026-04-15T15:16:15.25725Z","end":"2026-04-15T15:16:15.429526Z","steps":["trace[1761110038] 'process raft request' (duration: 172.040942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:15.429721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.126284ms","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-04-15T15:16:15.429832Z","caller":"traceutil/trace.go:171","msg":"trace[934308728] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2213; }","duration":"160.301639ms","start":"2026-04-15T15:16:15.269505Z","end":"2026-04-15T15:16:15.429807Z","steps":["trace[934308728] 'agreement among raft nodes before linearized reading' (duration: 160.113874ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:16:15.749538Z","caller":"traceutil/trace.go:171","msg":"trace[92988528] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"189.050316ms","start":"2026-04-15T15:16:15.560454Z","end":"2026-04-15T15:16:15.749505Z","steps":["trace[92988528] 'process raft request' (duration: 188.865141ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:16:15.898635Z","caller":"traceutil/trace.go:171","msg":"trace[993992968] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"336.956913ms","start":"2026-04-15T15:16:15.561647Z","end":"2026-04-15T15:16:15.898604Z","steps":["trace[993992968] 'process raft request' (duration: 334.051796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:15.898969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:16:15.561622Z","time spent":"337.176129ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:16:15.899096Z","caller":"traceutil/trace.go:171","msg":"trace[2102453157] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"267.771317ms","start":"2026-04-15T15:16:15.631291Z","end":"2026-04-15T15:16:15.899063Z","steps":["trace[2102453157] 'process raft request' (duration: 267.248153ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:16:15.899099Z","caller":"traceutil/trace.go:171","msg":"trace[202081797] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2297; }","duration":"284.133633ms","start":"2026-04-15T15:16:15.614888Z","end":"2026-04-15T15:16:15.899021Z","steps":["trace[202081797] 'read index received' (duration: 134.579822ms)","trace[202081797] 'applied index is now lower than readState.Index' (duration: 149.551531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:16:15.899709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.76848ms","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-04-15T15:16:15.899851Z","caller":"traceutil/trace.go:171","msg":"trace[1407137221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2216; }","duration":"284.987966ms","start":"2026-04-15T15:16:15.614829Z","end":"2026-04-15T15:16:15.899817Z","steps":["trace[1407137221] 'agreement among raft nodes before linearized reading' (duration: 284.38665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:15.900363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.552922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-15T15:16:15.900405Z","caller":"traceutil/trace.go:171","msg":"trace[650463941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2217; }","duration":"179.655485ms","start":"2026-04-15T15:16:15.720739Z","end":"2026-04-15T15:16:15.900394Z","steps":["trace[650463941] 'agreement among raft nodes before linearized reading' (duration: 179.500051ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:16:15.900541Z","caller":"traceutil/trace.go:171","msg":"trace[1053543454] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"103.803961ms","start":"2026-04-15T15:16:15.796728Z","end":"2026-04-15T15:16:15.900532Z","steps":["trace[1053543454] 'process raft request' (duration: 103.38789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:15.900925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.638574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-15T15:16:15.900994Z","caller":"traceutil/trace.go:171","msg":"trace[1260722170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2217; }","duration":"179.778698ms","start":"2026-04-15T15:16:15.7212Z","end":"2026-04-15T15:16:15.900979Z","steps":["trace[1260722170] 'agreement among raft nodes before linearized reading' (duration: 179.49955ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:16:50.987562Z","caller":"traceutil/trace.go:171","msg":"trace[179793086] linearizableReadLoop","detail":"{readStateIndex:2682; appliedIndex:2681; }","duration":"114.474194ms","start":"2026-04-15T15:16:50.873064Z","end":"2026-04-15T15:16:50.987538Z","steps":["trace[179793086] 'read index received' (duration: 114.395712ms)","trace[179793086] 'applied index is now lower than readState.Index' (duration: 77.312µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:16:50.987621Z","caller":"traceutil/trace.go:171","msg":"trace[2000550048] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"130.806047ms","start":"2026-04-15T15:16:50.856783Z","end":"2026-04-15T15:16:50.987589Z","steps":["trace[2000550048] 'process raft request' (duration: 130.627382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:16:50.987674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.590807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:16:50.987695Z","caller":"traceutil/trace.go:171","msg":"trace[414022604] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2582; }","duration":"114.629968ms","start":"2026-04-15T15:16:50.873057Z","end":"2026-04-15T15:16:50.987687Z","steps":["trace[414022604] 'agreement among raft nodes before linearized reading' (duration: 114.575196ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:17:22.700713Z","caller":"traceutil/trace.go:171","msg":"trace[1379502643] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"244.84832ms","start":"2026-04-15T15:17:22.455838Z","end":"2026-04-15T15:17:22.700686Z","steps":["trace[1379502643] 'process raft request' (duration: 178.508335ms)","trace[1379502643] 'compare' (duration: 65.918213ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:17:22.701092Z","caller":"traceutil/trace.go:171","msg":"trace[1749908279] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"244.792978ms","start":"2026-04-15T15:17:22.456269Z","end":"2026-04-15T15:17:22.701062Z","steps":["trace[1749908279] 'process raft request' (duration: 244.380467ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:17:22.70142Z","caller":"traceutil/trace.go:171","msg":"trace[541486222] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"244.913691ms","start":"2026-04-15T15:17:22.456479Z","end":"2026-04-15T15:17:22.701393Z","steps":["trace[541486222] 'process raft request' (duration: 244.483669ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:17:28.409695Z","caller":"traceutil/trace.go:171","msg":"trace[471216177] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"162.634873ms","start":"2026-04-15T15:17:28.247027Z","end":"2026-04-15T15:17:28.409662Z","steps":["trace[471216177] 'process raft request' (duration: 162.463898ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:17:28.457243Z","caller":"traceutil/trace.go:171","msg":"trace[1701788031] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"208.429229ms","start":"2026-04-15T15:17:28.248756Z","end":"2026-04-15T15:17:28.457185Z","steps":["trace[1701788031] 'process raft request' (duration: 208.095349ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:30.725487Z","caller":"traceutil/trace.go:171","msg":"trace[263846608] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"184.56822ms","start":"2026-04-15T15:21:30.540891Z","end":"2026-04-15T15:21:30.725459Z","steps":["trace[263846608] 'read index received' (duration: 184.113568ms)","trace[263846608] 'applied index is now lower than readState.Index' (duration: 453.442µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:21:30.725609Z","caller":"traceutil/trace.go:171","msg":"trace[2044999806] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"300.010873ms","start":"2026-04-15T15:21:30.425564Z","end":"2026-04-15T15:21:30.725575Z","steps":["trace[2044999806] 'process raft request' (duration: 299.49497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:30.725827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:21:30.425517Z","time spent":"300.181468ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":413790,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:21:30.726158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.246888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719252"} {"level":"info","ts":"2026-04-15T15:21:30.726268Z","caller":"traceutil/trace.go:171","msg":"trace[655463564] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5002; }","duration":"185.430323ms","start":"2026-04-15T15:21:30.540823Z","end":"2026-04-15T15:21:30.726253Z","steps":["trace[655463564] 'agreement among raft nodes before linearized reading' (duration: 184.776685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:30.779102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.730777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T15:21:30.779183Z","caller":"traceutil/trace.go:171","msg":"trace[1669155008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5003; }","duration":"231.83451ms","start":"2026-04-15T15:21:30.547331Z","end":"2026-04-15T15:21:30.779166Z","steps":["trace[1669155008] 'agreement among raft nodes before linearized reading' (duration: 231.582033ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:42.796533Z","caller":"traceutil/trace.go:171","msg":"trace[606080039] transaction","detail":"{read_only:false; response_revision:5230; number_of_response:1; }","duration":"137.964869ms","start":"2026-04-15T15:21:42.658533Z","end":"2026-04-15T15:21:42.796497Z","steps":["trace[606080039] 'process raft request' (duration: 128.592877ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:43.380715Z","caller":"traceutil/trace.go:171","msg":"trace[2091559307] linearizableReadLoop","detail":"{readStateIndex:5440; appliedIndex:5439; }","duration":"104.123449ms","start":"2026-04-15T15:21:43.276573Z","end":"2026-04-15T15:21:43.380697Z","steps":["trace[2091559307] 'read index received' (duration: 103.958265ms)","trace[2091559307] 'applied index is now lower than readState.Index' (duration: 164.134µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:21:43.380948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.316484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:21:43.381033Z","caller":"traceutil/trace.go:171","msg":"trace[1522318012] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5240; }","duration":"104.487368ms","start":"2026-04-15T15:21:43.276523Z","end":"2026-04-15T15:21:43.38101Z","steps":["trace[1522318012] 'agreement among raft nodes before linearized reading' (duration: 104.306774ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:43.381165Z","caller":"traceutil/trace.go:171","msg":"trace[410279137] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"162.090507ms","start":"2026-04-15T15:21:43.21905Z","end":"2026-04-15T15:21:43.38114Z","steps":["trace[410279137] 'process raft request' (duration: 161.521802ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:51.322635Z","caller":"traceutil/trace.go:171","msg":"trace[2068529857] linearizableReadLoop","detail":"{readStateIndex:5592; appliedIndex:5591; }","duration":"302.627224ms","start":"2026-04-15T15:21:51.019969Z","end":"2026-04-15T15:21:51.322597Z","steps":["trace[2068529857] 'read index received' (duration: 302.339306ms)","trace[2068529857] 'applied index is now lower than readState.Index' (duration: 286.398µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:21:51.322742Z","caller":"traceutil/trace.go:171","msg":"trace[946402413] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"309.462188ms","start":"2026-04-15T15:21:51.01324Z","end":"2026-04-15T15:21:51.322702Z","steps":["trace[946402413] 'process raft request' (duration: 309.17859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.323013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.489242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38d6a970-ecc3-4260-ab79-8389dccb0523\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-15T15:21:51.323067Z","caller":"traceutil/trace.go:171","msg":"trace[976641532] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-38d6a970-ecc3-4260-ab79-8389dccb0523; range_end:; response_count:1; response_revision:5387; }","duration":"122.622086ms","start":"2026-04-15T15:21:51.200429Z","end":"2026-04-15T15:21:51.323051Z","steps":["trace[976641532] 'agreement among raft nodes before linearized reading' (duration: 122.38605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.323074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:21:51.013208Z","time spent":"309.626492ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2581,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T15:21:51.323052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.056535ms","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-04-15T15:21:51.323147Z","caller":"traceutil/trace.go:171","msg":"trace[630548864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5387; }","duration":"303.22372ms","start":"2026-04-15T15:21:51.019902Z","end":"2026-04-15T15:21:51.323126Z","steps":["trace[630548864] 'agreement among raft nodes before linearized reading' (duration: 302.868881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.323496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:21:51.019877Z","time spent":"303.312122ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T15:21:51.585388Z","caller":"traceutil/trace.go:171","msg":"trace[2119374158] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5593; }","duration":"254.442989ms","start":"2026-04-15T15:21:51.330924Z","end":"2026-04-15T15:21:51.585367Z","steps":["trace[2119374158] 'read index received' (duration: 25.754662ms)","trace[2119374158] 'applied index is now lower than readState.Index' (duration: 228.687467ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:21:51.585541Z","caller":"traceutil/trace.go:171","msg":"trace[1031533305] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"246.838064ms","start":"2026-04-15T15:21:51.338659Z","end":"2026-04-15T15:21:51.585498Z","steps":["trace[1031533305] 'process raft request' (duration: 246.644429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.585613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.677286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-15T15:21:51.585568Z","caller":"traceutil/trace.go:171","msg":"trace[94075417] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"256.155735ms","start":"2026-04-15T15:21:51.32927Z","end":"2026-04-15T15:21:51.585425Z","steps":["trace[94075417] 'process raft request' (duration: 255.870698ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:51.585658Z","caller":"traceutil/trace.go:171","msg":"trace[365015046] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5390; }","duration":"254.790578ms","start":"2026-04-15T15:21:51.330852Z","end":"2026-04-15T15:21:51.585643Z","steps":["trace[365015046] 'agreement among raft nodes before linearized reading' (duration: 254.636434ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:51.799735Z","caller":"traceutil/trace.go:171","msg":"trace[1766657229] linearizableReadLoop","detail":"{readStateIndex:5597; appliedIndex:5595; }","duration":"214.240579ms","start":"2026-04-15T15:21:51.585455Z","end":"2026-04-15T15:21:51.799696Z","steps":["trace[1766657229] 'read index received' (duration: 181.665963ms)","trace[1766657229] 'applied index is now lower than readState.Index' (duration: 32.572806ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:21:51.799895Z","caller":"traceutil/trace.go:171","msg":"trace[892846679] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"275.830794ms","start":"2026-04-15T15:21:51.524024Z","end":"2026-04-15T15:21:51.799855Z","steps":["trace[892846679] 'process raft request' (duration: 275.596818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.800014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.226719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T15:21:51.800062Z","caller":"traceutil/trace.go:171","msg":"trace[1084786934] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5392; }","duration":"406.346632ms","start":"2026-04-15T15:21:51.3937Z","end":"2026-04-15T15:21:51.800047Z","steps":["trace[1084786934] 'agreement among raft nodes before linearized reading' (duration: 406.177098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.800113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:21:51.393681Z","time spent":"406.421524ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-15T15:21:51.800154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.740105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6nwnk\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-15T15:21:51.800229Z","caller":"traceutil/trace.go:171","msg":"trace[451535556] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6nwnk; range_end:; response_count:1; response_revision:5392; }","duration":"373.861168ms","start":"2026-04-15T15:21:51.426349Z","end":"2026-04-15T15:21:51.80021Z","steps":["trace[451535556] 'agreement among raft nodes before linearized reading' (duration: 373.520669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.800276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:21:51.426338Z","time spent":"373.928ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6475,"request content":"key:\"/registry/pods/openstack/keepalived-6nwnk\" "} {"level":"info","ts":"2026-04-15T15:21:51.800595Z","caller":"traceutil/trace.go:171","msg":"trace[819706494] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"391.378009ms","start":"2026-04-15T15:21:51.409193Z","end":"2026-04-15T15:21:51.800571Z","steps":["trace[819706494] 'process raft request' (duration: 357.906939ms)","trace[819706494] 'compare' (duration: 32.319629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:21:51.800742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:21:51.409173Z","time spent":"391.479502ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:21:51.801036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.644424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:21:51.801175Z","caller":"traceutil/trace.go:171","msg":"trace[48051836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5392; }","duration":"412.367954ms","start":"2026-04-15T15:21:51.388774Z","end":"2026-04-15T15:21:51.801142Z","steps":["trace[48051836] 'agreement among raft nodes before linearized reading' (duration: 411.47879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.801236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:21:51.388698Z","time spent":"412.515698ms","remote":"127.0.0.1:39264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T15:21:51.802419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.691789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2211023"} {"level":"info","ts":"2026-04-15T15:21:51.802521Z","caller":"traceutil/trace.go:171","msg":"trace[1630431421] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5392; }","duration":"222.0984ms","start":"2026-04-15T15:21:51.5804Z","end":"2026-04-15T15:21:51.802499Z","steps":["trace[1630431421] 'agreement among raft nodes before linearized reading' (duration: 220.075526ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:51.80845Z","caller":"traceutil/trace.go:171","msg":"trace[298772913] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"206.87422ms","start":"2026-04-15T15:21:51.601554Z","end":"2026-04-15T15:21:51.808428Z","steps":["trace[298772913] 'process raft request' (duration: 206.554552ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:21:51.808627Z","caller":"traceutil/trace.go:171","msg":"trace[2053217345] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"179.134145ms","start":"2026-04-15T15:21:51.629463Z","end":"2026-04-15T15:21:51.808597Z","steps":["trace[2053217345] 'process raft request' (duration: 178.741494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.808725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.827971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-15T15:21:51.809535Z","caller":"traceutil/trace.go:171","msg":"trace[1244122083] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5394; }","duration":"216.742196ms","start":"2026-04-15T15:21:51.592772Z","end":"2026-04-15T15:21:51.809514Z","steps":["trace[1244122083] 'agreement among raft nodes before linearized reading' (duration: 215.78587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:21:51.808712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.190157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2592"} {"level":"info","ts":"2026-04-15T15:21:51.80996Z","caller":"traceutil/trace.go:171","msg":"trace[1956647857] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5394; }","duration":"215.39772ms","start":"2026-04-15T15:21:51.594494Z","end":"2026-04-15T15:21:51.809892Z","steps":["trace[1956647857] 'agreement among raft nodes before linearized reading' (duration: 214.115505ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:23:11.028729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3043} {"level":"info","ts":"2026-04-15T15:23:11.120269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3043,"took":"89.510076ms","hash":3857246852,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-15T15:23:11.120346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3857246852,"revision":3043,"compact-revision":-1} {"level":"info","ts":"2026-04-15T15:28:05.811562Z","caller":"traceutil/trace.go:171","msg":"trace[1451600421] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"420.696465ms","start":"2026-04-15T15:28:05.390839Z","end":"2026-04-15T15:28:05.811536Z","steps":["trace[1451600421] 'process raft request' (duration: 420.53611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:28:05.811722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:28:05.390818Z","time spent":"420.823568ms","remote":"127.0.0.1:39518","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-04-15T15:28:06.000312Z","caller":"traceutil/trace.go:171","msg":"trace[2065713523] linearizableReadLoop","detail":"{readStateIndex:8582; appliedIndex:8580; }","duration":"566.589062ms","start":"2026-04-15T15:28:05.433698Z","end":"2026-04-15T15:28:06.000287Z","steps":["trace[2065713523] 'read index received' (duration: 377.721875ms)","trace[2065713523] 'applied index is now lower than readState.Index' (duration: 188.865737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:28:06.00052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.905351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T15:28:06.000559Z","caller":"traceutil/trace.go:171","msg":"trace[296091422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8289; }","duration":"566.989833ms","start":"2026-04-15T15:28:05.43356Z","end":"2026-04-15T15:28:06.000549Z","steps":["trace[296091422] 'agreement among raft nodes before linearized reading' (duration: 566.821589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:28:06.000598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:28:05.433542Z","time spent":"567.048924ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T15:28:06.000801Z","caller":"traceutil/trace.go:171","msg":"trace[108470990] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"495.639798ms","start":"2026-04-15T15:28:05.505149Z","end":"2026-04-15T15:28:06.000789Z","steps":["trace[108470990] 'process raft request' (duration: 495.093503ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:06.000782Z","caller":"traceutil/trace.go:171","msg":"trace[1735422248] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"602.012119ms","start":"2026-04-15T15:28:05.398734Z","end":"2026-04-15T15:28:06.000746Z","steps":["trace[1735422248] 'process raft request' (duration: 601.362931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:28:06.000895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:28:05.505125Z","time spent":"495.711629ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:28:06.000957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:28:05.39872Z","time spent":"602.139872ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:28:11.036142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6151} {"level":"info","ts":"2026-04-15T15:28:11.14301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6151,"took":"104.232653ms","hash":1986688061,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":23572480,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-15T15:28:11.143117Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1986688061,"revision":6151,"compact-revision":3043} {"level":"info","ts":"2026-04-15T15:28:18.124785Z","caller":"traceutil/trace.go:171","msg":"trace[2025765371] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"259.678399ms","start":"2026-04-15T15:28:17.865059Z","end":"2026-04-15T15:28:18.124737Z","steps":["trace[2025765371] 'process raft request' (duration: 259.241727ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:18.125516Z","caller":"traceutil/trace.go:171","msg":"trace[338796117] linearizableReadLoop","detail":"{readStateIndex:8647; appliedIndex:8646; }","duration":"200.415349ms","start":"2026-04-15T15:28:17.925083Z","end":"2026-04-15T15:28:18.125498Z","steps":["trace[338796117] 'read index received' (duration: 199.180586ms)","trace[338796117] 'applied index is now lower than readState.Index' (duration: 1.232733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:28:18.125769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.663086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T15:28:18.12582Z","caller":"traceutil/trace.go:171","msg":"trace[880940975] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8350; }","duration":"200.781659ms","start":"2026-04-15T15:28:17.925023Z","end":"2026-04-15T15:28:18.125805Z","steps":["trace[880940975] 'agreement among raft nodes before linearized reading' (duration: 200.587213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:28:18.131518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.128298ms","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-04-15T15:28:18.131613Z","caller":"traceutil/trace.go:171","msg":"trace[1188694933] 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:8352; }","duration":"148.241261ms","start":"2026-04-15T15:28:17.983347Z","end":"2026-04-15T15:28:18.131588Z","steps":["trace[1188694933] 'agreement among raft nodes before linearized reading' (duration: 147.969924ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:18.593988Z","caller":"traceutil/trace.go:171","msg":"trace[1780551147] linearizableReadLoop","detail":"{readStateIndex:8653; appliedIndex:8652; }","duration":"106.453743ms","start":"2026-04-15T15:28:18.487503Z","end":"2026-04-15T15:28:18.593957Z","steps":["trace[1780551147] 'read index received' (duration: 106.365381ms)","trace[1780551147] 'applied index is now lower than readState.Index' (duration: 86.882µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:28:18.594265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.753372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-15T15:28:18.594302Z","caller":"traceutil/trace.go:171","msg":"trace[1118584723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8356; }","duration":"106.815093ms","start":"2026-04-15T15:28:18.487474Z","end":"2026-04-15T15:28:18.594289Z","steps":["trace[1118584723] 'agreement among raft nodes before linearized reading' (duration: 106.622438ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:18.594077Z","caller":"traceutil/trace.go:171","msg":"trace[54765746] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"106.70729ms","start":"2026-04-15T15:28:18.487322Z","end":"2026-04-15T15:28:18.594029Z","steps":["trace[54765746] 'process raft request' (duration: 106.441403ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:18.602226Z","caller":"traceutil/trace.go:171","msg":"trace[43279010] transaction","detail":"{read_only:false; response_revision:8357; number_of_response:1; }","duration":"105.930658ms","start":"2026-04-15T15:28:18.496274Z","end":"2026-04-15T15:28:18.602204Z","steps":["trace[43279010] 'process raft request' (duration: 105.667361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:28:18.913216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.517598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-15T15:28:18.913476Z","caller":"traceutil/trace.go:171","msg":"trace[1722370909] transaction","detail":"{read_only:false; response_revision:8359; number_of_response:1; }","duration":"303.700147ms","start":"2026-04-15T15:28:18.609755Z","end":"2026-04-15T15:28:18.913455Z","steps":["trace[1722370909] 'process raft request' (duration: 303.589244ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:18.913488Z","caller":"traceutil/trace.go:171","msg":"trace[1232608176] transaction","detail":"{read_only:false; response_revision:8358; number_of_response:1; }","duration":"313.777989ms","start":"2026-04-15T15:28:18.599675Z","end":"2026-04-15T15:28:18.913453Z","steps":["trace[1232608176] 'process raft request' (duration: 51.890621ms)","trace[1232608176] 'compare' (duration: 261.303793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:28:18.913557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:28:18.609726Z","time spent":"303.793499ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:28:18.913646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:28:18.599643Z","time spent":"313.913943ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:28:45.15673Z","caller":"traceutil/trace.go:171","msg":"trace[1346485438] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"108.080167ms","start":"2026-04-15T15:28:45.048623Z","end":"2026-04-15T15:28:45.156703Z","steps":["trace[1346485438] 'process raft request' (duration: 107.929033ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:45.158634Z","caller":"traceutil/trace.go:171","msg":"trace[584778697] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"107.240904ms","start":"2026-04-15T15:28:45.051377Z","end":"2026-04-15T15:28:45.158618Z","steps":["trace[584778697] 'process raft request' (duration: 106.888344ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:48.487053Z","caller":"traceutil/trace.go:171","msg":"trace[2103610481] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"230.750028ms","start":"2026-04-15T15:28:48.25626Z","end":"2026-04-15T15:28:48.48701Z","steps":["trace[2103610481] 'process raft request' (duration: 218.538408ms)","trace[2103610481] 'compare' (duration: 11.985974ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:28:48.487282Z","caller":"traceutil/trace.go:171","msg":"trace[335740440] linearizableReadLoop","detail":"{readStateIndex:8873; appliedIndex:8871; }","duration":"140.502133ms","start":"2026-04-15T15:28:48.346723Z","end":"2026-04-15T15:28:48.487225Z","steps":["trace[335740440] 'read index received' (duration: 128.094657ms)","trace[335740440] 'applied index is now lower than readState.Index' (duration: 12.402705ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:28:48.487483Z","caller":"traceutil/trace.go:171","msg":"trace[1185343710] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"229.045152ms","start":"2026-04-15T15:28:48.258402Z","end":"2026-04-15T15:28:48.487448Z","steps":["trace[1185343710] 'process raft request' (duration: 228.526048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:28:48.487618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.866352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T15:28:48.48767Z","caller":"traceutil/trace.go:171","msg":"trace[1541894754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8568; }","duration":"140.966185ms","start":"2026-04-15T15:28:48.346692Z","end":"2026-04-15T15:28:48.487658Z","steps":["trace[1541894754] 'agreement among raft nodes before linearized reading' (duration: 140.691778ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:48.5085Z","caller":"traceutil/trace.go:171","msg":"trace[1455405986] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"136.486703ms","start":"2026-04-15T15:28:48.371997Z","end":"2026-04-15T15:28:48.508484Z","steps":["trace[1455405986] 'process raft request' (duration: 136.3678ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:28:52.618412Z","caller":"traceutil/trace.go:171","msg":"trace[2056403827] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"111.377666ms","start":"2026-04-15T15:28:52.506994Z","end":"2026-04-15T15:28:52.618372Z","steps":["trace[2056403827] 'process raft request' (duration: 111.221742ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:29:01.969079Z","caller":"traceutil/trace.go:171","msg":"trace[1257972943] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"190.029739ms","start":"2026-04-15T15:29:01.779022Z","end":"2026-04-15T15:29:01.969051Z","steps":["trace[1257972943] 'process raft request' (duration: 189.818993ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:29:02.055942Z","caller":"traceutil/trace.go:171","msg":"trace[2006730081] linearizableReadLoop","detail":"{readStateIndex:8994; appliedIndex:8992; }","duration":"149.506275ms","start":"2026-04-15T15:29:01.906407Z","end":"2026-04-15T15:29:02.055913Z","steps":["trace[2006730081] 'read index received' (duration: 62.529528ms)","trace[2006730081] 'applied index is now lower than readState.Index' (duration: 86.975267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:29:02.056079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.644128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-15T15:29:02.05613Z","caller":"traceutil/trace.go:171","msg":"trace[523908683] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"270.628014ms","start":"2026-04-15T15:29:01.785463Z","end":"2026-04-15T15:29:02.056091Z","steps":["trace[523908683] 'process raft request' (duration: 248.497116ms)","trace[523908683] 'compare' (duration: 21.828349ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:29:02.056191Z","caller":"traceutil/trace.go:171","msg":"trace[949370444] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8682; }","duration":"149.755031ms","start":"2026-04-15T15:29:01.906353Z","end":"2026-04-15T15:29:02.056108Z","steps":["trace[949370444] 'agreement among raft nodes before linearized reading' (duration: 149.658158ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:29:02.321803Z","caller":"traceutil/trace.go:171","msg":"trace[484474465] transaction","detail":"{read_only:false; response_revision:8683; number_of_response:1; }","duration":"157.14203ms","start":"2026-04-15T15:29:02.164625Z","end":"2026-04-15T15:29:02.321767Z","steps":["trace[484474465] 'process raft request' (duration: 156.906234ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:29:02.346656Z","caller":"traceutil/trace.go:171","msg":"trace[953811618] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"178.047165ms","start":"2026-04-15T15:29:02.168572Z","end":"2026-04-15T15:29:02.346619Z","steps":["trace[953811618] 'process raft request' (duration: 177.635554ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:29:02.782281Z","caller":"traceutil/trace.go:171","msg":"trace[336843990] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"147.044429ms","start":"2026-04-15T15:29:02.635218Z","end":"2026-04-15T15:29:02.782262Z","steps":["trace[336843990] 'process raft request' (duration: 146.897525ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:29:02.789255Z","caller":"traceutil/trace.go:171","msg":"trace[492139571] linearizableReadLoop","detail":"{readStateIndex:9000; appliedIndex:8998; }","duration":"134.667635ms","start":"2026-04-15T15:29:02.65456Z","end":"2026-04-15T15:29:02.789228Z","steps":["trace[492139571] 'read index received' (duration: 127.648286ms)","trace[492139571] 'applied index is now lower than readState.Index' (duration: 7.018389ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:29:02.789339Z","caller":"traceutil/trace.go:171","msg":"trace[458992325] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"140.112262ms","start":"2026-04-15T15:29:02.649217Z","end":"2026-04-15T15:29:02.789329Z","steps":["trace[458992325] 'process raft request' (duration: 139.892806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:29:02.789535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.957463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-15T15:29:02.78956Z","caller":"traceutil/trace.go:171","msg":"trace[782208772] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8688; }","duration":"135.014664ms","start":"2026-04-15T15:29:02.654535Z","end":"2026-04-15T15:29:02.78955Z","steps":["trace[782208772] 'agreement among raft nodes before linearized reading' (duration: 134.902081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:29:02.789821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.760597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T15:29:02.78999Z","caller":"traceutil/trace.go:171","msg":"trace[1256276127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8688; }","duration":"104.960483ms","start":"2026-04-15T15:29:02.685004Z","end":"2026-04-15T15:29:02.789964Z","steps":["trace[1256276127] 'agreement among raft nodes before linearized reading' (duration: 104.683055ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:29:15.023501Z","caller":"traceutil/trace.go:171","msg":"trace[1782861043] transaction","detail":"{read_only:false; response_revision:8790; number_of_response:1; }","duration":"108.402886ms","start":"2026-04-15T15:29:14.915068Z","end":"2026-04-15T15:29:15.02347Z","steps":["trace[1782861043] 'process raft request' (duration: 108.17954ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:29:18.917842Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c6.wal"} {"level":"info","ts":"2026-04-15T15:30:51.271493Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"95a1d32286abc938","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-15T15:30:51.278966Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-15T15:30:51.279108Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-15T15:33:11.048184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8319} {"level":"info","ts":"2026-04-15T15:33:11.13486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8319,"took":"83.359859ms","hash":3394184759,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":24043520,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-15T15:33:11.134966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3394184759,"revision":8319,"compact-revision":6151} {"level":"info","ts":"2026-04-15T15:33:18.895415Z","caller":"traceutil/trace.go:171","msg":"trace[562867390] transaction","detail":"{read_only:false; response_revision:10583; number_of_response:1; }","duration":"251.848264ms","start":"2026-04-15T15:33:18.643348Z","end":"2026-04-15T15:33:18.895196Z","steps":["trace[562867390] 'process raft request' (duration: 251.514705ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:33:18.895896Z","caller":"traceutil/trace.go:171","msg":"trace[1878486181] linearizableReadLoop","detail":"{readStateIndex:10956; appliedIndex:10956; }","duration":"231.428084ms","start":"2026-04-15T15:33:18.664425Z","end":"2026-04-15T15:33:18.895853Z","steps":["trace[1878486181] 'read index received' (duration: 231.402173ms)","trace[1878486181] 'applied index is now lower than readState.Index' (duration: 22.191µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:33:18.896015Z","caller":"traceutil/trace.go:171","msg":"trace[913398191] transaction","detail":"{read_only:false; response_revision:10584; number_of_response:1; }","duration":"122.66365ms","start":"2026-04-15T15:33:18.773329Z","end":"2026-04-15T15:33:18.895993Z","steps":["trace[913398191] 'process raft request' (duration: 122.558567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:33:18.896206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.756183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6nwnk\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-15T15:33:18.896291Z","caller":"traceutil/trace.go:171","msg":"trace[1458393719] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6nwnk; range_end:; response_count:1; response_revision:10584; }","duration":"231.884226ms","start":"2026-04-15T15:33:18.664387Z","end":"2026-04-15T15:33:18.896271Z","steps":["trace[1458393719] 'agreement among raft nodes before linearized reading' (duration: 231.575648ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:35:41.614856Z","caller":"traceutil/trace.go:171","msg":"trace[1834531377] transaction","detail":"{read_only:false; response_revision:11780; number_of_response:1; }","duration":"187.044354ms","start":"2026-04-15T15:35:41.427778Z","end":"2026-04-15T15:35:41.614822Z","steps":["trace[1834531377] 'process raft request' (duration: 186.756896ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:35:41.793877Z","caller":"traceutil/trace.go:171","msg":"trace[932085897] transaction","detail":"{read_only:false; response_revision:11782; number_of_response:1; }","duration":"118.843465ms","start":"2026-04-15T15:35:41.674983Z","end":"2026-04-15T15:35:41.793827Z","steps":["trace[932085897] 'process raft request' (duration: 95.969588ms)","trace[932085897] 'compare' (duration: 22.678042ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:36:04.653188Z","caller":"traceutil/trace.go:171","msg":"trace[1443492910] transaction","detail":"{read_only:false; response_revision:11907; number_of_response:1; }","duration":"181.300789ms","start":"2026-04-15T15:36:04.471846Z","end":"2026-04-15T15:36:04.653147Z","steps":["trace[1443492910] 'process raft request' (duration: 120.111979ms)","trace[1443492910] 'compare' (duration: 60.905113ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:38:11.055815Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10542} {"level":"info","ts":"2026-04-15T15:38:11.135418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10542,"took":"75.965742ms","hash":1897775515,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":25944064,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-15T15:38:11.135506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1897775515,"revision":10542,"compact-revision":8319} {"level":"info","ts":"2026-04-15T15:42:37.628536Z","caller":"traceutil/trace.go:171","msg":"trace[94201620] linearizableReadLoop","detail":"{readStateIndex:15040; appliedIndex:15039; }","duration":"240.405636ms","start":"2026-04-15T15:42:37.388101Z","end":"2026-04-15T15:42:37.628506Z","steps":["trace[94201620] 'read index received' (duration: 240.202651ms)","trace[94201620] 'applied index is now lower than readState.Index' (duration: 201.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:42:37.628669Z","caller":"traceutil/trace.go:171","msg":"trace[331501807] transaction","detail":"{read_only:false; response_revision:14536; number_of_response:1; }","duration":"274.30362ms","start":"2026-04-15T15:42:37.354354Z","end":"2026-04-15T15:42:37.628657Z","steps":["trace[331501807] 'process raft request' (duration: 273.976692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:42:37.628929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.816228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:42:37.628975Z","caller":"traceutil/trace.go:171","msg":"trace[1529589533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14536; }","duration":"240.912061ms","start":"2026-04-15T15:42:37.388051Z","end":"2026-04-15T15:42:37.628963Z","steps":["trace[1529589533] 'agreement among raft nodes before linearized reading' (duration: 240.817808ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:42:37.76866Z","caller":"traceutil/trace.go:171","msg":"trace[2113907380] transaction","detail":"{read_only:false; response_revision:14537; number_of_response:1; }","duration":"199.197437ms","start":"2026-04-15T15:42:37.56942Z","end":"2026-04-15T15:42:37.768617Z","steps":["trace[2113907380] 'process raft request' (duration: 198.689103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:42:37.768765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.175805ms","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":"warn","ts":"2026-04-15T15:42:37.768859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.883462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-15T15:42:37.768893Z","caller":"traceutil/trace.go:171","msg":"trace[1935842677] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:14537; }","duration":"170.35572ms","start":"2026-04-15T15:42:37.598506Z","end":"2026-04-15T15:42:37.768862Z","steps":["trace[1935842677] 'agreement among raft nodes before linearized reading' (duration: 170.137494ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:42:37.768939Z","caller":"traceutil/trace.go:171","msg":"trace[894185398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14537; }","duration":"322.021735ms","start":"2026-04-15T15:42:37.446899Z","end":"2026-04-15T15:42:37.76892Z","steps":["trace[894185398] 'agreement among raft nodes before linearized reading' (duration: 321.781979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:42:37.769045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.029643ms","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-04-15T15:42:37.769103Z","caller":"traceutil/trace.go:171","msg":"trace[213672216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14537; }","duration":"323.145326ms","start":"2026-04-15T15:42:37.445945Z","end":"2026-04-15T15:42:37.76909Z","steps":["trace[213672216] 'agreement among raft nodes before linearized reading' (duration: 322.9532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:42:37.768986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:42:37.44687Z","time spent":"322.102097ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T15:42:37.769162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:42:37.445923Z","time spent":"323.221408ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T15:42:37.769021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.934716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6nwnk\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-15T15:42:37.769287Z","caller":"traceutil/trace.go:171","msg":"trace[1528307452] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6nwnk; range_end:; response_count:1; response_revision:14537; }","duration":"184.243174ms","start":"2026-04-15T15:42:37.585006Z","end":"2026-04-15T15:42:37.769249Z","steps":["trace[1528307452] 'agreement among raft nodes before linearized reading' (duration: 183.528095ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:42:37.769314Z","caller":"traceutil/trace.go:171","msg":"trace[962916770] linearizableReadLoop","detail":"{readStateIndex:15041; appliedIndex:15040; }","duration":"139.889979ms","start":"2026-04-15T15:42:37.62862Z","end":"2026-04-15T15:42:37.76851Z","steps":["trace[962916770] 'read index received' (duration: 139.380375ms)","trace[962916770] 'applied index is now lower than readState.Index' (duration: 508.494µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:42:37.769536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.176188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-15T15:42:37.769607Z","caller":"traceutil/trace.go:171","msg":"trace[692032558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14537; }","duration":"352.27202ms","start":"2026-04-15T15:42:37.41732Z","end":"2026-04-15T15:42:37.769592Z","steps":["trace[692032558] 'agreement among raft nodes before linearized reading' (duration: 352.087875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:42:37.769605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.20711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T15:42:37.769653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:42:37.417309Z","time spent":"352.331512ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T15:42:37.769658Z","caller":"traceutil/trace.go:171","msg":"trace[1073248733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14537; }","duration":"139.293963ms","start":"2026-04-15T15:42:37.630352Z","end":"2026-04-15T15:42:37.769646Z","steps":["trace[1073248733] 'agreement among raft nodes before linearized reading' (duration: 139.218861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:43:00.231889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.291475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-15T15:43:00.231985Z","caller":"traceutil/trace.go:171","msg":"trace[1004230529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14849; }","duration":"171.440239ms","start":"2026-04-15T15:43:00.060525Z","end":"2026-04-15T15:43:00.231966Z","steps":["trace[1004230529] 'range keys from in-memory index tree' (duration: 171.089329ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:43:00.510754Z","caller":"traceutil/trace.go:171","msg":"trace[762140157] transaction","detail":"{read_only:false; response_revision:14850; number_of_response:1; }","duration":"274.565857ms","start":"2026-04-15T15:43:00.236157Z","end":"2026-04-15T15:43:00.510723Z","steps":["trace[762140157] 'process raft request' (duration: 274.354231ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:43:00.599018Z","caller":"traceutil/trace.go:171","msg":"trace[30812677] transaction","detail":"{read_only:false; response_revision:14851; number_of_response:1; }","duration":"362.786633ms","start":"2026-04-15T15:43:00.236193Z","end":"2026-04-15T15:43:00.598979Z","steps":["trace[30812677] 'process raft request' (duration: 362.473025ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:43:00.599015Z","caller":"traceutil/trace.go:171","msg":"trace[1164230829] transaction","detail":"{read_only:false; response_revision:14852; number_of_response:1; }","duration":"324.701207ms","start":"2026-04-15T15:43:00.274281Z","end":"2026-04-15T15:43:00.598982Z","steps":["trace[1164230829] 'process raft request' (duration: 324.602385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:43:00.599277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:43:00.236161Z","time spent":"362.965868ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T15:43:00.599368Z","caller":"traceutil/trace.go:171","msg":"trace[1155715537] transaction","detail":"{read_only:false; response_revision:14853; number_of_response:1; }","duration":"295.552462ms","start":"2026-04-15T15:43:00.303795Z","end":"2026-04-15T15:43:00.599348Z","steps":["trace[1155715537] 'process raft request' (duration: 295.138161ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:43:00.599393Z","caller":"traceutil/trace.go:171","msg":"trace[422491243] transaction","detail":"{read_only:false; response_revision:14854; number_of_response:1; }","duration":"287.336511ms","start":"2026-04-15T15:43:00.312019Z","end":"2026-04-15T15:43:00.599356Z","steps":["trace[422491243] 'process raft request' (duration: 287.139146ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:43:00.599382Z","caller":"traceutil/trace.go:171","msg":"trace[75984717] linearizableReadLoop","detail":"{readStateIndex:15371; appliedIndex:15366; }","duration":"210.491061ms","start":"2026-04-15T15:43:00.388856Z","end":"2026-04-15T15:43:00.599347Z","steps":["trace[75984717] 'read index received' (duration: 121.72619ms)","trace[75984717] 'applied index is now lower than readState.Index' (duration: 88.763141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T15:43:00.599564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.676246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T15:43:00.599625Z","caller":"traceutil/trace.go:171","msg":"trace[2027289128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14854; }","duration":"210.807669ms","start":"2026-04-15T15:43:00.388805Z","end":"2026-04-15T15:43:00.599613Z","steps":["trace[2027289128] 'agreement among raft nodes before linearized reading' (duration: 210.647005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:43:00.599693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:43:00.274263Z","time spent":"325.114139ms","remote":"127.0.0.1:39518","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-04-15T15:43:11.062653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12764} {"level":"info","ts":"2026-04-15T15:43:11.14341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12764,"took":"78.346661ms","hash":377050803,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":28372992,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-15T15:43:11.143476Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":377050803,"revision":12764,"compact-revision":10542} {"level":"info","ts":"2026-04-15T15:44:17.18576Z","caller":"traceutil/trace.go:171","msg":"trace[784783297] linearizableReadLoop","detail":"{readStateIndex:16149; appliedIndex:16146; }","duration":"276.290064ms","start":"2026-04-15T15:44:16.909453Z","end":"2026-04-15T15:44:17.185743Z","steps":["trace[784783297] 'read index received' (duration: 11.50645ms)","trace[784783297] 'applied index is now lower than readState.Index' (duration: 264.782484ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:44:17.185826Z","caller":"traceutil/trace.go:171","msg":"trace[1873253124] transaction","detail":"{read_only:false; response_revision:15610; number_of_response:1; }","duration":"280.469187ms","start":"2026-04-15T15:44:16.905329Z","end":"2026-04-15T15:44:17.185798Z","steps":["trace[1873253124] 'process raft request' (duration: 280.104797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:44:17.186034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.568451ms","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-04-15T15:44:17.186075Z","caller":"traceutil/trace.go:171","msg":"trace[1884485799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15610; }","duration":"276.691645ms","start":"2026-04-15T15:44:16.90937Z","end":"2026-04-15T15:44:17.186061Z","steps":["trace[1884485799] 'agreement among raft nodes before linearized reading' (duration: 276.491339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:44:17.186233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.003983ms","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-04-15T15:44:17.1864Z","caller":"traceutil/trace.go:171","msg":"trace[797960739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15610; }","duration":"169.27135ms","start":"2026-04-15T15:44:17.017094Z","end":"2026-04-15T15:44:17.186366Z","steps":["trace[797960739] 'agreement among raft nodes before linearized reading' (duration: 168.828638ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:44:17.185571Z","caller":"traceutil/trace.go:171","msg":"trace[537736082] transaction","detail":"{read_only:false; response_revision:15609; number_of_response:1; }","duration":"289.345545ms","start":"2026-04-15T15:44:16.896173Z","end":"2026-04-15T15:44:17.185518Z","steps":["trace[537736082] 'process raft request' (duration: 286.724735ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:47:19.421181Z","caller":"traceutil/trace.go:171","msg":"trace[1934919202] transaction","detail":"{read_only:false; response_revision:17078; number_of_response:1; }","duration":"133.079025ms","start":"2026-04-15T15:47:19.288067Z","end":"2026-04-15T15:47:19.421146Z","steps":["trace[1934919202] 'process raft request' (duration: 103.5673ms)","trace[1934919202] 'compare' (duration: 29.208327ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:47:19.421268Z","caller":"traceutil/trace.go:171","msg":"trace[420582969] transaction","detail":"{read_only:false; response_revision:17080; number_of_response:1; }","duration":"130.075364ms","start":"2026-04-15T15:47:19.291163Z","end":"2026-04-15T15:47:19.421238Z","steps":["trace[420582969] 'process raft request' (duration: 129.957201ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:47:19.421484Z","caller":"traceutil/trace.go:171","msg":"trace[1671487220] transaction","detail":"{read_only:false; response_revision:17079; number_of_response:1; }","duration":"130.525736ms","start":"2026-04-15T15:47:19.290936Z","end":"2026-04-15T15:47:19.421462Z","steps":["trace[1671487220] 'process raft request' (duration: 130.127556ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:48:11.070311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14938} {"level":"info","ts":"2026-04-15T15:48:11.163957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14938,"took":"90.112157ms","hash":1189086693,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":30597120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-15T15:48:11.164041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1189086693,"revision":14938,"compact-revision":12764} {"level":"info","ts":"2026-04-15T15:50:23.500546Z","caller":"traceutil/trace.go:171","msg":"trace[399966416] transaction","detail":"{read_only:false; response_revision:18351; number_of_response:1; }","duration":"196.811203ms","start":"2026-04-15T15:50:23.303697Z","end":"2026-04-15T15:50:23.500508Z","steps":["trace[399966416] 'process raft request' (duration: 196.548986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.076624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499512249436211231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T15:50:24.275968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.390753ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T15:50:24.27614Z","caller":"traceutil/trace.go:171","msg":"trace[1451334073] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18351; }","duration":"461.578868ms","start":"2026-04-15T15:50:23.814541Z","end":"2026-04-15T15:50:24.27612Z","steps":["trace[1451334073] 'range keys from in-memory index tree' (duration: 461.375483ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T15:50:24.3573Z","caller":"traceutil/trace.go:171","msg":"trace[1393365883] linearizableReadLoop","detail":"{readStateIndex:18983; appliedIndex:18982; }","duration":"781.737816ms","start":"2026-04-15T15:50:23.57554Z","end":"2026-04-15T15:50:24.357278Z","steps":["trace[1393365883] 'read index received' (duration: 781.50499ms)","trace[1393365883] 'applied index is now lower than readState.Index' (duration: 232.286µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T15:50:24.357634Z","caller":"traceutil/trace.go:171","msg":"trace[2039149028] transaction","detail":"{read_only:false; response_revision:18352; number_of_response:1; }","duration":"791.533191ms","start":"2026-04-15T15:50:23.566093Z","end":"2026-04-15T15:50:24.357626Z","steps":["trace[2039149028] 'process raft request' (duration: 791.064838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.357714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:50:23.566069Z","time spent":"791.580971ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T15:50:24.357912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.382483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e381bcf9-4b60-4f30-bfd2-83434ea67ead\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-04-15T15:50:24.357932Z","caller":"traceutil/trace.go:171","msg":"trace[1174506596] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e381bcf9-4b60-4f30-bfd2-83434ea67ead; range_end:; response_count:1; response_revision:18352; }","duration":"782.453066ms","start":"2026-04-15T15:50:23.575471Z","end":"2026-04-15T15:50:24.357924Z","steps":["trace[1174506596] 'agreement among raft nodes before linearized reading' (duration: 782.357093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.357947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:50:23.575444Z","time spent":"782.499657ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3781,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e381bcf9-4b60-4f30-bfd2-83434ea67ead\" "} {"level":"warn","ts":"2026-04-15T15:50:24.362492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.854833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-04-15T15:50:24.362606Z","caller":"traceutil/trace.go:171","msg":"trace[1106792187] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18353; }","duration":"761.014818ms","start":"2026-04-15T15:50:23.601565Z","end":"2026-04-15T15:50:24.36258Z","steps":["trace[1106792187] 'agreement among raft nodes before linearized reading' (duration: 760.698219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.362658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:50:23.601553Z","time spent":"761.09438ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":586,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-15T15:50:24.363032Z","caller":"traceutil/trace.go:171","msg":"trace[1935755820] transaction","detail":"{read_only:false; response_revision:18353; number_of_response:1; }","duration":"124.285659ms","start":"2026-04-15T15:50:24.238734Z","end":"2026-04-15T15:50:24.36302Z","steps":["trace[1935755820] 'process raft request' (duration: 123.323793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.363705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.850641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.244\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-15T15:50:24.363736Z","caller":"traceutil/trace.go:171","msg":"trace[133549186] range","detail":"{range_begin:/registry/masterleases/199.19.213.244; range_end:; response_count:1; response_revision:18353; }","duration":"171.937583ms","start":"2026-04-15T15:50:24.191789Z","end":"2026-04-15T15:50:24.363726Z","steps":["trace[133549186] 'agreement among raft nodes before linearized reading' (duration: 171.662536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.363937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.320033ms","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:1936"} {"level":"info","ts":"2026-04-15T15:50:24.36397Z","caller":"traceutil/trace.go:171","msg":"trace[795777072] 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:18353; }","duration":"336.354244ms","start":"2026-04-15T15:50:24.027602Z","end":"2026-04-15T15:50:24.363956Z","steps":["trace[795777072] 'agreement among raft nodes before linearized reading' (duration: 336.259112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.364003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:50:24.027572Z","time spent":"336.423406ms","remote":"127.0.0.1:39408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1960,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-15T15:50:24.364086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.312104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T15:50:24.364126Z","caller":"traceutil/trace.go:171","msg":"trace[814424021] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:18353; }","duration":"565.360346ms","start":"2026-04-15T15:50:23.798757Z","end":"2026-04-15T15:50:24.364118Z","steps":["trace[814424021] 'agreement among raft nodes before linearized reading' (duration: 565.283174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.364148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:50:23.798677Z","time spent":"565.466088ms","remote":"127.0.0.1:46710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-15T15:50:24.364157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.883229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-15T15:50:24.364239Z","caller":"traceutil/trace.go:171","msg":"trace[1957770339] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:18353; }","duration":"578.990663ms","start":"2026-04-15T15:50:23.785227Z","end":"2026-04-15T15:50:24.364217Z","steps":["trace[1957770339] 'agreement among raft nodes before linearized reading' (duration: 578.817239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.364281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:50:23.785114Z","time spent":"579.158378ms","remote":"127.0.0.1:42268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"warn","ts":"2026-04-15T15:50:24.364288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.627089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-15T15:50:24.364318Z","caller":"traceutil/trace.go:171","msg":"trace[898430468] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:18353; }","duration":"645.707581ms","start":"2026-04-15T15:50:23.718602Z","end":"2026-04-15T15:50:24.36431Z","steps":["trace[898430468] 'agreement among raft nodes before linearized reading' (duration: 645.485935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T15:50:24.364341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T15:50:23.71858Z","time spent":"645.756723ms","remote":"127.0.0.1:39460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":191,"response size":33,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-15T15:52:07.966029Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"95a1d32286abc938","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-15T15:52:07.979762Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-15T15:52:07.97987Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-15T15:53:11.077016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17406} {"level":"info","ts":"2026-04-15T15:53:11.178778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17406,"took":"97.542638ms","hash":3372822120,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":34230272,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-15T15:53:11.178878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3372822120,"revision":17406,"compact-revision":14938} {"level":"info","ts":"2026-04-15T15:58:11.083298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19742} {"level":"info","ts":"2026-04-15T15:58:11.205368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19742,"took":"117.360491ms","hash":3050735637,"current-db-size-bytes":58605568,"current-db-size":"59 MB","current-db-size-in-use-bytes":27049984,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-15T15:58:11.205429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3050735637,"revision":19742,"compact-revision":17406}