{"level":"warn","ts":"2026-04-22T14:19:43.659228Z","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-22T14:19:43.659378Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-22T14:19:43.659607Z","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-22T14:19:43.659631Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-22T14:19:43.659655Z","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-22T14:19:43.660411Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-04-22T14:19:43.660539Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-22T14:19:43.664437Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.630776ms"} {"level":"info","ts":"2026-04-22T14:19:43.673036Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-04-22T14:19:43.67313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T14:19:43.673167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-04-22T14:19:43.673185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T14:19:43.673198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-04-22T14:19:43.673249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-04-22T14:19:43.677315Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T14:19:43.678393Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T14:19:43.679423Z","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-22T14:19:43.681174Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T14:19:43.681471Z","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-22T14:19:43.681545Z","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-22T14:19:43.681558Z","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-22T14:19:43.681468Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T14:19:43.682475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-04-22T14:19:43.684245Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-04-22T14:19:43.689906Z","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-22T14:19:43.690132Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T14:19:43.690173Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T14:19:43.690329Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-22T14:19:43.690399Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-04-22T14:19:44.174093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T14:19:44.174173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T14:19:44.174202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-04-22T14:19:44.174219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-04-22T14:19:44.174225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-22T14:19:44.174235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-04-22T14:19:44.174244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-04-22T14:19:44.179681Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T14:19:44.180861Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T14:19:44.180907Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T14:19:44.180947Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T14:19:44.181027Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T14:19:44.181062Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T14:19:44.181081Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T14:19:44.181534Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T14:19:44.181617Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T14:19:44.18432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T14:19:44.185848Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-04-22T14:19:59.910997Z","caller":"traceutil/trace.go:171","msg":"trace[1476231203] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:376; }","duration":"164.05082ms","start":"2026-04-22T14:19:59.74691Z","end":"2026-04-22T14:19:59.910961Z","steps":["trace[1476231203] 'read index received' (duration: 163.796287ms)","trace[1476231203] 'applied index is now lower than readState.Index' (duration: 253.293µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:19:59.911109Z","caller":"traceutil/trace.go:171","msg":"trace[1651830157] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"252.491725ms","start":"2026-04-22T14:19:59.658578Z","end":"2026-04-22T14:19:59.91107Z","steps":["trace[1651830157] 'process raft request' (duration: 252.198492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:19:59.911344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.368233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-22T14:19:59.911555Z","caller":"traceutil/trace.go:171","msg":"trace[343601885] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:364; }","duration":"164.694156ms","start":"2026-04-22T14:19:59.746832Z","end":"2026-04-22T14:19:59.911526Z","steps":["trace[343601885] 'agreement among raft nodes before linearized reading' (duration: 164.285062ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:20:00.201497Z","caller":"traceutil/trace.go:171","msg":"trace[1790015050] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"245.698932ms","start":"2026-04-22T14:19:59.955774Z","end":"2026-04-22T14:20:00.201473Z","steps":["trace[1790015050] 'read index received' (duration: 245.469798ms)","trace[1790015050] 'applied index is now lower than readState.Index' (duration: 228.114µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:20:00.201591Z","caller":"traceutil/trace.go:171","msg":"trace[1309472803] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"273.413543ms","start":"2026-04-22T14:19:59.928139Z","end":"2026-04-22T14:20:00.201552Z","steps":["trace[1309472803] 'process raft request' (duration: 273.185469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:20:00.201689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.033747ms","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-22T14:20:00.201733Z","caller":"traceutil/trace.go:171","msg":"trace[812626099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:365; }","duration":"127.150529ms","start":"2026-04-22T14:20:00.074572Z","end":"2026-04-22T14:20:00.201723Z","steps":["trace[812626099] 'agreement among raft nodes before linearized reading' (duration: 127.046158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:20:00.201701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.133006ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T14:20:00.201774Z","caller":"traceutil/trace.go:171","msg":"trace[581400362] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:365; }","duration":"126.219647ms","start":"2026-04-22T14:20:00.075544Z","end":"2026-04-22T14:20:00.201764Z","steps":["trace[581400362] 'agreement among raft nodes before linearized reading' (duration: 126.112826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:20:00.20176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.963245ms","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-22T14:20:00.201853Z","caller":"traceutil/trace.go:171","msg":"trace[600574336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:365; }","duration":"246.128726ms","start":"2026-04-22T14:19:59.955701Z","end":"2026-04-22T14:20:00.20183Z","steps":["trace[600574336] 'agreement among raft nodes before linearized reading' (duration: 245.921224ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:20:13.22801Z","caller":"traceutil/trace.go:171","msg":"trace[503822945] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"128.00455ms","start":"2026-04-22T14:20:13.099984Z","end":"2026-04-22T14:20:13.227988Z","steps":["trace[503822945] 'process raft request' (duration: 99.549833ms)","trace[503822945] 'compare' (duration: 28.354887ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:20:13.237429Z","caller":"traceutil/trace.go:171","msg":"trace[815990281] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"137.124492ms","start":"2026-04-22T14:20:13.100251Z","end":"2026-04-22T14:20:13.237376Z","steps":["trace[815990281] 'process raft request' (duration: 136.906739ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:20:13.237693Z","caller":"traceutil/trace.go:171","msg":"trace[70809286] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"122.362564ms","start":"2026-04-22T14:20:13.11532Z","end":"2026-04-22T14:20:13.237682Z","steps":["trace[70809286] 'process raft request' (duration: 122.02568ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:20:15.644197Z","caller":"traceutil/trace.go:171","msg":"trace[2077059128] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"114.244908ms","start":"2026-04-22T14:20:15.529927Z","end":"2026-04-22T14:20:15.644172Z","steps":["trace[2077059128] 'process raft request' (duration: 114.098067ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:20:19.114452Z","caller":"traceutil/trace.go:171","msg":"trace[1209742010] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"153.814865ms","start":"2026-04-22T14:20:18.960601Z","end":"2026-04-22T14:20:19.114416Z","steps":["trace[1209742010] 'process raft request' (duration: 153.589653ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:20:19.280232Z","caller":"traceutil/trace.go:171","msg":"trace[1868791170] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"101.16727ms","start":"2026-04-22T14:20:19.179046Z","end":"2026-04-22T14:20:19.280213Z","steps":["trace[1868791170] 'process raft request' (duration: 100.804207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:20:19.800171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.881135ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T14:20:19.800265Z","caller":"traceutil/trace.go:171","msg":"trace[2127691766] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:572; }","duration":"227.995246ms","start":"2026-04-22T14:20:19.572257Z","end":"2026-04-22T14:20:19.800252Z","steps":["trace[2127691766] 'range keys from in-memory index tree' (duration: 227.870375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:20:19.80102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.638522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T14:20:19.801288Z","caller":"traceutil/trace.go:171","msg":"trace[459525164] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"462.022699ms","start":"2026-04-22T14:20:19.339232Z","end":"2026-04-22T14:20:19.801255Z","steps":["trace[459525164] 'process raft request' (duration: 32.4982ms)","trace[459525164] 'compare' (duration: 428.450649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T14:20:19.801385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T14:20:19.339212Z","time spent":"462.14009ms","remote":"127.0.0.1:49890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T14:20:19.801464Z","caller":"traceutil/trace.go:171","msg":"trace[1072436178] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"183.981297ms","start":"2026-04-22T14:20:19.617445Z","end":"2026-04-22T14:20:19.801426Z","steps":["trace[1072436178] 'process raft request' (duration: 183.724004ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:20:19.801562Z","caller":"traceutil/trace.go:171","msg":"trace[12441713] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"178.654077ms","start":"2026-04-22T14:20:19.622901Z","end":"2026-04-22T14:20:19.801555Z","steps":["trace[12441713] 'process raft request' (duration: 178.415355ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:20:19.801551Z","caller":"traceutil/trace.go:171","msg":"trace[191791582] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:594; }","duration":"140.899129ms","start":"2026-04-22T14:20:19.660634Z","end":"2026-04-22T14:20:19.801533Z","steps":["trace[191791582] 'read index received' (duration: 131.293778ms)","trace[191791582] 'applied index is now lower than readState.Index' (duration: 9.604421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T14:20:19.801922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.241302ms","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-22T14:20:19.802076Z","caller":"traceutil/trace.go:171","msg":"trace[917358575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"141.442533ms","start":"2026-04-22T14:20:19.660607Z","end":"2026-04-22T14:20:19.80205Z","steps":["trace[917358575] 'agreement among raft nodes before linearized reading' (duration: 141.02976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:20:34.49047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.387367ms","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-22T14:20:34.49056Z","caller":"traceutil/trace.go:171","msg":"trace[1280799165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"108.535228ms","start":"2026-04-22T14:20:34.382006Z","end":"2026-04-22T14:20:34.490541Z","steps":["trace[1280799165] 'range keys from in-memory index tree' (duration: 108.202945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:20:34.490482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.719574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T14:20:34.490698Z","caller":"traceutil/trace.go:171","msg":"trace[898140565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"170.993926ms","start":"2026-04-22T14:20:34.31966Z","end":"2026-04-22T14:20:34.490654Z","steps":["trace[898140565] 'range keys from in-memory index tree' (duration: 170.628233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:20:34.490829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.949004ms","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-22T14:20:34.490971Z","caller":"traceutil/trace.go:171","msg":"trace[636274574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"107.131836ms","start":"2026-04-22T14:20:34.383814Z","end":"2026-04-22T14:20:34.490946Z","steps":["trace[636274574] 'range keys from in-memory index tree' (duration: 106.592612ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:21:06.825193Z","caller":"traceutil/trace.go:171","msg":"trace[590369746] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"157.25637ms","start":"2026-04-22T14:21:06.667912Z","end":"2026-04-22T14:21:06.825168Z","steps":["trace[590369746] 'process raft request' (duration: 157.045421ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:21:06.827801Z","caller":"traceutil/trace.go:171","msg":"trace[1796850029] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"159.155ms","start":"2026-04-22T14:21:06.668623Z","end":"2026-04-22T14:21:06.827778Z","steps":["trace[1796850029] 'process raft request' (duration: 159.045645ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:21:54.383287Z","caller":"traceutil/trace.go:171","msg":"trace[259241510] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"224.363749ms","start":"2026-04-22T14:21:54.1589Z","end":"2026-04-22T14:21:54.383263Z","steps":["trace[259241510] 'process raft request' (duration: 224.223796ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:21:54.390617Z","caller":"traceutil/trace.go:171","msg":"trace[2116693529] linearizableReadLoop","detail":"{readStateIndex:1901; appliedIndex:1899; }","duration":"129.180974ms","start":"2026-04-22T14:21:54.261418Z","end":"2026-04-22T14:21:54.390599Z","steps":["trace[2116693529] 'read index received' (duration: 121.719522ms)","trace[2116693529] 'applied index is now lower than readState.Index' (duration: 7.460712ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:21:54.390698Z","caller":"traceutil/trace.go:171","msg":"trace[891348021] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"157.744971ms","start":"2026-04-22T14:21:54.232926Z","end":"2026-04-22T14:21:54.390671Z","steps":["trace[891348021] 'process raft request' (duration: 157.598737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:21:54.390815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.382509ms","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-22T14:21:54.390872Z","caller":"traceutil/trace.go:171","msg":"trace[327939590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1830; }","duration":"129.479522ms","start":"2026-04-22T14:21:54.261377Z","end":"2026-04-22T14:21:54.390857Z","steps":["trace[327939590] 'agreement among raft nodes before linearized reading' (duration: 129.309997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:22:05.719008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.693ms","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-22T14:22:05.719381Z","caller":"traceutil/trace.go:171","msg":"trace[1750676793] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1930; }","duration":"125.038999ms","start":"2026-04-22T14:22:05.594231Z","end":"2026-04-22T14:22:05.71927Z","steps":["trace[1750676793] 'range keys from in-memory index tree' (duration: 124.513586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:22:09.239441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.968113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T14:22:09.239735Z","caller":"traceutil/trace.go:171","msg":"trace[328763414] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"178.154135ms","start":"2026-04-22T14:22:09.061541Z","end":"2026-04-22T14:22:09.239695Z","steps":["trace[328763414] 'process raft request' (duration: 60.63571ms)","trace[328763414] 'compare' (duration: 116.785119ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:22:09.240079Z","caller":"traceutil/trace.go:171","msg":"trace[474307482] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"166.99644ms","start":"2026-04-22T14:22:09.073061Z","end":"2026-04-22T14:22:09.240058Z","steps":["trace[474307482] 'process raft request' (duration: 166.563871ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:22:32.742472Z","caller":"traceutil/trace.go:171","msg":"trace[771181717] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"203.669809ms","start":"2026-04-22T14:22:32.538767Z","end":"2026-04-22T14:22:32.742437Z","steps":["trace[771181717] 'process raft request' (duration: 201.955355ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:22:32.814046Z","caller":"traceutil/trace.go:171","msg":"trace[612634424] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2169; }","duration":"221.498112ms","start":"2026-04-22T14:22:32.592516Z","end":"2026-04-22T14:22:32.814014Z","steps":["trace[612634424] 'read index received' (duration: 148.27344ms)","trace[612634424] 'applied index is now lower than readState.Index' (duration: 73.223202ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:22:32.814104Z","caller":"traceutil/trace.go:171","msg":"trace[129716091] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"273.002005ms","start":"2026-04-22T14:22:32.541057Z","end":"2026-04-22T14:22:32.814059Z","steps":["trace[129716091] 'process raft request' (duration: 272.71099ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:22:32.81416Z","caller":"traceutil/trace.go:171","msg":"trace[2144222844] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"251.926129ms","start":"2026-04-22T14:22:32.56219Z","end":"2026-04-22T14:22:32.814116Z","steps":["trace[2144222844] 'process raft request' (duration: 251.769166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:22:32.814342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.809808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-22T14:22:32.814425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.641755ms","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-22T14:22:32.814481Z","caller":"traceutil/trace.go:171","msg":"trace[809514728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2092; }","duration":"122.756777ms","start":"2026-04-22T14:22:32.691708Z","end":"2026-04-22T14:22:32.814465Z","steps":["trace[809514728] 'agreement among raft nodes before linearized reading' (duration: 122.589334ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:22:32.81465Z","caller":"traceutil/trace.go:171","msg":"trace[1070115403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2092; }","duration":"222.048383ms","start":"2026-04-22T14:22:32.592411Z","end":"2026-04-22T14:22:32.814459Z","steps":["trace[1070115403] 'agreement among raft nodes before linearized reading' (duration: 221.727217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:22:33.156492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.536302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T14:22:33.156684Z","caller":"traceutil/trace.go:171","msg":"trace[176877358] linearizableReadLoop","detail":"{readStateIndex:2174; appliedIndex:2172; }","duration":"244.042217ms","start":"2026-04-22T14:22:32.912619Z","end":"2026-04-22T14:22:33.156661Z","steps":["trace[176877358] 'read index received' (duration: 33.323472ms)","trace[176877358] 'applied index is now lower than readState.Index' (duration: 210.717835ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:22:33.156752Z","caller":"traceutil/trace.go:171","msg":"trace[353980813] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"336.771037ms","start":"2026-04-22T14:22:32.819918Z","end":"2026-04-22T14:22:33.156689Z","steps":["trace[353980813] 'process raft request' (duration: 125.938949ms)","trace[353980813] 'compare' (duration: 210.383639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T14:22:33.157655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T14:22:32.8199Z","time spent":"337.541061ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T14:22:33.156774Z","caller":"traceutil/trace.go:171","msg":"trace[871015773] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"336.537531ms","start":"2026-04-22T14:22:32.820198Z","end":"2026-04-22T14:22:33.156736Z","steps":["trace[871015773] 'process raft request' (duration: 336.399048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:22:33.157249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.478905ms","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-22T14:22:33.157886Z","caller":"traceutil/trace.go:171","msg":"trace[1354328247] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2094; }","duration":"245.308212ms","start":"2026-04-22T14:22:32.912553Z","end":"2026-04-22T14:22:33.157861Z","steps":["trace[1354328247] 'agreement among raft nodes before linearized reading' (duration: 244.214881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:22:33.158809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T14:22:32.820168Z","time spent":"337.582511ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T14:22:33.176067Z","caller":"traceutil/trace.go:171","msg":"trace[1381753384] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"200.526176ms","start":"2026-04-22T14:22:32.975519Z","end":"2026-04-22T14:22:33.176045Z","steps":["trace[1381753384] 'process raft request' (duration: 200.383343ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:23:23.55079Z","caller":"traceutil/trace.go:171","msg":"trace[2064194854] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2561; }","duration":"163.951852ms","start":"2026-04-22T14:23:23.386812Z","end":"2026-04-22T14:23:23.550763Z","steps":["trace[2064194854] 'read index received' (duration: 163.697869ms)","trace[2064194854] 'applied index is now lower than readState.Index' (duration: 252.913µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:23:23.550887Z","caller":"traceutil/trace.go:171","msg":"trace[620035133] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"201.458161ms","start":"2026-04-22T14:23:23.349399Z","end":"2026-04-22T14:23:23.550857Z","steps":["trace[620035133] 'process raft request' (duration: 201.196947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:23:23.551092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.550321ms","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-22T14:23:23.551179Z","caller":"traceutil/trace.go:171","msg":"trace[2037384274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2470; }","duration":"154.680393ms","start":"2026-04-22T14:23:23.39648Z","end":"2026-04-22T14:23:23.551161Z","steps":["trace[2037384274] 'agreement among raft nodes before linearized reading' (duration: 154.48062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:23:23.551581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.586741ms","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-22T14:23:23.551623Z","caller":"traceutil/trace.go:171","msg":"trace[124020314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2470; }","duration":"164.839895ms","start":"2026-04-22T14:23:23.386771Z","end":"2026-04-22T14:23:23.551611Z","steps":["trace[124020314] 'agreement among raft nodes before linearized reading' (duration: 164.5236ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:23:57.06388Z","caller":"traceutil/trace.go:171","msg":"trace[2064940764] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"173.780535ms","start":"2026-04-22T14:23:56.890077Z","end":"2026-04-22T14:23:57.063858Z","steps":["trace[2064940764] 'process raft request' (duration: 173.582713ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:37.516515Z","caller":"traceutil/trace.go:171","msg":"trace[1445985488] linearizableReadLoop","detail":"{readStateIndex:4686; appliedIndex:4685; }","duration":"125.67796ms","start":"2026-04-22T14:27:37.39081Z","end":"2026-04-22T14:27:37.516488Z","steps":["trace[1445985488] 'read index received' (duration: 125.328103ms)","trace[1445985488] 'applied index is now lower than readState.Index' (duration: 348.777µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:27:37.516584Z","caller":"traceutil/trace.go:171","msg":"trace[1537932179] transaction","detail":"{read_only:false; response_revision:4512; number_of_response:1; }","duration":"128.404432ms","start":"2026-04-22T14:27:37.388136Z","end":"2026-04-22T14:27:37.51654Z","steps":["trace[1537932179] 'process raft request' (duration: 128.168517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:37.516786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.927714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T14:27:37.516828Z","caller":"traceutil/trace.go:171","msg":"trace[642785864] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:4512; }","duration":"126.039547ms","start":"2026-04-22T14:27:37.390778Z","end":"2026-04-22T14:27:37.516818Z","steps":["trace[642785864] 'agreement among raft nodes before linearized reading' (duration: 125.859923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:37.757373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.553881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T14:27:37.757553Z","caller":"traceutil/trace.go:171","msg":"trace[1184069094] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:4513; }","duration":"106.788105ms","start":"2026-04-22T14:27:37.650739Z","end":"2026-04-22T14:27:37.757527Z","steps":["trace[1184069094] 'count revisions from in-memory index tree' (duration: 106.376657ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:43.597262Z","caller":"traceutil/trace.go:171","msg":"trace[1330230921] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"307.335096ms","start":"2026-04-22T14:27:43.289903Z","end":"2026-04-22T14:27:43.597238Z","steps":["trace[1330230921] 'process raft request' (duration: 307.189883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:43.597404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T14:27:43.289885Z","time spent":"307.450617ms","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T14:27:43.607363Z","caller":"traceutil/trace.go:171","msg":"trace[1938191368] transaction","detail":"{read_only:false; response_revision:4621; number_of_response:1; }","duration":"267.910316ms","start":"2026-04-22T14:27:43.339433Z","end":"2026-04-22T14:27:43.607344Z","steps":["trace[1938191368] 'process raft request' (duration: 267.713833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:44.201292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.140518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T14:27:44.20158Z","caller":"traceutil/trace.go:171","msg":"trace[381322757] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"215.815512ms","start":"2026-04-22T14:27:43.985732Z","end":"2026-04-22T14:27:44.201548Z","steps":["trace[381322757] 'process raft request' (duration: 13.352088ms)","trace[381322757] 'compare' (duration: 201.934444ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:27:44.20211Z","caller":"traceutil/trace.go:171","msg":"trace[1573800829] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"201.915424ms","start":"2026-04-22T14:27:43.999942Z","end":"2026-04-22T14:27:44.201857Z","steps":["trace[1573800829] 'process raft request' (duration: 201.470606ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:44.23942Z","caller":"traceutil/trace.go:171","msg":"trace[425250920] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"237.835459ms","start":"2026-04-22T14:27:44.001562Z","end":"2026-04-22T14:27:44.239398Z","steps":["trace[425250920] 'process raft request' (duration: 236.957432ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:44.409292Z","caller":"traceutil/trace.go:171","msg":"trace[238803839] linearizableReadLoop","detail":"{readStateIndex:4805; appliedIndex:4804; }","duration":"131.650374ms","start":"2026-04-22T14:27:44.277618Z","end":"2026-04-22T14:27:44.409269Z","steps":["trace[238803839] 'read index received' (duration: 128.343304ms)","trace[238803839] 'applied index is now lower than readState.Index' (duration: 3.30601ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T14:27:44.409398Z","caller":"traceutil/trace.go:171","msg":"trace[1963596666] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"148.346294ms","start":"2026-04-22T14:27:44.261041Z","end":"2026-04-22T14:27:44.409388Z","steps":["trace[1963596666] 'process raft request' (duration: 145.125654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:44.409668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.026151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-22T14:27:44.409909Z","caller":"traceutil/trace.go:171","msg":"trace[2002529509] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4629; }","duration":"132.309717ms","start":"2026-04-22T14:27:44.277582Z","end":"2026-04-22T14:27:44.409892Z","steps":["trace[2002529509] 'agreement among raft nodes before linearized reading' (duration: 131.94641ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:44.43347Z","caller":"traceutil/trace.go:171","msg":"trace[2002932791] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"155.67936ms","start":"2026-04-22T14:27:44.277765Z","end":"2026-04-22T14:27:44.433444Z","steps":["trace[2002932791] 'process raft request' (duration: 155.530867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:44.433762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.057737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T14:27:44.43435Z","caller":"traceutil/trace.go:171","msg":"trace[63607666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4630; }","duration":"115.7535ms","start":"2026-04-22T14:27:44.318576Z","end":"2026-04-22T14:27:44.434329Z","steps":["trace[63607666] 'agreement among raft nodes before linearized reading' (duration: 115.058587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:44.513796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.354166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f3527cc4-db10-4cdd-9487-2c82e66150ec\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-22T14:27:44.517356Z","caller":"traceutil/trace.go:171","msg":"trace[536689749] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f3527cc4-db10-4cdd-9487-2c82e66150ec; range_end:; response_count:1; response_revision:4634; }","duration":"103.981703ms","start":"2026-04-22T14:27:44.413337Z","end":"2026-04-22T14:27:44.517319Z","steps":["trace[536689749] 'agreement among raft nodes before linearized reading' (duration: 100.186942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T14:27:46.067266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.684144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d0f9b1a7-93e8-4cde-85b3-a2ac4c8df342\" ","response":"range_response_count:1 size:3936"} {"level":"info","ts":"2026-04-22T14:27:46.067346Z","caller":"traceutil/trace.go:171","msg":"trace[1350614604] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d0f9b1a7-93e8-4cde-85b3-a2ac4c8df342; range_end:; response_count:1; response_revision:4653; }","duration":"114.821457ms","start":"2026-04-22T14:27:45.952503Z","end":"2026-04-22T14:27:46.067324Z","steps":["trace[1350614604] 'range keys from in-memory index tree' (duration: 114.4667ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:27:56.767831Z","caller":"traceutil/trace.go:171","msg":"trace[1905314839] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"172.900849ms","start":"2026-04-22T14:27:56.594901Z","end":"2026-04-22T14:27:56.767802Z","steps":["trace[1905314839] 'process raft request' (duration: 172.286668ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T14:29:44.412514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2908} {"level":"info","ts":"2026-04-22T14:29:44.537395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2908,"took":"122.964435ms","hash":3491298996,"current-db-size-bytes":54358016,"current-db-size":"54 MB","current-db-size-in-use-bytes":29392896,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-22T14:29:44.537474Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3491298996,"revision":2908,"compact-revision":-1} {"level":"info","ts":"2026-04-22T14:34:44.418372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5620} {"level":"info","ts":"2026-04-22T14:34:44.495668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5620,"took":"75.756874ms","hash":1794982568,"current-db-size-bytes":54358016,"current-db-size":"54 MB","current-db-size-in-use-bytes":17948672,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-22T14:34:44.495722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1794982568,"revision":5620,"compact-revision":2908}