{"level":"warn","ts":"2026-05-19T19:03:42.534846Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T19:03:42.534951Z","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-05-19T19:03:42.535067Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T19:03:42.535083Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-19T19:03:42.535123Z","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-05-19T19:03:42.535992Z","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-05-19T19:03:42.536869Z","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.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-05-19T19:03:42.542359Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.814923ms"} {"level":"info","ts":"2026-05-19T19:03:42.548435Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-05-19T19:03:42.548547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T19:03:42.548573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-05-19T19:03:42.548587Z","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-05-19T19:03:42.548597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-05-19T19:03:42.548641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-05-19T19:03:42.558171Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T19:03:42.561334Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T19:03:42.562553Z","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-05-19T19:03:42.565209Z","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-05-19T19:03:42.566358Z","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-05-19T19:03:42.566359Z","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-05-19T19:03:42.566507Z","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-05-19T19:03:42.566524Z","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-05-19T19:03:42.566967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-05-19T19:03:42.567134Z","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-05-19T19:03:42.571971Z","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-05-19T19:03:42.572117Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-19T19:03:42.572223Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-19T19:03:42.572201Z","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-05-19T19:03:42.572264Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T19:03:43.149632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T19:03:43.149701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T19:03:43.149735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-05-19T19:03:43.149755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-05-19T19:03:43.149767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-19T19:03:43.149784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-05-19T19:03:43.149798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-19T19:03:43.150723Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T19:03:43.151653Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T19:03:43.151652Z","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-05-19T19:03:43.151729Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T19:03:43.152244Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T19:03:43.152289Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T19:03:43.152277Z","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-05-19T19:03:43.152415Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T19:03:43.152458Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T19:03:43.155196Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-05-19T19:03:43.155308Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T19:04:18.537373Z","caller":"traceutil/trace.go:171","msg":"trace[318551126] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"168.59067ms","start":"2026-05-19T19:04:18.368748Z","end":"2026-05-19T19:04:18.537339Z","steps":["trace[318551126] 'read index received' (duration: 167.654714ms)","trace[318551126] 'applied index is now lower than readState.Index' (duration: 934.786µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:04:18.537503Z","caller":"traceutil/trace.go:171","msg":"trace[1703323482] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"193.907174ms","start":"2026-05-19T19:04:18.343548Z","end":"2026-05-19T19:04:18.537455Z","steps":["trace[1703323482] 'process raft request' (duration: 193.116872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:04:18.537571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.791485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:04:18.537662Z","caller":"traceutil/trace.go:171","msg":"trace[2147321419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"168.94602ms","start":"2026-05-19T19:04:18.368699Z","end":"2026-05-19T19:04:18.537645Z","steps":["trace[2147321419] 'agreement among raft nodes before linearized reading' (duration: 168.793645ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:04:18.757115Z","caller":"traceutil/trace.go:171","msg":"trace[1928677712] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"151.809062ms","start":"2026-05-19T19:04:18.605279Z","end":"2026-05-19T19:04:18.757088Z","steps":["trace[1928677712] 'process raft request' (duration: 151.662638ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:04:19.362434Z","caller":"traceutil/trace.go:171","msg":"trace[1934209980] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"418.527581ms","start":"2026-05-19T19:04:18.943879Z","end":"2026-05-19T19:04:19.362407Z","steps":["trace[1934209980] 'process raft request' (duration: 333.715032ms)","trace[1934209980] 'compare' (duration: 84.155021ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:04:19.362539Z","caller":"traceutil/trace.go:171","msg":"trace[2001615320] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"225.206174ms","start":"2026-05-19T19:04:19.137304Z","end":"2026-05-19T19:04:19.36251Z","steps":["trace[2001615320] 'read index received' (duration: 140.378555ms)","trace[2001615320] 'applied index is now lower than readState.Index' (duration: 84.825059ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:04:19.362754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.441001ms","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-05-19T19:04:19.362832Z","caller":"traceutil/trace.go:171","msg":"trace[226896414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"225.560263ms","start":"2026-05-19T19:04:19.137252Z","end":"2026-05-19T19:04:19.362812Z","steps":["trace[226896414] 'agreement among raft nodes before linearized reading' (duration: 225.362808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:04:19.362954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:04:18.94386Z","time spent":"418.637745ms","remote":"127.0.0.1:37752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:04:19.364112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.960635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-19T19:04:19.364153Z","caller":"traceutil/trace.go:171","msg":"trace[1402603867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"176.032007ms","start":"2026-05-19T19:04:19.18811Z","end":"2026-05-19T19:04:19.364142Z","steps":["trace[1402603867] 'agreement among raft nodes before linearized reading' (duration: 175.932194ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:04:19.364108Z","caller":"traceutil/trace.go:171","msg":"trace[1298448314] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"194.048118ms","start":"2026-05-19T19:04:19.170041Z","end":"2026-05-19T19:04:19.364089Z","steps":["trace[1298448314] 'process raft request' (duration: 192.898796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:04:19.364192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.41553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7bcpn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T19:04:19.364321Z","caller":"traceutil/trace.go:171","msg":"trace[1534017649] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7bcpn; range_end:; response_count:1; response_revision:581; }","duration":"157.569884ms","start":"2026-05-19T19:04:19.206744Z","end":"2026-05-19T19:04:19.364314Z","steps":["trace[1534017649] 'agreement among raft nodes before linearized reading' (duration: 157.379288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:05:50.086944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.983145ms","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-05-19T19:05:50.087074Z","caller":"traceutil/trace.go:171","msg":"trace[955553433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1814; }","duration":"152.160749ms","start":"2026-05-19T19:05:49.934885Z","end":"2026-05-19T19:05:50.087046Z","steps":["trace[955553433] 'range keys from in-memory index tree' (duration: 151.772388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:05:50.087124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.844058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-19T19:05:50.086944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.035034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-691a2a48-0769-4212-9c22-b73da8788683\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-19T19:05:50.086976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.326927ms","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-05-19T19:05:50.087194Z","caller":"traceutil/trace.go:171","msg":"trace[1725970894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1814; }","duration":"242.956001ms","start":"2026-05-19T19:05:49.844222Z","end":"2026-05-19T19:05:50.087178Z","steps":["trace[1725970894] 'range keys from in-memory index tree' (duration: 242.685544ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:05:50.087223Z","caller":"traceutil/trace.go:171","msg":"trace[656836725] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-691a2a48-0769-4212-9c22-b73da8788683; range_end:; response_count:1; response_revision:1814; }","duration":"260.355272ms","start":"2026-05-19T19:05:49.826836Z","end":"2026-05-19T19:05:50.087192Z","steps":["trace[656836725] 'range keys from in-memory index tree' (duration: 259.828077ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:05:50.087243Z","caller":"traceutil/trace.go:171","msg":"trace[1372561443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1814; }","duration":"249.618915ms","start":"2026-05-19T19:05:49.8376Z","end":"2026-05-19T19:05:50.087218Z","steps":["trace[1372561443] 'range keys from in-memory index tree' (duration: 249.207434ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:05:50.43489Z","caller":"traceutil/trace.go:171","msg":"trace[1560826546] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"175.595718ms","start":"2026-05-19T19:05:50.259257Z","end":"2026-05-19T19:05:50.434853Z","steps":["trace[1560826546] 'process raft request' (duration: 175.059194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:06:02.371563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.060257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:06:02.37166Z","caller":"traceutil/trace.go:171","msg":"trace[1875854092] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"150.632567ms","start":"2026-05-19T19:06:02.221013Z","end":"2026-05-19T19:06:02.371646Z","steps":["trace[1875854092] 'read index received' (duration: 41.275232ms)","trace[1875854092] 'applied index is now lower than readState.Index' (duration: 109.356185ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:06:02.371734Z","caller":"traceutil/trace.go:171","msg":"trace[2124483225] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"155.755149ms","start":"2026-05-19T19:06:02.21597Z","end":"2026-05-19T19:06:02.371726Z","steps":["trace[2124483225] 'process raft request' (duration: 46.376394ms)","trace[2124483225] 'compare' (duration: 108.916312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:06:02.37187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.848792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-19T19:06:02.37194Z","caller":"traceutil/trace.go:171","msg":"trace[501986592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1918; }","duration":"150.945875ms","start":"2026-05-19T19:06:02.22098Z","end":"2026-05-19T19:06:02.371925Z","steps":["trace[501986592] 'agreement among raft nodes before linearized reading' (duration: 150.788831ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:06:34.448883Z","caller":"traceutil/trace.go:171","msg":"trace[1658076970] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"110.656419ms","start":"2026-05-19T19:06:34.338169Z","end":"2026-05-19T19:06:34.448825Z","steps":["trace[1658076970] 'process raft request' (duration: 110.471674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:06:40.631039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.381382ms","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-05-19T19:06:40.631166Z","caller":"traceutil/trace.go:171","msg":"trace[1215238980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2138; }","duration":"159.543596ms","start":"2026-05-19T19:06:40.471592Z","end":"2026-05-19T19:06:40.631136Z","steps":["trace[1215238980] 'range keys from in-memory index tree' (duration: 159.216837ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:06:40.786009Z","caller":"traceutil/trace.go:171","msg":"trace[1669637852] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"150.581619ms","start":"2026-05-19T19:06:40.635402Z","end":"2026-05-19T19:06:40.785984Z","steps":["trace[1669637852] 'process raft request' (duration: 150.442975ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:06:40.813885Z","caller":"traceutil/trace.go:171","msg":"trace[866878457] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2224; }","duration":"112.12112ms","start":"2026-05-19T19:06:40.701735Z","end":"2026-05-19T19:06:40.813856Z","steps":["trace[866878457] 'read index received' (duration: 84.139219ms)","trace[866878457] 'applied index is now lower than readState.Index' (duration: 27.980671ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:06:40.814025Z","caller":"traceutil/trace.go:171","msg":"trace[1061280458] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"178.020065ms","start":"2026-05-19T19:06:40.635953Z","end":"2026-05-19T19:06:40.813973Z","steps":["trace[1061280458] 'process raft request' (duration: 177.799149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:06:40.814264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.447418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T19:06:40.814172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.865815ms","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-05-19T19:06:40.814325Z","caller":"traceutil/trace.go:171","msg":"trace[1344848579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2140; }","duration":"112.620713ms","start":"2026-05-19T19:06:40.701691Z","end":"2026-05-19T19:06:40.814312Z","steps":["trace[1344848579] 'agreement among raft nodes before linearized reading' (duration: 112.282504ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:06:40.814388Z","caller":"traceutil/trace.go:171","msg":"trace[208463850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2140; }","duration":"111.122412ms","start":"2026-05-19T19:06:40.70325Z","end":"2026-05-19T19:06:40.814373Z","steps":["trace[208463850] 'agreement among raft nodes before linearized reading' (duration: 110.799803ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:06:40.982228Z","caller":"traceutil/trace.go:171","msg":"trace[1916052834] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"163.505015ms","start":"2026-05-19T19:06:40.818683Z","end":"2026-05-19T19:06:40.982188Z","steps":["trace[1916052834] 'process raft request' (duration: 163.410282ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:06:40.982236Z","caller":"traceutil/trace.go:171","msg":"trace[1023797093] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"163.603848ms","start":"2026-05-19T19:06:40.818584Z","end":"2026-05-19T19:06:40.982188Z","steps":["trace[1023797093] 'process raft request' (duration: 134.049884ms)","trace[1023797093] 'compare' (duration: 29.264816ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:08:07.321948Z","caller":"traceutil/trace.go:171","msg":"trace[1435714388] linearizableReadLoop","detail":"{readStateIndex:2912; appliedIndex:2911; }","duration":"114.327046ms","start":"2026-05-19T19:08:07.207596Z","end":"2026-05-19T19:08:07.321923Z","steps":["trace[1435714388] 'read index received' (duration: 114.173872ms)","trace[1435714388] 'applied index is now lower than readState.Index' (duration: 152.024µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:08:07.322003Z","caller":"traceutil/trace.go:171","msg":"trace[484486640] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"122.377308ms","start":"2026-05-19T19:08:07.199586Z","end":"2026-05-19T19:08:07.321963Z","steps":["trace[484486640] 'process raft request' (duration: 122.183703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:08:07.322232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.620264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T19:08:07.322292Z","caller":"traceutil/trace.go:171","msg":"trace[390821447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2798; }","duration":"114.739387ms","start":"2026-05-19T19:08:07.207539Z","end":"2026-05-19T19:08:07.322279Z","steps":["trace[390821447] 'agreement among raft nodes before linearized reading' (duration: 114.530651ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:08:07.499698Z","caller":"traceutil/trace.go:171","msg":"trace[1800873568] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"132.953269ms","start":"2026-05-19T19:08:07.36672Z","end":"2026-05-19T19:08:07.499673Z","steps":["trace[1800873568] 'process raft request' (duration: 132.876567ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:08:07.499721Z","caller":"traceutil/trace.go:171","msg":"trace[182841927] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"173.306434ms","start":"2026-05-19T19:08:07.326383Z","end":"2026-05-19T19:08:07.499689Z","steps":["trace[182841927] 'process raft request' (duration: 173.082497ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:08:07.499924Z","caller":"traceutil/trace.go:171","msg":"trace[974657886] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"125.862024ms","start":"2026-05-19T19:08:07.374035Z","end":"2026-05-19T19:08:07.499897Z","steps":["trace[974657886] 'process raft request' (duration: 125.597227ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:52.72572Z","caller":"traceutil/trace.go:171","msg":"trace[1182927670] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"122.703951ms","start":"2026-05-19T19:11:52.602984Z","end":"2026-05-19T19:11:52.725688Z","steps":["trace[1182927670] 'process raft request' (duration: 122.414843ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:52.725703Z","caller":"traceutil/trace.go:171","msg":"trace[1161512043] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"121.368503ms","start":"2026-05-19T19:11:52.604273Z","end":"2026-05-19T19:11:52.725642Z","steps":["trace[1161512043] 'process raft request' (duration: 121.305652ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:52.904756Z","caller":"traceutil/trace.go:171","msg":"trace[1319674193] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"124.183612ms","start":"2026-05-19T19:11:52.780537Z","end":"2026-05-19T19:11:52.904721Z","steps":["trace[1319674193] 'process raft request' (duration: 63.038292ms)","trace[1319674193] 'compare' (duration: 60.886613ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:52.905005Z","caller":"traceutil/trace.go:171","msg":"trace[26192598] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"116.980682ms","start":"2026-05-19T19:11:52.787996Z","end":"2026-05-19T19:11:52.904977Z","steps":["trace[26192598] 'process raft request' (duration: 116.652233ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:58.572319Z","caller":"traceutil/trace.go:171","msg":"trace[1967503463] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"295.344042ms","start":"2026-05-19T19:11:58.276953Z","end":"2026-05-19T19:11:58.572297Z","steps":["trace[1967503463] 'process raft request' (duration: 295.198758ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:58.597945Z","caller":"traceutil/trace.go:171","msg":"trace[645219165] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5172; }","duration":"235.420582ms","start":"2026-05-19T19:11:58.362508Z","end":"2026-05-19T19:11:58.597929Z","steps":["trace[645219165] 'read index received' (duration: 209.692639ms)","trace[645219165] 'applied index is now lower than readState.Index' (duration: 25.727243ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:58.598045Z","caller":"traceutil/trace.go:171","msg":"trace[146828882] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"310.050619ms","start":"2026-05-19T19:11:58.287986Z","end":"2026-05-19T19:11:58.598037Z","steps":["trace[146828882] 'process raft request' (duration: 309.785892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:58.598122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:11:58.287965Z","time spent":"310.095261ms","remote":"127.0.0.1:37752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T19:11:58.598217Z","caller":"traceutil/trace.go:171","msg":"trace[29711058] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"250.81309ms","start":"2026-05-19T19:11:58.347399Z","end":"2026-05-19T19:11:58.598212Z","steps":["trace[29711058] 'process raft request' (duration: 250.47174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:58.598408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.908167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-75795f54-8b3a-49c1-8fa1-0e872fbec1b1\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-19T19:11:58.598428Z","caller":"traceutil/trace.go:171","msg":"trace[305372344] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-75795f54-8b3a-49c1-8fa1-0e872fbec1b1; range_end:; response_count:1; response_revision:4979; }","duration":"235.990049ms","start":"2026-05-19T19:11:58.362432Z","end":"2026-05-19T19:11:58.598422Z","steps":["trace[305372344] 'agreement among raft nodes before linearized reading' (duration: 235.907206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:58.598521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.032897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:58.598538Z","caller":"traceutil/trace.go:171","msg":"trace[292354797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4979; }","duration":"224.066318ms","start":"2026-05-19T19:11:58.374468Z","end":"2026-05-19T19:11:58.598534Z","steps":["trace[292354797] 'agreement among raft nodes before linearized reading' (duration: 224.040797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:58.888448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.827575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:11:58.888566Z","caller":"traceutil/trace.go:171","msg":"trace[676628781] linearizableReadLoop","detail":"{readStateIndex:5182; appliedIndex:5181; }","duration":"107.996182ms","start":"2026-05-19T19:11:58.780552Z","end":"2026-05-19T19:11:58.888548Z","steps":["trace[676628781] 'read index received' (duration: 46.212µs)","trace[676628781] 'applied index is now lower than readState.Index' (duration: 107.94879ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:11:58.88862Z","caller":"traceutil/trace.go:171","msg":"trace[3962040] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"132.382308ms","start":"2026-05-19T19:11:58.756194Z","end":"2026-05-19T19:11:58.888577Z","steps":["trace[3962040] 'process raft request' (duration: 23.332636ms)","trace[3962040] 'compare' (duration: 108.714633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:58.888689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.137146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:11:58.888749Z","caller":"traceutil/trace.go:171","msg":"trace[1764208564] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4985; }","duration":"108.226078ms","start":"2026-05-19T19:11:58.780509Z","end":"2026-05-19T19:11:58.888735Z","steps":["trace[1764208564] 'agreement among raft nodes before linearized reading' (duration: 108.116735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:11:59.21742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.211863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:11:59.21751Z","caller":"traceutil/trace.go:171","msg":"trace[1449677605] linearizableReadLoop","detail":"{readStateIndex:5183; appliedIndex:5182; }","duration":"163.319505ms","start":"2026-05-19T19:11:59.054176Z","end":"2026-05-19T19:11:59.217496Z","steps":["trace[1449677605] 'read index received' (duration: 43.201µs)","trace[1449677605] 'applied index is now lower than readState.Index' (duration: 163.275394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:11:59.217672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.502411ms","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-05-19T19:11:59.217697Z","caller":"traceutil/trace.go:171","msg":"trace[469644577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4986; }","duration":"163.566852ms","start":"2026-05-19T19:11:59.054123Z","end":"2026-05-19T19:11:59.21769Z","steps":["trace[469644577] 'agreement among raft nodes before linearized reading' (duration: 163.46677ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:11:59.217873Z","caller":"traceutil/trace.go:171","msg":"trace[470229588] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"220.138929ms","start":"2026-05-19T19:11:58.997691Z","end":"2026-05-19T19:11:59.21783Z","steps":["trace[470229588] 'process raft request' (duration: 26.448153ms)","trace[470229588] 'compare' (duration: 193.09727ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:12:16.267486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.045995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:2289"} {"level":"info","ts":"2026-05-19T19:12:16.267745Z","caller":"traceutil/trace.go:171","msg":"trace[1879131543] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"358.076841ms","start":"2026-05-19T19:12:15.909651Z","end":"2026-05-19T19:12:16.267728Z","steps":["trace[1879131543] 'process raft request' (duration: 357.982849ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:16.267696Z","caller":"traceutil/trace.go:171","msg":"trace[1062014291] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5337; }","duration":"392.180315ms","start":"2026-05-19T19:12:15.875465Z","end":"2026-05-19T19:12:16.267645Z","steps":["trace[1062014291] 'process raft request' (duration: 227.851352ms)","trace[1062014291] 'compare' (duration: 163.957293ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:12:16.267747Z","caller":"traceutil/trace.go:171","msg":"trace[639704938] linearizableReadLoop","detail":"{readStateIndex:5543; appliedIndex:5542; }","duration":"390.086487ms","start":"2026-05-19T19:12:15.87763Z","end":"2026-05-19T19:12:16.267716Z","steps":["trace[639704938] 'read index received' (duration: 26.38254ms)","trace[639704938] 'applied index is now lower than readState.Index' (duration: 363.689246ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:12:16.267806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:15.909622Z","time spent":"358.161614ms","remote":"127.0.0.1:37552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":840,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T19:12:16.26792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:15.875447Z","time spent":"392.35744ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":103,"response count":0,"response size":2312,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:12:16.268012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.366765ms","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-05-19T19:12:16.268069Z","caller":"traceutil/trace.go:171","msg":"trace[1351847327] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5338; }","duration":"390.460747ms","start":"2026-05-19T19:12:15.877594Z","end":"2026-05-19T19:12:16.268055Z","steps":["trace[1351847327] 'agreement among raft nodes before linearized reading' (duration: 390.258762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:16.268158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.894954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f9ba167a-6d54-479d-abb0-3a3d29e13c86\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-05-19T19:12:16.26814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.618226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-4g9nt\" ","response":"range_response_count:1 size:2596"} {"level":"info","ts":"2026-05-19T19:12:16.26819Z","caller":"traceutil/trace.go:171","msg":"trace[2116335838] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f9ba167a-6d54-479d-abb0-3a3d29e13c86; range_end:; response_count:1; response_revision:5338; }","duration":"240.966655ms","start":"2026-05-19T19:12:16.027214Z","end":"2026-05-19T19:12:16.26818Z","steps":["trace[2116335838] 'agreement among raft nodes before linearized reading' (duration: 240.854892ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:16.268221Z","caller":"traceutil/trace.go:171","msg":"trace[1518027515] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-4g9nt; range_end:; response_count:1; response_revision:5338; }","duration":"358.740909ms","start":"2026-05-19T19:12:15.909458Z","end":"2026-05-19T19:12:16.268199Z","steps":["trace[1518027515] 'agreement among raft nodes before linearized reading' (duration: 358.490912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:16.268386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.100824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4792"} {"level":"warn","ts":"2026-05-19T19:12:16.26842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:15.909439Z","time spent":"358.967106ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":2619,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-4g9nt\" "} {"level":"info","ts":"2026-05-19T19:12:16.268466Z","caller":"traceutil/trace.go:171","msg":"trace[1180759292] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5338; }","duration":"357.190106ms","start":"2026-05-19T19:12:15.911253Z","end":"2026-05-19T19:12:16.268443Z","steps":["trace[1180759292] 'agreement among raft nodes before linearized reading' (duration: 356.947189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:16.268155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:15.877582Z","time spent":"390.563931ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1154,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-05-19T19:12:16.26852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:15.911246Z","time spent":"357.263867ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":4815,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-05-19T19:12:16.269355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.127296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2214723"} {"level":"info","ts":"2026-05-19T19:12:16.269476Z","caller":"traceutil/trace.go:171","msg":"trace[1995985173] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5338; }","duration":"252.286009ms","start":"2026-05-19T19:12:16.017165Z","end":"2026-05-19T19:12:16.269451Z","steps":["trace[1995985173] 'agreement among raft nodes before linearized reading' (duration: 251.022244ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:16.626851Z","caller":"traceutil/trace.go:171","msg":"trace[1664179465] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"340.871034ms","start":"2026-05-19T19:12:16.285937Z","end":"2026-05-19T19:12:16.626808Z","steps":["trace[1664179465] 'process raft request' (duration: 241.921413ms)","trace[1664179465] 'compare' (duration: 98.709235ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:12:16.626885Z","caller":"traceutil/trace.go:171","msg":"trace[1367870859] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5340; }","duration":"249.527554ms","start":"2026-05-19T19:12:16.377324Z","end":"2026-05-19T19:12:16.626852Z","steps":["trace[1367870859] 'process raft request' (duration: 249.452792ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:16.626925Z","caller":"traceutil/trace.go:171","msg":"trace[1783978659] linearizableReadLoop","detail":"{readStateIndex:5546; appliedIndex:5545; }","duration":"263.832039ms","start":"2026-05-19T19:12:16.36307Z","end":"2026-05-19T19:12:16.626902Z","steps":["trace[1783978659] 'read index received' (duration: 164.787925ms)","trace[1783978659] 'applied index is now lower than readState.Index' (duration: 99.041004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:12:16.627049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:16.285926Z","time spent":"341.004888ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2414,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:12:16.627151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.503871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-19T19:12:16.627193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.243096ms","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:2429"} {"level":"info","ts":"2026-05-19T19:12:16.627304Z","caller":"traceutil/trace.go:171","msg":"trace[588332004] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5340; }","duration":"349.474101ms","start":"2026-05-19T19:12:16.277808Z","end":"2026-05-19T19:12:16.627282Z","steps":["trace[588332004] 'agreement among raft nodes before linearized reading' (duration: 349.220835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:16.627304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.161451ms","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":"warn","ts":"2026-05-19T19:12:16.627331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.712288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-05-19T19:12:16.627366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:16.277796Z","time spent":"349.552544ms","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2452,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"info","ts":"2026-05-19T19:12:16.627382Z","caller":"traceutil/trace.go:171","msg":"trace[584699766] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5340; }","duration":"350.269005ms","start":"2026-05-19T19:12:16.277091Z","end":"2026-05-19T19:12:16.62736Z","steps":["trace[584699766] 'agreement among raft nodes before linearized reading' (duration: 350.054209ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:16.62739Z","caller":"traceutil/trace.go:171","msg":"trace[355579837] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5340; }","duration":"323.79469ms","start":"2026-05-19T19:12:16.303574Z","end":"2026-05-19T19:12:16.627369Z","steps":["trace[355579837] 'agreement among raft nodes before linearized reading' (duration: 323.667336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:16.627431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:16.303533Z","time spent":"323.887263ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1840,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-05-19T19:12:16.627439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:16.277081Z","time spent":"350.335876ms","remote":"127.0.0.1:37560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1154,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"info","ts":"2026-05-19T19:12:16.627214Z","caller":"traceutil/trace.go:171","msg":"trace[42807525] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:5340; }","duration":"341.608384ms","start":"2026-05-19T19:12:16.28559Z","end":"2026-05-19T19:12:16.627198Z","steps":["trace[42807525] 'agreement among raft nodes before linearized reading' (duration: 341.401898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:16.627519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:16.285567Z","time spent":"341.938004ms","remote":"127.0.0.1:37496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"info","ts":"2026-05-19T19:12:16.744095Z","caller":"traceutil/trace.go:171","msg":"trace[1554432983] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"101.478791ms","start":"2026-05-19T19:12:16.642585Z","end":"2026-05-19T19:12:16.744064Z","steps":["trace[1554432983] 'process raft request' (duration: 101.253185ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.027832Z","caller":"traceutil/trace.go:171","msg":"trace[652269228] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"241.398617ms","start":"2026-05-19T19:12:28.786406Z","end":"2026-05-19T19:12:29.027805Z","steps":["trace[652269228] 'process raft request' (duration: 241.114759ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.028029Z","caller":"traceutil/trace.go:171","msg":"trace[485826538] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5712; }","duration":"233.770907ms","start":"2026-05-19T19:12:28.794225Z","end":"2026-05-19T19:12:29.027996Z","steps":["trace[485826538] 'read index received' (duration: 35.286508ms)","trace[485826538] 'applied index is now lower than readState.Index' (duration: 198.481928ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:12:29.028092Z","caller":"traceutil/trace.go:171","msg":"trace[1178214774] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"241.467479ms","start":"2026-05-19T19:12:28.786585Z","end":"2026-05-19T19:12:29.028053Z","steps":["trace[1178214774] 'process raft request' (duration: 241.168791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.028402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.966688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-05-19T19:12:29.028444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.996058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-05-19T19:12:29.028452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.918086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T19:12:29.028483Z","caller":"traceutil/trace.go:171","msg":"trace[748546609] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5504; }","duration":"166.09187ms","start":"2026-05-19T19:12:28.862373Z","end":"2026-05-19T19:12:29.028465Z","steps":["trace[748546609] 'agreement among raft nodes before linearized reading' (duration: 165.838413ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.028508Z","caller":"traceutil/trace.go:171","msg":"trace[1121875777] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5504; }","duration":"114.094741ms","start":"2026-05-19T19:12:28.914383Z","end":"2026-05-19T19:12:29.028478Z","steps":["trace[1121875777] 'agreement among raft nodes before linearized reading' (duration: 113.911746ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.028522Z","caller":"traceutil/trace.go:171","msg":"trace[823713762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5504; }","duration":"114.016458ms","start":"2026-05-19T19:12:28.914485Z","end":"2026-05-19T19:12:29.028501Z","steps":["trace[823713762] 'agreement among raft nodes before linearized reading' (duration: 113.800612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.028656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.427274ms","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-05-19T19:12:29.028856Z","caller":"traceutil/trace.go:171","msg":"trace[781284101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5504; }","duration":"234.637381ms","start":"2026-05-19T19:12:28.7942Z","end":"2026-05-19T19:12:29.028837Z","steps":["trace[781284101] 'agreement among raft nodes before linearized reading' (duration: 233.965172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.908892ms","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-05-19T19:12:29.029133Z","caller":"traceutil/trace.go:171","msg":"trace[2054244334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5504; }","duration":"111.068906ms","start":"2026-05-19T19:12:28.918047Z","end":"2026-05-19T19:12:29.029116Z","steps":["trace[2054244334] 'agreement among raft nodes before linearized reading' (duration: 110.161811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.266351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.945403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:12:29.266479Z","caller":"traceutil/trace.go:171","msg":"trace[240916776] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"173.931689ms","start":"2026-05-19T19:12:29.092522Z","end":"2026-05-19T19:12:29.266453Z","steps":["trace[240916776] 'process raft request' (duration: 31.788641ms)","trace[240916776] 'compare' (duration: 141.650094ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:12:29.31093Z","caller":"traceutil/trace.go:171","msg":"trace[1940611033] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"216.661301ms","start":"2026-05-19T19:12:29.094244Z","end":"2026-05-19T19:12:29.310905Z","steps":["trace[1940611033] 'process raft request' (duration: 216.520887ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.310973Z","caller":"traceutil/trace.go:171","msg":"trace[1490595487] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"216.761194ms","start":"2026-05-19T19:12:29.094176Z","end":"2026-05-19T19:12:29.310938Z","steps":["trace[1490595487] 'process raft request' (duration: 216.407384ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.311247Z","caller":"traceutil/trace.go:171","msg":"trace[916422409] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"215.972903ms","start":"2026-05-19T19:12:29.095237Z","end":"2026-05-19T19:12:29.31121Z","steps":["trace[916422409] 'process raft request' (duration: 215.620983ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.311419Z","caller":"traceutil/trace.go:171","msg":"trace[368270315] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"215.822079ms","start":"2026-05-19T19:12:29.095575Z","end":"2026-05-19T19:12:29.311397Z","steps":["trace[368270315] 'process raft request' (duration: 215.541621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.638686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.517791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:12:29.638798Z","caller":"traceutil/trace.go:171","msg":"trace[2106235142] linearizableReadLoop","detail":"{readStateIndex:5725; appliedIndex:5724; }","duration":"278.30292ms","start":"2026-05-19T19:12:29.360477Z","end":"2026-05-19T19:12:29.63878Z","steps":["trace[2106235142] 'read index received' (duration: 171.600584ms)","trace[2106235142] 'applied index is now lower than readState.Index' (duration: 106.698726ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:12:29.638858Z","caller":"traceutil/trace.go:171","msg":"trace[1595534512] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"325.374665ms","start":"2026-05-19T19:12:29.313451Z","end":"2026-05-19T19:12:29.638826Z","steps":["trace[1595534512] 'process raft request' (duration: 218.651868ms)","trace[1595534512] 'compare' (duration: 106.325556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:12:29.639011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.80004ms","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":"warn","ts":"2026-05-19T19:12:29.639013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:12:29.31342Z","time spent":"325.517169ms","remote":"127.0.0.1:37752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T19:12:29.638993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.504955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-19T19:12:29.639065Z","caller":"traceutil/trace.go:171","msg":"trace[665550999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5514; }","duration":"271.871823ms","start":"2026-05-19T19:12:29.367179Z","end":"2026-05-19T19:12:29.639051Z","steps":["trace[665550999] 'agreement among raft nodes before linearized reading' (duration: 271.700868ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.639085Z","caller":"traceutil/trace.go:171","msg":"trace[656543457] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5514; }","duration":"278.641739ms","start":"2026-05-19T19:12:29.360424Z","end":"2026-05-19T19:12:29.639065Z","steps":["trace[656543457] 'agreement among raft nodes before linearized reading' (duration: 278.429533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.639509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.016049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:12:29.639551Z","caller":"traceutil/trace.go:171","msg":"trace[751785652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5514; }","duration":"271.080751ms","start":"2026-05-19T19:12:29.36846Z","end":"2026-05-19T19:12:29.639541Z","steps":["trace[751785652] 'agreement among raft nodes before linearized reading' (duration: 271.016399ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.865546Z","caller":"traceutil/trace.go:171","msg":"trace[597526037] linearizableReadLoop","detail":"{readStateIndex:5729; appliedIndex:5726; }","duration":"170.294258ms","start":"2026-05-19T19:12:29.695229Z","end":"2026-05-19T19:12:29.865523Z","steps":["trace[597526037] 'read index received' (duration: 14.109031ms)","trace[597526037] 'applied index is now lower than readState.Index' (duration: 156.183987ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:12:29.865654Z","caller":"traceutil/trace.go:171","msg":"trace[1616758050] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"175.859763ms","start":"2026-05-19T19:12:29.689763Z","end":"2026-05-19T19:12:29.865623Z","steps":["trace[1616758050] 'process raft request' (duration: 175.596966ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:12:29.865679Z","caller":"traceutil/trace.go:171","msg":"trace[1153823950] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"175.785321ms","start":"2026-05-19T19:12:29.689862Z","end":"2026-05-19T19:12:29.865647Z","steps":["trace[1153823950] 'process raft request' (duration: 175.623636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.865738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.428262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:12:29.865827Z","caller":"traceutil/trace.go:171","msg":"trace[224354714] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5518; }","duration":"170.636747ms","start":"2026-05-19T19:12:29.695181Z","end":"2026-05-19T19:12:29.865818Z","steps":["trace[224354714] 'agreement among raft nodes before linearized reading' (duration: 170.439892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.865868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.102162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana.18b10d41efd48db9\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-05-19T19:12:29.865951Z","caller":"traceutil/trace.go:171","msg":"trace[1769615861] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana.18b10d41efd48db9; range_end:; response_count:1; response_revision:5518; }","duration":"153.224105ms","start":"2026-05-19T19:12:29.712701Z","end":"2026-05-19T19:12:29.865925Z","steps":["trace[1769615861] 'agreement among raft nodes before linearized reading' (duration: 153.01646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:12:29.866076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.449191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-19T19:12:29.866177Z","caller":"traceutil/trace.go:171","msg":"trace[456700938] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5518; }","duration":"114.566924ms","start":"2026-05-19T19:12:29.751561Z","end":"2026-05-19T19:12:29.866128Z","steps":["trace[456700938] 'agreement among raft nodes before linearized reading' (duration: 114.256765ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:13:43.18391Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2996} {"level":"info","ts":"2026-05-19T19:13:43.258138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2996,"took":"72.494295ms","hash":346037502,"current-db-size-bytes":58687488,"current-db-size":"59 MB","current-db-size-in-use-bytes":33755136,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T19:13:43.258222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":346037502,"revision":2996,"compact-revision":-1} {"level":"info","ts":"2026-05-19T19:18:23.73971Z","caller":"traceutil/trace.go:171","msg":"trace[424686186] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"259.266877ms","start":"2026-05-19T19:18:23.480416Z","end":"2026-05-19T19:18:23.739683Z","steps":["trace[424686186] 'process raft request' (duration: 259.099772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:18:35.158499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.077568ms","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-05-19T19:18:35.158612Z","caller":"traceutil/trace.go:171","msg":"trace[1334075263] linearizableReadLoop","detail":"{readStateIndex:8552; appliedIndex:8551; }","duration":"188.822194ms","start":"2026-05-19T19:18:34.969757Z","end":"2026-05-19T19:18:35.158579Z","steps":["trace[1334075263] 'read index received' (duration: 180.539935ms)","trace[1334075263] 'applied index is now lower than readState.Index' (duration: 8.280239ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:18:35.158635Z","caller":"traceutil/trace.go:171","msg":"trace[1494588165] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8253; }","duration":"257.232072ms","start":"2026-05-19T19:18:34.901383Z","end":"2026-05-19T19:18:35.158615Z","steps":["trace[1494588165] 'range keys from in-memory index tree' (duration: 257.061348ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:18:35.158648Z","caller":"traceutil/trace.go:171","msg":"trace[1489699841] transaction","detail":"{read_only:false; response_revision:8254; number_of_response:1; }","duration":"249.482647ms","start":"2026-05-19T19:18:34.909094Z","end":"2026-05-19T19:18:35.158577Z","steps":["trace[1489699841] 'process raft request' (duration: 241.157236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:18:35.158857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.085472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-19T19:18:35.158901Z","caller":"traceutil/trace.go:171","msg":"trace[1196791269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8254; }","duration":"189.204485ms","start":"2026-05-19T19:18:34.969686Z","end":"2026-05-19T19:18:35.158891Z","steps":["trace[1196791269] 'agreement among raft nodes before linearized reading' (duration: 188.994789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:18:36.171763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.052421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:18:36.171843Z","caller":"traceutil/trace.go:171","msg":"trace[1357718247] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8256; }","duration":"255.190025ms","start":"2026-05-19T19:18:35.916635Z","end":"2026-05-19T19:18:36.171825Z","steps":["trace[1357718247] 'count revisions from in-memory index tree' (duration: 254.904457ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:18:43.218501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6127} {"level":"info","ts":"2026-05-19T19:18:43.426487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6127,"took":"205.465707ms","hash":1251266745,"current-db-size-bytes":58687488,"current-db-size":"59 MB","current-db-size-in-use-bytes":23162880,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-19T19:18:43.426574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1251266745,"revision":6127,"compact-revision":2996} {"level":"info","ts":"2026-05-19T19:19:04.533598Z","caller":"traceutil/trace.go:171","msg":"trace[457071010] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"268.470814ms","start":"2026-05-19T19:19:04.265101Z","end":"2026-05-19T19:19:04.533572Z","steps":["trace[457071010] 'process raft request' (duration: 268.292969ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:19:04.533726Z","caller":"traceutil/trace.go:171","msg":"trace[245612092] linearizableReadLoop","detail":"{readStateIndex:8772; appliedIndex:8772; }","duration":"164.702567ms","start":"2026-05-19T19:19:04.36898Z","end":"2026-05-19T19:19:04.533683Z","steps":["trace[245612092] 'read index received' (duration: 164.685617ms)","trace[245612092] 'applied index is now lower than readState.Index' (duration: 13.75µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:19:04.533975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.975274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:19:04.534024Z","caller":"traceutil/trace.go:171","msg":"trace[729366695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8465; }","duration":"165.084447ms","start":"2026-05-19T19:19:04.368924Z","end":"2026-05-19T19:19:04.534009Z","steps":["trace[729366695] 'agreement among raft nodes before linearized reading' (duration: 164.900152ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:19:04.717916Z","caller":"traceutil/trace.go:171","msg":"trace[1827104693] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"268.134265ms","start":"2026-05-19T19:19:04.449757Z","end":"2026-05-19T19:19:04.717891Z","steps":["trace[1827104693] 'process raft request' (duration: 267.894628ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:19:04.718111Z","caller":"traceutil/trace.go:171","msg":"trace[798963299] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"267.228429ms","start":"2026-05-19T19:19:04.450872Z","end":"2026-05-19T19:19:04.7181Z","steps":["trace[798963299] 'process raft request' (duration: 266.979033ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:19:04.718089Z","caller":"traceutil/trace.go:171","msg":"trace[2022086207] linearizableReadLoop","detail":"{readStateIndex:8774; appliedIndex:8772; }","duration":"183.021644ms","start":"2026-05-19T19:19:04.535043Z","end":"2026-05-19T19:19:04.718065Z","steps":["trace[2022086207] 'read index received' (duration: 182.632194ms)","trace[2022086207] 'applied index is now lower than readState.Index' (duration: 387.87µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:19:04.71819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.131037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:19:04.718232Z","caller":"traceutil/trace.go:171","msg":"trace[237212546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8467; }","duration":"183.21405ms","start":"2026-05-19T19:19:04.535005Z","end":"2026-05-19T19:19:04.71822Z","steps":["trace[237212546] 'agreement among raft nodes before linearized reading' (duration: 183.133147ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:19:17.210677Z","caller":"traceutil/trace.go:171","msg":"trace[770694159] linearizableReadLoop","detail":"{readStateIndex:8889; appliedIndex:8888; }","duration":"124.256145ms","start":"2026-05-19T19:19:17.086393Z","end":"2026-05-19T19:19:17.21065Z","steps":["trace[770694159] 'read index received' (duration: 124.089841ms)","trace[770694159] 'applied index is now lower than readState.Index' (duration: 164.764µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:19:17.210792Z","caller":"traceutil/trace.go:171","msg":"trace[1389468681] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"142.569833ms","start":"2026-05-19T19:19:17.068178Z","end":"2026-05-19T19:19:17.210748Z","steps":["trace[1389468681] 'process raft request' (duration: 142.305666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:19:17.210875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.473091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T19:19:17.210913Z","caller":"traceutil/trace.go:171","msg":"trace[109564930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8576; }","duration":"124.552843ms","start":"2026-05-19T19:19:17.086348Z","end":"2026-05-19T19:19:17.210901Z","steps":["trace[109564930] 'agreement among raft nodes before linearized reading' (duration: 124.404119ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:19:45.35652Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f0.wal"} {"level":"info","ts":"2026-05-19T19:20:35.329469Z","caller":"traceutil/trace.go:171","msg":"trace[135928420] transaction","detail":"{read_only:false; response_revision:9334; number_of_response:1; }","duration":"235.858971ms","start":"2026-05-19T19:20:35.093577Z","end":"2026-05-19T19:20:35.329436Z","steps":["trace[135928420] 'process raft request' (duration: 235.688526ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:20:35.347423Z","caller":"traceutil/trace.go:171","msg":"trace[350256597] linearizableReadLoop","detail":"{readStateIndex:9669; appliedIndex:9667; }","duration":"172.536628ms","start":"2026-05-19T19:20:35.174861Z","end":"2026-05-19T19:20:35.347398Z","steps":["trace[350256597] 'read index received' (duration: 154.520989ms)","trace[350256597] 'applied index is now lower than readState.Index' (duration: 18.014159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:20:35.347688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.807956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-19T19:20:35.347735Z","caller":"traceutil/trace.go:171","msg":"trace[174589959] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9335; }","duration":"172.901029ms","start":"2026-05-19T19:20:35.174821Z","end":"2026-05-19T19:20:35.347722Z","steps":["trace[174589959] 'agreement among raft nodes before linearized reading' (duration: 172.734274ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:20:35.348279Z","caller":"traceutil/trace.go:171","msg":"trace[308977951] transaction","detail":"{read_only:false; response_revision:9335; number_of_response:1; }","duration":"246.498856ms","start":"2026-05-19T19:20:35.101032Z","end":"2026-05-19T19:20:35.347531Z","steps":["trace[308977951] 'process raft request' (duration: 246.232858ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:21:19.709101Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"90a0cb2044183f95","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T19:21:19.716062Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T19:21:19.716164Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T19:23:43.225002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8304} {"level":"info","ts":"2026-05-19T19:23:43.300683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8304,"took":"73.943056ms","hash":2513641542,"current-db-size-bytes":58687488,"current-db-size":"59 MB","current-db-size-in-use-bytes":25448448,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-19T19:23:43.300746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2513641542,"revision":8304,"compact-revision":6127} {"level":"warn","ts":"2026-05-19T19:24:16.649115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.673923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:24:16.64921Z","caller":"traceutil/trace.go:171","msg":"trace[1415067269] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:11170; }","duration":"108.810416ms","start":"2026-05-19T19:24:16.540378Z","end":"2026-05-19T19:24:16.649189Z","steps":["trace[1415067269] 'count revisions from in-memory index tree' (duration: 108.611341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:24:16.649237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.984267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T19:24:16.649341Z","caller":"traceutil/trace.go:171","msg":"trace[1477243389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11170; }","duration":"111.145342ms","start":"2026-05-19T19:24:16.538175Z","end":"2026-05-19T19:24:16.64932Z","steps":["trace[1477243389] 'range keys from in-memory index tree' (duration: 110.804502ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:24:17.144817Z","caller":"traceutil/trace.go:171","msg":"trace[785059964] transaction","detail":"{read_only:false; response_revision:11175; number_of_response:1; }","duration":"175.682917ms","start":"2026-05-19T19:24:16.969099Z","end":"2026-05-19T19:24:17.144782Z","steps":["trace[785059964] 'process raft request' (duration: 175.398339ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:28:43.234108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10798} {"level":"info","ts":"2026-05-19T19:28:43.313948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10798,"took":"77.194045ms","hash":1078263925,"current-db-size-bytes":58687488,"current-db-size":"59 MB","current-db-size-in-use-bytes":27770880,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T19:28:43.314025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1078263925,"revision":10798,"compact-revision":8304} {"level":"warn","ts":"2026-05-19T19:30:52.057821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.948216ms","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-05-19T19:30:52.057916Z","caller":"traceutil/trace.go:171","msg":"trace[37268503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13816; }","duration":"359.090569ms","start":"2026-05-19T19:30:51.698805Z","end":"2026-05-19T19:30:52.057895Z","steps":["trace[37268503] 'range keys from in-memory index tree' (duration: 358.823771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:30:52.057956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:30:51.698785Z","time spent":"359.161541ms","remote":"127.0.0.1:37752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T19:30:52.058175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.239089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:30:52.058515Z","caller":"traceutil/trace.go:171","msg":"trace[1006473337] linearizableReadLoop","detail":"{readStateIndex:14299; appliedIndex:14298; }","duration":"179.953766ms","start":"2026-05-19T19:30:51.878542Z","end":"2026-05-19T19:30:52.058496Z","steps":["trace[1006473337] 'read index received' (duration: 10.244524ms)","trace[1006473337] 'applied index is now lower than readState.Index' (duration: 169.707772ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:30:52.058632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.09497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-z6ck2\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-05-19T19:30:52.058661Z","caller":"traceutil/trace.go:171","msg":"trace[490410175] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-z6ck2; range_end:; response_count:1; response_revision:13817; }","duration":"180.165362ms","start":"2026-05-19T19:30:51.878487Z","end":"2026-05-19T19:30:52.058652Z","steps":["trace[490410175] 'agreement among raft nodes before linearized reading' (duration: 180.08746ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:30:52.058636Z","caller":"traceutil/trace.go:171","msg":"trace[1454370624] transaction","detail":"{read_only:false; response_revision:13817; number_of_response:1; }","duration":"228.483027ms","start":"2026-05-19T19:30:51.830096Z","end":"2026-05-19T19:30:52.058579Z","steps":["trace[1454370624] 'process raft request' (duration: 58.736644ms)","trace[1454370624] 'compare' (duration: 169.083285ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:31:12.704762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.221167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-19T19:31:12.704879Z","caller":"traceutil/trace.go:171","msg":"trace[530985740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14119; }","duration":"115.411481ms","start":"2026-05-19T19:31:12.589448Z","end":"2026-05-19T19:31:12.704859Z","steps":["trace[530985740] 'range keys from in-memory index tree' (duration: 114.9898ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:31:12.987798Z","caller":"traceutil/trace.go:171","msg":"trace[2096703514] linearizableReadLoop","detail":"{readStateIndex:14615; appliedIndex:14614; }","duration":"257.14063ms","start":"2026-05-19T19:31:12.730628Z","end":"2026-05-19T19:31:12.987768Z","steps":["trace[2096703514] 'read index received' (duration: 256.863952ms)","trace[2096703514] 'applied index is now lower than readState.Index' (duration: 275.598µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T19:31:12.987882Z","caller":"traceutil/trace.go:171","msg":"trace[94455447] transaction","detail":"{read_only:false; response_revision:14120; number_of_response:1; }","duration":"278.623764ms","start":"2026-05-19T19:31:12.709213Z","end":"2026-05-19T19:31:12.987836Z","steps":["trace[94455447] 'process raft request' (duration: 278.377137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:31:12.988191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.480154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-19T19:31:12.988185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.525941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8b63a654-55dc-4800-b862-165e4a128d52\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-19T19:31:12.988244Z","caller":"traceutil/trace.go:171","msg":"trace[7354098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14120; }","duration":"115.574327ms","start":"2026-05-19T19:31:12.872657Z","end":"2026-05-19T19:31:12.988231Z","steps":["trace[7354098] 'agreement among raft nodes before linearized reading' (duration: 115.460923ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:31:12.98828Z","caller":"traceutil/trace.go:171","msg":"trace[1403774506] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8b63a654-55dc-4800-b862-165e4a128d52; range_end:; response_count:1; response_revision:14120; }","duration":"257.671435ms","start":"2026-05-19T19:31:12.730589Z","end":"2026-05-19T19:31:12.98826Z","steps":["trace[1403774506] 'agreement among raft nodes before linearized reading' (duration: 257.340325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:31:12.988091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.306155ms","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-05-19T19:31:12.988614Z","caller":"traceutil/trace.go:171","msg":"trace[737799900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14120; }","duration":"176.894761ms","start":"2026-05-19T19:31:12.811708Z","end":"2026-05-19T19:31:12.988603Z","steps":["trace[737799900] 'agreement among raft nodes before linearized reading' (duration: 176.257223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:32:37.921295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.687058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T19:32:37.921431Z","caller":"traceutil/trace.go:171","msg":"trace[972567154] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14924; }","duration":"132.904835ms","start":"2026-05-19T19:32:37.788498Z","end":"2026-05-19T19:32:37.921403Z","steps":["trace[972567154] 'range keys from in-memory index tree' (duration: 132.548055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:32:38.160846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.174903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T19:32:38.160958Z","caller":"traceutil/trace.go:171","msg":"trace[1536713476] linearizableReadLoop","detail":"{readStateIndex:15443; appliedIndex:15441; }","duration":"236.563118ms","start":"2026-05-19T19:32:37.924373Z","end":"2026-05-19T19:32:38.160936Z","steps":["trace[1536713476] 'read index received' (duration: 86.615264ms)","trace[1536713476] 'applied index is now lower than readState.Index' (duration: 149.946624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:32:38.161232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.0423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T19:32:38.161273Z","caller":"traceutil/trace.go:171","msg":"trace[428816892] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:14926; }","duration":"191.128003ms","start":"2026-05-19T19:32:37.970134Z","end":"2026-05-19T19:32:38.161262Z","steps":["trace[428816892] 'agreement among raft nodes before linearized reading' (duration: 191.042261ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:32:38.16224Z","caller":"traceutil/trace.go:171","msg":"trace[1811756516] transaction","detail":"{read_only:false; response_revision:14926; number_of_response:1; }","duration":"239.527962ms","start":"2026-05-19T19:32:37.922697Z","end":"2026-05-19T19:32:38.162225Z","steps":["trace[1811756516] 'process raft request' (duration: 126.866028ms)","trace[1811756516] 'compare' (duration: 110.72063ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T19:32:38.162874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.492282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-19T19:32:38.162933Z","caller":"traceutil/trace.go:171","msg":"trace[951847918] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14926; }","duration":"238.584805ms","start":"2026-05-19T19:32:37.924336Z","end":"2026-05-19T19:32:38.162921Z","steps":["trace[951847918] 'agreement among raft nodes before linearized reading' (duration: 236.760734ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:32:38.557521Z","caller":"traceutil/trace.go:171","msg":"trace[972891173] transaction","detail":"{read_only:false; response_revision:14927; number_of_response:1; }","duration":"103.011058ms","start":"2026-05-19T19:32:38.454485Z","end":"2026-05-19T19:32:38.557496Z","steps":["trace[972891173] 'process raft request' (duration: 102.866214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T19:32:38.558429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.740865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737432"} {"level":"info","ts":"2026-05-19T19:32:38.558515Z","caller":"traceutil/trace.go:171","msg":"trace[2109491671] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14927; }","duration":"100.867468ms","start":"2026-05-19T19:32:38.457631Z","end":"2026-05-19T19:32:38.558498Z","steps":["trace[2109491671] 'agreement among raft nodes before linearized reading' (duration: 100.059716ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T19:33:43.271715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12981} {"level":"info","ts":"2026-05-19T19:33:43.363059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12981,"took":"89.247006ms","hash":1371623800,"current-db-size-bytes":58687488,"current-db-size":"59 MB","current-db-size-in-use-bytes":29708288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T19:33:43.363159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1371623800,"revision":12981,"compact-revision":10798} {"level":"info","ts":"2026-05-19T19:38:43.291751Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15355} {"level":"info","ts":"2026-05-19T19:38:43.39076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15355,"took":"94.991935ms","hash":978159195,"current-db-size-bytes":58687488,"current-db-size":"59 MB","current-db-size-in-use-bytes":31088640,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T19:38:43.39086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":978159195,"revision":15355,"compact-revision":12981} {"level":"info","ts":"2026-05-19T19:43:43.298495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17572} {"level":"info","ts":"2026-05-19T19:43:43.396125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17572,"took":"93.955396ms","hash":2084058547,"current-db-size-bytes":58687488,"current-db-size":"59 MB","current-db-size-in-use-bytes":26030080,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T19:43:43.396207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2084058547,"revision":17572,"compact-revision":15355} {"level":"info","ts":"2026-05-19T19:44:18.854615Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"90a0cb2044183f95","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T19:44:18.860208Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T19:44:18.860268Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}