{"level":"warn","ts":"2026-04-20T10:10:13.463197Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T10:10:13.463354Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.115:2380","--initial-cluster=instance=https://199.19.213.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T10:10:13.463462Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T10:10:13.46348Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.115:2380"]} {"level":"info","ts":"2026-04-20T10:10:13.463549Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T10:10:13.46444Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.115:2379"]} {"level":"info","ts":"2026-04-20T10:10:13.464539Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.115:2380"],"listen-peer-urls":["https://199.19.213.115:2380"],"advertise-client-urls":["https://199.19.213.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T10:10:13.477884Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.063652ms"} {"level":"info","ts":"2026-04-20T10:10:13.489747Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"76964ef0a001678d","cluster-id":"a26f37ac4c69834d"} {"level":"info","ts":"2026-04-20T10:10:13.489859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T10:10:13.48996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became follower at term 0"} {"level":"info","ts":"2026-04-20T10:10:13.49002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 76964ef0a001678d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T10:10:13.490028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became follower at term 1"} {"level":"info","ts":"2026-04-20T10:10:13.490073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d switched to configuration voters=(8545104138365659021)"} {"level":"warn","ts":"2026-04-20T10:10:13.495756Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T10:10:13.499964Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T10:10:13.503569Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T10:10:13.50784Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"76964ef0a001678d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T10:10:13.507982Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T10:10:13.508045Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T10:10:13.508056Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T10:10:13.508302Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"76964ef0a001678d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T10:10:13.510604Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T10:10:13.51068Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.115:2380"} {"level":"info","ts":"2026-04-20T10:10:13.510722Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.115:2380"} {"level":"info","ts":"2026-04-20T10:10:13.51084Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"76964ef0a001678d","initial-advertise-peer-urls":["https://199.19.213.115:2380"],"listen-peer-urls":["https://199.19.213.115:2380"],"advertise-client-urls":["https://199.19.213.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T10:10:13.510876Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T10:10:13.511872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d switched to configuration voters=(8545104138365659021)"} {"level":"info","ts":"2026-04-20T10:10:13.512123Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a26f37ac4c69834d","local-member-id":"76964ef0a001678d","added-peer-id":"76964ef0a001678d","added-peer-peer-urls":["https://199.19.213.115:2380"]} {"level":"info","ts":"2026-04-20T10:10:13.690636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T10:10:13.691053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T10:10:13.691213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d received MsgPreVoteResp from 76964ef0a001678d at term 1"} {"level":"info","ts":"2026-04-20T10:10:13.691319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became candidate at term 2"} {"level":"info","ts":"2026-04-20T10:10:13.691401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d received MsgVoteResp from 76964ef0a001678d at term 2"} {"level":"info","ts":"2026-04-20T10:10:13.691496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76964ef0a001678d became leader at term 2"} {"level":"info","ts":"2026-04-20T10:10:13.691612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 76964ef0a001678d elected leader 76964ef0a001678d at term 2"} {"level":"info","ts":"2026-04-20T10:10:13.704996Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"76964ef0a001678d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.115:2379]}","request-path":"/0/members/76964ef0a001678d/attributes","cluster-id":"a26f37ac4c69834d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T10:10:13.705022Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:10:13.705321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:10:13.705422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T10:10:13.705594Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T10:10:13.705639Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T10:10:13.708679Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.115:2379"} {"level":"info","ts":"2026-04-20T10:10:13.708955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T10:10:13.72221Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a26f37ac4c69834d","local-member-id":"76964ef0a001678d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:10:13.722339Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:10:13.722386Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T10:10:17.907051Z","caller":"traceutil/trace.go:171","msg":"trace[1439105677] linearizableReadLoop","detail":"{readStateIndex:219; appliedIndex:218; }","duration":"121.013721ms","start":"2026-04-20T10:10:17.786011Z","end":"2026-04-20T10:10:17.907025Z","steps":["trace[1439105677] 'read index received' (duration: 112.329918ms)","trace[1439105677] 'applied index is now lower than readState.Index' (duration: 8.682793ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:10:17.907088Z","caller":"traceutil/trace.go:171","msg":"trace[2093497812] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"123.339507ms","start":"2026-04-20T10:10:17.783714Z","end":"2026-04-20T10:10:17.907054Z","steps":["trace[2093497812] 'process raft request' (duration: 114.639594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:17.907389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.344935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-20T10:10:17.907461Z","caller":"traceutil/trace.go:171","msg":"trace[659441063] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:213; }","duration":"121.440407ms","start":"2026-04-20T10:10:17.786007Z","end":"2026-04-20T10:10:17.907448Z","steps":["trace[659441063] 'agreement among raft nodes before linearized reading' (duration: 121.131242ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:18.237846Z","caller":"traceutil/trace.go:171","msg":"trace[671148215] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"116.878398ms","start":"2026-04-20T10:10:18.120941Z","end":"2026-04-20T10:10:18.237819Z","steps":["trace[671148215] 'process raft request' (duration: 112.705995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:43.323015Z","caller":"traceutil/trace.go:171","msg":"trace[986983337] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"116.143994ms","start":"2026-04-20T10:10:43.20685Z","end":"2026-04-20T10:10:43.322994Z","steps":["trace[986983337] 'process raft request' (duration: 109.484025ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:43.323374Z","caller":"traceutil/trace.go:171","msg":"trace[1588707591] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"104.028066ms","start":"2026-04-20T10:10:43.2193Z","end":"2026-04-20T10:10:43.323328Z","steps":["trace[1588707591] 'process raft request' (duration: 103.753983ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:44.367889Z","caller":"traceutil/trace.go:171","msg":"trace[1970136210] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"123.728399ms","start":"2026-04-20T10:10:44.244142Z","end":"2026-04-20T10:10:44.36787Z","steps":["trace[1970136210] 'process raft request' (duration: 116.903629ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:46.524023Z","caller":"traceutil/trace.go:171","msg":"trace[42693835] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"237.465244ms","start":"2026-04-20T10:10:46.286535Z","end":"2026-04-20T10:10:46.524001Z","steps":["trace[42693835] 'process raft request' (duration: 237.01283ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:49.262083Z","caller":"traceutil/trace.go:171","msg":"trace[893873876] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"240.844246ms","start":"2026-04-20T10:10:49.021218Z","end":"2026-04-20T10:10:49.262062Z","steps":["trace[893873876] 'process raft request' (duration: 240.680935ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:49.32065Z","caller":"traceutil/trace.go:171","msg":"trace[1779684131] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"263.149018ms","start":"2026-04-20T10:10:49.057477Z","end":"2026-04-20T10:10:49.320626Z","steps":["trace[1779684131] 'process raft request' (duration: 262.641253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:49.877799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.570024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:10:49.877895Z","caller":"traceutil/trace.go:171","msg":"trace[1492241847] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"503.138045ms","start":"2026-04-20T10:10:49.374739Z","end":"2026-04-20T10:10:49.877877Z","steps":["trace[1492241847] 'process raft request' (duration: 259.048477ms)","trace[1492241847] 'compare' (duration: 243.386541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:10:49.878225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:49.374719Z","time spent":"503.383467ms","remote":"127.0.0.1:53400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:10:49.882298Z","caller":"traceutil/trace.go:171","msg":"trace[1516461992] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"397.51168ms","start":"2026-04-20T10:10:49.48475Z","end":"2026-04-20T10:10:49.882262Z","steps":["trace[1516461992] 'process raft request' (duration: 397.327908ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:10:49.882512Z","caller":"traceutil/trace.go:171","msg":"trace[805028334] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"254.483092ms","start":"2026-04-20T10:10:49.627998Z","end":"2026-04-20T10:10:49.882481Z","steps":["trace[805028334] 'read index received' (duration: 5.803448ms)","trace[805028334] 'applied index is now lower than readState.Index' (duration: 248.678154ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:10:49.882621Z","caller":"traceutil/trace.go:171","msg":"trace[1795942700] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"294.69114ms","start":"2026-04-20T10:10:49.587918Z","end":"2026-04-20T10:10:49.882609Z","steps":["trace[1795942700] 'process raft request' (duration: 294.298986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:10:49.882739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:10:49.484726Z","time spent":"397.667171ms","remote":"127.0.0.1:53296","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-04-20T10:10:49.883596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.474201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:10:49.884223Z","caller":"traceutil/trace.go:171","msg":"trace[1484200479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"256.189128ms","start":"2026-04-20T10:10:49.627967Z","end":"2026-04-20T10:10:49.884156Z","steps":["trace[1484200479] 'agreement among raft nodes before linearized reading' (duration: 254.606853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:04.30596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.723464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4prv2\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-20T10:11:04.306035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.847488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:11:04.306059Z","caller":"traceutil/trace.go:171","msg":"trace[1473572876] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4prv2; range_end:; response_count:1; response_revision:733; }","duration":"206.858615ms","start":"2026-04-20T10:11:04.09918Z","end":"2026-04-20T10:11:04.306038Z","steps":["trace[1473572876] 'range keys from in-memory index tree' (duration: 206.546943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:04.306054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.857512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:11:04.306125Z","caller":"traceutil/trace.go:171","msg":"trace[1290966435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"335.980319ms","start":"2026-04-20T10:11:03.970127Z","end":"2026-04-20T10:11:04.306107Z","steps":["trace[1290966435] 'range keys from in-memory index tree' (duration: 335.701717ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:04.306153Z","caller":"traceutil/trace.go:171","msg":"trace[878594803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"307.009313ms","start":"2026-04-20T10:11:03.999126Z","end":"2026-04-20T10:11:04.306136Z","steps":["trace[878594803] 'range keys from in-memory index tree' (duration: 306.66766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:04.306176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:03.97011Z","time spent":"336.05694ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:11:04.306195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:03.999104Z","time spent":"307.082045ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T10:11:04.306684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.441972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:11:04.306905Z","caller":"traceutil/trace.go:171","msg":"trace[74805151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"311.670044ms","start":"2026-04-20T10:11:03.99519Z","end":"2026-04-20T10:11:04.30686Z","steps":["trace[74805151] 'range keys from in-memory index tree' (duration: 310.512285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:04.306946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:03.995169Z","time spent":"311.764895ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T10:11:33.161699Z","caller":"traceutil/trace.go:171","msg":"trace[156026711] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"112.848789ms","start":"2026-04-20T10:11:33.048825Z","end":"2026-04-20T10:11:33.161674Z","steps":["trace[156026711] 'process raft request' (duration: 112.686365ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:50.57575Z","caller":"traceutil/trace.go:171","msg":"trace[1748126341] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"141.61347ms","start":"2026-04-20T10:11:50.434108Z","end":"2026-04-20T10:11:50.575722Z","steps":["trace[1748126341] 'read index received' (duration: 129.548052ms)","trace[1748126341] 'applied index is now lower than readState.Index' (duration: 12.064458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:50.576056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.904884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-04-20T10:11:50.575874Z","caller":"traceutil/trace.go:171","msg":"trace[1940362938] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"245.030573ms","start":"2026-04-20T10:11:50.33081Z","end":"2026-04-20T10:11:50.57584Z","steps":["trace[1940362938] 'process raft request' (duration: 232.880343ms)","trace[1940362938] 'compare' (duration: 11.777601ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:11:50.576075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.941068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1020"} {"level":"info","ts":"2026-04-20T10:11:50.576126Z","caller":"traceutil/trace.go:171","msg":"trace[1629191885] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:1350; }","duration":"141.013917ms","start":"2026-04-20T10:11:50.43509Z","end":"2026-04-20T10:11:50.576103Z","steps":["trace[1629191885] 'agreement among raft nodes before linearized reading' (duration: 140.816673ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:50.5762Z","caller":"traceutil/trace.go:171","msg":"trace[1185754543] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:1350; }","duration":"142.09251ms","start":"2026-04-20T10:11:50.434064Z","end":"2026-04-20T10:11:50.576157Z","steps":["trace[1185754543] 'agreement among raft nodes before linearized reading' (duration: 141.802904ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:11:51.249475Z","caller":"traceutil/trace.go:171","msg":"trace[1562702776] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1406; }","duration":"110.033637ms","start":"2026-04-20T10:11:51.139414Z","end":"2026-04-20T10:11:51.249447Z","steps":["trace[1562702776] 'read index received' (duration: 109.533377ms)","trace[1562702776] 'applied index is now lower than readState.Index' (duration: 498.79µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:51.249611Z","caller":"traceutil/trace.go:171","msg":"trace[859763880] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"134.401903ms","start":"2026-04-20T10:11:51.115174Z","end":"2026-04-20T10:11:51.249576Z","steps":["trace[859763880] 'process raft request' (duration: 133.77563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:51.249695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.266702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1071"} {"level":"info","ts":"2026-04-20T10:11:51.249754Z","caller":"traceutil/trace.go:171","msg":"trace[3827081] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1372; }","duration":"110.371485ms","start":"2026-04-20T10:11:51.139367Z","end":"2026-04-20T10:11:51.249738Z","steps":["trace[3827081] 'agreement among raft nodes before linearized reading' (duration: 110.187881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:51.665929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.848658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T10:11:51.66607Z","caller":"traceutil/trace.go:171","msg":"trace[332503542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"315.047153ms","start":"2026-04-20T10:11:51.350997Z","end":"2026-04-20T10:11:51.666044Z","steps":["trace[332503542] 'range keys from in-memory index tree' (duration: 314.579743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:51.666132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:51.350973Z","time spent":"315.144334ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T10:11:52.120027Z","caller":"traceutil/trace.go:171","msg":"trace[1299130014] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"343.495764ms","start":"2026-04-20T10:11:51.77651Z","end":"2026-04-20T10:11:52.120006Z","steps":["trace[1299130014] 'read index received' (duration: 343.346001ms)","trace[1299130014] 'applied index is now lower than readState.Index' (duration: 148.743µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:11:52.120177Z","caller":"traceutil/trace.go:171","msg":"trace[1984601579] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"348.269908ms","start":"2026-04-20T10:11:51.771865Z","end":"2026-04-20T10:11:52.120135Z","steps":["trace[1984601579] 'process raft request' (duration: 348.009772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:52.120206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.666098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:11:52.120265Z","caller":"traceutil/trace.go:171","msg":"trace[161876968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"343.76568ms","start":"2026-04-20T10:11:51.776481Z","end":"2026-04-20T10:11:52.120247Z","steps":["trace[161876968] 'agreement among raft nodes before linearized reading' (duration: 343.631347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:11:52.120302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:51.776466Z","time spent":"343.826911ms","remote":"127.0.0.1:53064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:11:52.120325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:11:51.771832Z","time spent":"348.408031ms","remote":"127.0.0.1:53202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3127,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T10:11:53.411123Z","caller":"traceutil/trace.go:171","msg":"trace[473405739] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"121.904011ms","start":"2026-04-20T10:11:53.289166Z","end":"2026-04-20T10:11:53.41107Z","steps":["trace[473405739] 'process raft request' (duration: 121.679786ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:15.614284Z","caller":"traceutil/trace.go:171","msg":"trace[1793679838] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"115.187419ms","start":"2026-04-20T10:12:15.499076Z","end":"2026-04-20T10:12:15.614263Z","steps":["trace[1793679838] 'process raft request' (duration: 109.904035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:16.087791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.745647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:12:16.087862Z","caller":"traceutil/trace.go:171","msg":"trace[1629377496] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1764; }","duration":"164.844849ms","start":"2026-04-20T10:12:15.923004Z","end":"2026-04-20T10:12:16.087849Z","steps":["trace[1629377496] 'count revisions from in-memory index tree' (duration: 164.642056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:16.088116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.203628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:175"} {"level":"warn","ts":"2026-04-20T10:12:16.088196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.005681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3995"} {"level":"info","ts":"2026-04-20T10:12:16.08822Z","caller":"traceutil/trace.go:171","msg":"trace[40429470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1764; }","duration":"229.049262ms","start":"2026-04-20T10:12:15.859161Z","end":"2026-04-20T10:12:16.08821Z","steps":["trace[40429470] 'range keys from in-memory index tree' (duration: 228.902049ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:16.088205Z","caller":"traceutil/trace.go:171","msg":"trace[2102526298] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:1764; }","duration":"164.31879ms","start":"2026-04-20T10:12:15.923861Z","end":"2026-04-20T10:12:16.088179Z","steps":["trace[2102526298] 'range keys from in-memory index tree' (duration: 163.988234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:16.088372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.382081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-20T10:12:16.088434Z","caller":"traceutil/trace.go:171","msg":"trace[72604162] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1764; }","duration":"164.476033ms","start":"2026-04-20T10:12:15.92394Z","end":"2026-04-20T10:12:16.088416Z","steps":["trace[72604162] 'range keys from in-memory index tree' (duration: 164.203228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:16.088419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.651593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:12:16.088487Z","caller":"traceutil/trace.go:171","msg":"trace[1229795283] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1764; }","duration":"175.776586ms","start":"2026-04-20T10:12:15.912695Z","end":"2026-04-20T10:12:16.088471Z","steps":["trace[1229795283] 'count revisions from in-memory index tree' (duration: 175.547981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:18.230307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.627942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:12:18.230404Z","caller":"traceutil/trace.go:171","msg":"trace[894891055] linearizableReadLoop","detail":"{readStateIndex:1848; appliedIndex:1847; }","duration":"429.05406ms","start":"2026-04-20T10:12:17.801334Z","end":"2026-04-20T10:12:18.230388Z","steps":["trace[894891055] 'read index received' (duration: 195.253054ms)","trace[894891055] 'applied index is now lower than readState.Index' (duration: 233.799735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:12:18.230721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.499624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-20T10:12:18.230736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.650871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T10:12:18.230765Z","caller":"traceutil/trace.go:171","msg":"trace[768952997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1786; }","duration":"129.572816ms","start":"2026-04-20T10:12:18.101182Z","end":"2026-04-20T10:12:18.230755Z","steps":["trace[768952997] 'agreement among raft nodes before linearized reading' (duration: 129.429152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:18.230793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.407696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T10:12:18.230808Z","caller":"traceutil/trace.go:171","msg":"trace[1154833909] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1786; }","duration":"364.765543ms","start":"2026-04-20T10:12:17.866022Z","end":"2026-04-20T10:12:18.230787Z","steps":["trace[1154833909] 'agreement among raft nodes before linearized reading' (duration: 364.564869ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:18.230912Z","caller":"traceutil/trace.go:171","msg":"trace[1500236458] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"440.572064ms","start":"2026-04-20T10:12:17.790199Z","end":"2026-04-20T10:12:18.230771Z","steps":["trace[1500236458] 'process raft request' (duration: 206.380191ms)","trace[1500236458] 'compare' (duration: 233.51131ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:12:18.230975Z","caller":"traceutil/trace.go:171","msg":"trace[6984722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1786; }","duration":"429.667091ms","start":"2026-04-20T10:12:17.801297Z","end":"2026-04-20T10:12:18.230964Z","steps":["trace[6984722] 'agreement among raft nodes before linearized reading' (duration: 429.311964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:18.231298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:17.801279Z","time spent":"430.004007ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T10:12:18.231085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:17.866002Z","time spent":"365.012937ms","remote":"127.0.0.1:49822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T10:12:18.231149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:12:17.79018Z","time spent":"440.839238ms","remote":"127.0.0.1:53296","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-04-20T10:12:18.230775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.286912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:12:18.231841Z","caller":"traceutil/trace.go:171","msg":"trace[1192975832] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1786; }","duration":"297.39499ms","start":"2026-04-20T10:12:17.934413Z","end":"2026-04-20T10:12:18.231808Z","steps":["trace[1192975832] 'agreement among raft nodes before linearized reading' (duration: 296.268561ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:34.590129Z","caller":"traceutil/trace.go:171","msg":"trace[1534119695] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"108.32547ms","start":"2026-04-20T10:12:34.481784Z","end":"2026-04-20T10:12:34.590109Z","steps":["trace[1534119695] 'process raft request' (duration: 108.164918ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:34.590285Z","caller":"traceutil/trace.go:171","msg":"trace[758166597] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"105.594608ms","start":"2026-04-20T10:12:34.484672Z","end":"2026-04-20T10:12:34.590267Z","steps":["trace[758166597] 'process raft request' (duration: 105.390685ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:52.233288Z","caller":"traceutil/trace.go:171","msg":"trace[1954568616] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"140.39804ms","start":"2026-04-20T10:12:52.092855Z","end":"2026-04-20T10:12:52.233253Z","steps":["trace[1954568616] 'process raft request' (duration: 140.168336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:52.395306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.649121ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T10:12:53.049615Z","caller":"traceutil/trace.go:171","msg":"trace[738334997] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2101; }","duration":"105.962701ms","start":"2026-04-20T10:12:52.94362Z","end":"2026-04-20T10:12:53.049582Z","steps":["trace[738334997] 'read index received' (duration: 105.778459ms)","trace[738334997] 'applied index is now lower than readState.Index' (duration: 182.932µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:12:53.04971Z","caller":"traceutil/trace.go:171","msg":"trace[194144665] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"163.222888ms","start":"2026-04-20T10:12:52.886431Z","end":"2026-04-20T10:12:53.049654Z","steps":["trace[194144665] 'process raft request' (duration: 162.925034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:53.049908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.250815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:12:53.049987Z","caller":"traceutil/trace.go:171","msg":"trace[29295077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2032; }","duration":"106.386716ms","start":"2026-04-20T10:12:52.943579Z","end":"2026-04-20T10:12:53.049965Z","steps":["trace[29295077] 'agreement among raft nodes before linearized reading' (duration: 106.139393ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:53.104538Z","caller":"traceutil/trace.go:171","msg":"trace[69271931] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"160.712368ms","start":"2026-04-20T10:12:52.943797Z","end":"2026-04-20T10:12:53.104509Z","steps":["trace[69271931] 'process raft request' (duration: 160.402474ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:53.104702Z","caller":"traceutil/trace.go:171","msg":"trace[1008911425] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"124.491647ms","start":"2026-04-20T10:12:52.980175Z","end":"2026-04-20T10:12:53.104667Z","steps":["trace[1008911425] 'process raft request' (duration: 124.245554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:53.104981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.487848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:12:53.105047Z","caller":"traceutil/trace.go:171","msg":"trace[1890215601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2034; }","duration":"158.584149ms","start":"2026-04-20T10:12:52.946446Z","end":"2026-04-20T10:12:53.10503Z","steps":["trace[1890215601] 'agreement among raft nodes before linearized reading' (duration: 158.387516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:12:53.105081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.450396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:12:53.105273Z","caller":"traceutil/trace.go:171","msg":"trace[1850891872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2034; }","duration":"160.720509ms","start":"2026-04-20T10:12:52.944531Z","end":"2026-04-20T10:12:53.105252Z","steps":["trace[1850891872] 'agreement among raft nodes before linearized reading' (duration: 160.12971ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:53.348026Z","caller":"traceutil/trace.go:171","msg":"trace[1611642218] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"293.994534ms","start":"2026-04-20T10:12:53.053981Z","end":"2026-04-20T10:12:53.347976Z","steps":["trace[1611642218] 'process raft request' (duration: 194.753103ms)","trace[1611642218] 'compare' (duration: 98.614203ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:12:53.348071Z","caller":"traceutil/trace.go:171","msg":"trace[933289032] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"237.537261ms","start":"2026-04-20T10:12:53.110497Z","end":"2026-04-20T10:12:53.348035Z","steps":["trace[933289032] 'process raft request' (duration: 237.493761ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:53.348413Z","caller":"traceutil/trace.go:171","msg":"trace[1917330825] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"238.244172ms","start":"2026-04-20T10:12:53.110123Z","end":"2026-04-20T10:12:53.348367Z","steps":["trace[1917330825] 'process raft request' (duration: 237.818216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:12:53.348546Z","caller":"traceutil/trace.go:171","msg":"trace[792766538] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"238.887032ms","start":"2026-04-20T10:12:53.109632Z","end":"2026-04-20T10:12:53.348519Z","steps":["trace[792766538] 'process raft request' (duration: 238.194691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:56.018424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.351609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:13:56.018558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.127245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:56.018621Z","caller":"traceutil/trace.go:171","msg":"trace[1972867241] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2477; }","duration":"149.267397ms","start":"2026-04-20T10:13:55.869338Z","end":"2026-04-20T10:13:56.018605Z","steps":["trace[1972867241] 'count revisions from in-memory index tree' (duration: 149.073575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:13:56.018767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.74787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:13:56.018783Z","caller":"traceutil/trace.go:171","msg":"trace[1798444552] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2477; }","duration":"127.814301ms","start":"2026-04-20T10:13:55.890964Z","end":"2026-04-20T10:13:56.018779Z","steps":["trace[1798444552] 'count revisions from in-memory index tree' (duration: 127.647399ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:13:56.019628Z","caller":"traceutil/trace.go:171","msg":"trace[1026660703] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2477; }","duration":"180.494241ms","start":"2026-04-20T10:13:55.838016Z","end":"2026-04-20T10:13:56.018511Z","steps":["trace[1026660703] 'count revisions from in-memory index tree' (duration: 180.283429ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:03.908815Z","caller":"traceutil/trace.go:171","msg":"trace[1562859593] linearizableReadLoop","detail":"{readStateIndex:2612; appliedIndex:2611; }","duration":"101.56408ms","start":"2026-04-20T10:14:03.807219Z","end":"2026-04-20T10:14:03.908783Z","steps":["trace[1562859593] 'read index received' (duration: 67.050536ms)","trace[1562859593] 'applied index is now lower than readState.Index' (duration: 34.511394ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:14:03.90889Z","caller":"traceutil/trace.go:171","msg":"trace[209428043] transaction","detail":"{read_only:false; response_revision:2526; number_of_response:1; }","duration":"102.59883ms","start":"2026-04-20T10:14:03.806245Z","end":"2026-04-20T10:14:03.908844Z","steps":["trace[209428043] 'process raft request' (duration: 68.092186ms)","trace[209428043] 'compare' (duration: 34.071429ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:14:03.909155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.809812ms","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-04-20T10:14:03.909233Z","caller":"traceutil/trace.go:171","msg":"trace[1008126932] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2526; }","duration":"102.037614ms","start":"2026-04-20T10:14:03.807178Z","end":"2026-04-20T10:14:03.909215Z","steps":["trace[1008126932] 'agreement among raft nodes before linearized reading' (duration: 101.799602ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:23.666869Z","caller":"traceutil/trace.go:171","msg":"trace[594097160] transaction","detail":"{read_only:false; response_revision:2626; number_of_response:1; }","duration":"149.492864ms","start":"2026-04-20T10:14:23.517348Z","end":"2026-04-20T10:14:23.666841Z","steps":["trace[594097160] 'process raft request' (duration: 149.317102ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:23.699397Z","caller":"traceutil/trace.go:171","msg":"trace[1844617824] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"160.639089ms","start":"2026-04-20T10:14:23.538731Z","end":"2026-04-20T10:14:23.69937Z","steps":["trace[1844617824] 'process raft request' (duration: 160.419767ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:29.901961Z","caller":"traceutil/trace.go:171","msg":"trace[11551655] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"183.648879ms","start":"2026-04-20T10:14:29.718259Z","end":"2026-04-20T10:14:29.901908Z","steps":["trace[11551655] 'process raft request' (duration: 183.420746ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:29.926147Z","caller":"traceutil/trace.go:171","msg":"trace[1912959688] linearizableReadLoop","detail":"{readStateIndex:2754; appliedIndex:2753; }","duration":"148.393626ms","start":"2026-04-20T10:14:29.777715Z","end":"2026-04-20T10:14:29.926109Z","steps":["trace[1912959688] 'read index received' (duration: 125.009856ms)","trace[1912959688] 'applied index is now lower than readState.Index' (duration: 23.38269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:14:29.926346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.598848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:14:29.926463Z","caller":"traceutil/trace.go:171","msg":"trace[101156736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2662; }","duration":"148.78038ms","start":"2026-04-20T10:14:29.777666Z","end":"2026-04-20T10:14:29.926446Z","steps":["trace[101156736] 'agreement among raft nodes before linearized reading' (duration: 148.612898ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:14:29.926478Z","caller":"traceutil/trace.go:171","msg":"trace[144024471] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"207.11224ms","start":"2026-04-20T10:14:29.719323Z","end":"2026-04-20T10:14:29.926435Z","steps":["trace[144024471] 'process raft request' (duration: 206.605905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:08.435167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.99984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-20T10:18:08.435243Z","caller":"traceutil/trace.go:171","msg":"trace[1002859436] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4433; }","duration":"119.122873ms","start":"2026-04-20T10:18:08.316104Z","end":"2026-04-20T10:18:08.435227Z","steps":["trace[1002859436] 'range keys from in-memory index tree' (duration: 118.864737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:16.768631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.510933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193165"} {"level":"info","ts":"2026-04-20T10:18:16.76871Z","caller":"traceutil/trace.go:171","msg":"trace[1278258238] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4620; }","duration":"233.626985ms","start":"2026-04-20T10:18:16.535069Z","end":"2026-04-20T10:18:16.768696Z","steps":["trace[1278258238] 'range keys from in-memory index tree' (duration: 232.633683ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:17.426006Z","caller":"traceutil/trace.go:171","msg":"trace[1646277036] linearizableReadLoop","detail":"{readStateIndex:4794; appliedIndex:4793; }","duration":"220.395342ms","start":"2026-04-20T10:18:17.205576Z","end":"2026-04-20T10:18:17.425971Z","steps":["trace[1646277036] 'read index received' (duration: 151.687121ms)","trace[1646277036] 'applied index is now lower than readState.Index' (duration: 68.707061ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:18:17.426118Z","caller":"traceutil/trace.go:171","msg":"trace[1635516047] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"236.38777ms","start":"2026-04-20T10:18:17.18972Z","end":"2026-04-20T10:18:17.426107Z","steps":["trace[1635516047] 'process raft request' (duration: 167.464444ms)","trace[1635516047] 'compare' (duration: 68.66802ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:18:17.426307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.657451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-20T10:18:17.426393Z","caller":"traceutil/trace.go:171","msg":"trace[1694537177] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4626; }","duration":"217.773134ms","start":"2026-04-20T10:18:17.208596Z","end":"2026-04-20T10:18:17.426369Z","steps":["trace[1694537177] 'agreement among raft nodes before linearized reading' (duration: 217.554519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:17.426504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.52406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T10:18:17.426531Z","caller":"traceutil/trace.go:171","msg":"trace[2073730284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4626; }","duration":"231.598732ms","start":"2026-04-20T10:18:17.194926Z","end":"2026-04-20T10:18:17.426524Z","steps":["trace[2073730284] 'agreement among raft nodes before linearized reading' (duration: 231.48931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:17.426628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.351225ms","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-04-20T10:18:17.426664Z","caller":"traceutil/trace.go:171","msg":"trace[30914784] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4626; }","duration":"131.420587ms","start":"2026-04-20T10:18:17.295232Z","end":"2026-04-20T10:18:17.426653Z","steps":["trace[30914784] 'agreement among raft nodes before linearized reading' (duration: 131.346545ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:17.569188Z","caller":"traceutil/trace.go:171","msg":"trace[906898778] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"106.172411ms","start":"2026-04-20T10:18:17.462991Z","end":"2026-04-20T10:18:17.569164Z","steps":["trace[906898778] 'process raft request' (duration: 98.041048ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:17.576003Z","caller":"traceutil/trace.go:171","msg":"trace[1114110701] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"106.174971ms","start":"2026-04-20T10:18:17.4698Z","end":"2026-04-20T10:18:17.575975Z","steps":["trace[1114110701] 'process raft request' (duration: 106.090469ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:17.57609Z","caller":"traceutil/trace.go:171","msg":"trace[14773853] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"111.972841ms","start":"2026-04-20T10:18:17.464098Z","end":"2026-04-20T10:18:17.576071Z","steps":["trace[14773853] 'process raft request' (duration: 111.646813ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:18:29.261373Z","caller":"traceutil/trace.go:171","msg":"trace[2766806] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"162.755663ms","start":"2026-04-20T10:18:29.098585Z","end":"2026-04-20T10:18:29.261341Z","steps":["trace[2766806] 'process raft request' (duration: 162.588999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:29.521161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.809428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:18:29.521279Z","caller":"traceutil/trace.go:171","msg":"trace[102475344] linearizableReadLoop","detail":"{readStateIndex:4960; appliedIndex:4957; }","duration":"325.812684ms","start":"2026-04-20T10:18:29.195455Z","end":"2026-04-20T10:18:29.521268Z","steps":["trace[102475344] 'read index received' (duration: 65.843192ms)","trace[102475344] 'applied index is now lower than readState.Index' (duration: 259.968672ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:18:29.521432Z","caller":"traceutil/trace.go:171","msg":"trace[286473743] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"415.693264ms","start":"2026-04-20T10:18:29.105731Z","end":"2026-04-20T10:18:29.521424Z","steps":["trace[286473743] 'process raft request' (duration: 275.559919ms)","trace[286473743] 'compare' (duration: 139.534071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:18:29.521483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:18:29.105703Z","time spent":"415.755745ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:18:29.521604Z","caller":"traceutil/trace.go:171","msg":"trace[632693255] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"350.67128ms","start":"2026-04-20T10:18:29.170927Z","end":"2026-04-20T10:18:29.521598Z","steps":["trace[632693255] 'process raft request' (duration: 350.302812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:29.521641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:18:29.170904Z","time spent":"350.71939ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:18:29.521741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.289493ms","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-04-20T10:18:29.521765Z","caller":"traceutil/trace.go:171","msg":"trace[952028113] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4787; }","duration":"326.357445ms","start":"2026-04-20T10:18:29.195402Z","end":"2026-04-20T10:18:29.521759Z","steps":["trace[952028113] 'agreement among raft nodes before linearized reading' (duration: 326.315274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:29.521786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:18:29.195382Z","time spent":"326.398226ms","remote":"127.0.0.1:53168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:18:29.522115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.651284ms","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":"info","ts":"2026-04-20T10:18:29.522159Z","caller":"traceutil/trace.go:171","msg":"trace[288662693] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4787; }","duration":"177.693325ms","start":"2026-04-20T10:18:29.344454Z","end":"2026-04-20T10:18:29.522148Z","steps":["trace[288662693] 'agreement among raft nodes before linearized reading' (duration: 177.573353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:18:29.52362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.523335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-04-20T10:18:29.523662Z","caller":"traceutil/trace.go:171","msg":"trace[350385918] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4787; }","duration":"255.596808ms","start":"2026-04-20T10:18:29.268057Z","end":"2026-04-20T10:18:29.523654Z","steps":["trace[350385918] 'agreement among raft nodes before linearized reading' (duration: 253.815739ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:14.066722Z","caller":"traceutil/trace.go:171","msg":"trace[1733187384] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"108.594099ms","start":"2026-04-20T10:20:13.958105Z","end":"2026-04-20T10:20:14.066699Z","steps":["trace[1733187384] 'process raft request' (duration: 108.455126ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:20:14.112436Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-04-20T10:20:14.311472Z","caller":"traceutil/trace.go:171","msg":"trace[1567548503] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"140.592766ms","start":"2026-04-20T10:20:14.170845Z","end":"2026-04-20T10:20:14.311438Z","steps":["trace[1567548503] 'process raft request' (duration: 73.225002ms)","trace[1567548503] 'compare' (duration: 40.053975ms)","trace[1567548503] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:531; } (duration: 27.179216ms)"],"step_count":3} {"level":"info","ts":"2026-04-20T10:20:14.552049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"438.058409ms","hash":186970518,"current-db-size-bytes":54640640,"current-db-size":"55 MB","current-db-size-in-use-bytes":29519872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-20T10:20:14.552141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":186970518,"revision":2892,"compact-revision":-1} {"level":"warn","ts":"2026-04-20T10:20:14.552704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.344572ms","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-04-20T10:20:14.55283Z","caller":"traceutil/trace.go:171","msg":"trace[337738999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5614; }","duration":"124.509905ms","start":"2026-04-20T10:20:14.428274Z","end":"2026-04-20T10:20:14.552784Z","steps":["trace[337738999] 'range keys from in-memory index tree' (duration: 123.790772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:11.498573Z","caller":"traceutil/trace.go:171","msg":"trace[614505881] transaction","detail":"{read_only:false; response_revision:5914; number_of_response:1; }","duration":"113.117822ms","start":"2026-04-20T10:21:11.385266Z","end":"2026-04-20T10:21:11.498384Z","steps":["trace[614505881] 'process raft request' (duration: 34.618187ms)","trace[614505881] 'compare' (duration: 78.278871ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:21:52.812003Z","caller":"traceutil/trace.go:171","msg":"trace[423574590] transaction","detail":"{read_only:false; response_revision:6284; number_of_response:1; }","duration":"109.305151ms","start":"2026-04-20T10:21:52.702609Z","end":"2026-04-20T10:21:52.811914Z","steps":["trace[423574590] 'process raft request' (duration: 97.509616ms)","trace[423574590] 'compare' (duration: 11.553501ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:21:52.812419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.446472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:21:52.812493Z","caller":"traceutil/trace.go:171","msg":"trace[1465090973] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6285; }","duration":"111.579313ms","start":"2026-04-20T10:21:52.700898Z","end":"2026-04-20T10:21:52.812477Z","steps":["trace[1465090973] 'agreement among raft nodes before linearized reading' (duration: 111.060176ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:53.388066Z","caller":"traceutil/trace.go:171","msg":"trace[1053203936] linearizableReadLoop","detail":"{readStateIndex:6510; appliedIndex:6509; }","duration":"305.485075ms","start":"2026-04-20T10:21:53.082551Z","end":"2026-04-20T10:21:53.388036Z","steps":["trace[1053203936] 'read index received' (duration: 303.429744ms)","trace[1053203936] 'applied index is now lower than readState.Index' (duration: 2.05435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:21:53.388438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.87031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:21:53.388603Z","caller":"traceutil/trace.go:171","msg":"trace[1827420806] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:6287; }","duration":"306.042302ms","start":"2026-04-20T10:21:53.082535Z","end":"2026-04-20T10:21:53.388578Z","steps":["trace[1827420806] 'agreement among raft nodes before linearized reading' (duration: 305.823909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:53.388688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:53.082476Z","time spent":"306.194405ms","remote":"127.0.0.1:46128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-04-20T10:21:53.388684Z","caller":"traceutil/trace.go:171","msg":"trace[1442740856] transaction","detail":"{read_only:false; response_revision:6287; number_of_response:1; }","duration":"341.515778ms","start":"2026-04-20T10:21:53.047136Z","end":"2026-04-20T10:21:53.388652Z","steps":["trace[1442740856] 'process raft request' (duration: 338.89659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:53.388913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.30667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T10:21:53.388987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:21:53.047116Z","time spent":"341.786532ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:21:53.389022Z","caller":"traceutil/trace.go:171","msg":"trace[51003502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6287; }","duration":"240.449032ms","start":"2026-04-20T10:21:53.148555Z","end":"2026-04-20T10:21:53.389004Z","steps":["trace[51003502] 'agreement among raft nodes before linearized reading' (duration: 240.222658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:21:53.389324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.082365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T10:21:53.389372Z","caller":"traceutil/trace.go:171","msg":"trace[362885007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6287; }","duration":"242.181437ms","start":"2026-04-20T10:21:53.147178Z","end":"2026-04-20T10:21:53.38936Z","steps":["trace[362885007] 'agreement among raft nodes before linearized reading' (duration: 241.307214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:21:59.83532Z","caller":"traceutil/trace.go:171","msg":"trace[1194477260] transaction","detail":"{read_only:false; response_revision:6345; number_of_response:1; }","duration":"106.841714ms","start":"2026-04-20T10:21:59.728416Z","end":"2026-04-20T10:21:59.835258Z","steps":["trace[1194477260] 'process raft request' (duration: 80.457446ms)","trace[1194477260] 'compare' (duration: 26.125944ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:22:01.903601Z","caller":"traceutil/trace.go:171","msg":"trace[1215403764] linearizableReadLoop","detail":"{readStateIndex:6580; appliedIndex:6579; }","duration":"293.566227ms","start":"2026-04-20T10:22:01.609936Z","end":"2026-04-20T10:22:01.903502Z","steps":["trace[1215403764] 'read index received' (duration: 293.396205ms)","trace[1215403764] 'applied index is now lower than readState.Index' (duration: 168.982µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:22:01.903725Z","caller":"traceutil/trace.go:171","msg":"trace[2014914483] transaction","detail":"{read_only:false; response_revision:6356; number_of_response:1; }","duration":"383.981187ms","start":"2026-04-20T10:22:01.519717Z","end":"2026-04-20T10:22:01.903698Z","steps":["trace[2014914483] 'process raft request' (duration: 383.624482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:01.903826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.625572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:22:01.903871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:01.519711Z","time spent":"384.081939ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:22:01.903927Z","caller":"traceutil/trace.go:171","msg":"trace[1791597187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6356; }","duration":"128.776744ms","start":"2026-04-20T10:22:01.775126Z","end":"2026-04-20T10:22:01.903902Z","steps":["trace[1791597187] 'agreement among raft nodes before linearized reading' (duration: 128.625352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:01.903963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.986103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T10:22:01.904039Z","caller":"traceutil/trace.go:171","msg":"trace[1512186028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6356; }","duration":"294.143166ms","start":"2026-04-20T10:22:01.609885Z","end":"2026-04-20T10:22:01.904028Z","steps":["trace[1512186028] 'agreement among raft nodes before linearized reading' (duration: 293.829292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:03.352476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.549273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T10:22:03.352751Z","caller":"traceutil/trace.go:171","msg":"trace[1520397470] linearizableReadLoop","detail":"{readStateIndex:6586; appliedIndex:6585; }","duration":"467.348904ms","start":"2026-04-20T10:22:02.885388Z","end":"2026-04-20T10:22:03.352737Z","steps":["trace[1520397470] 'read index received' (duration: 258.458896ms)","trace[1520397470] 'applied index is now lower than readState.Index' (duration: 208.888787ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:22:03.352966Z","caller":"traceutil/trace.go:171","msg":"trace[273330681] transaction","detail":"{read_only:false; response_revision:6361; number_of_response:1; }","duration":"481.36939ms","start":"2026-04-20T10:22:02.87158Z","end":"2026-04-20T10:22:03.35295Z","steps":["trace[273330681] 'process raft request' (duration: 272.283579ms)","trace[273330681] 'compare' (duration: 208.421661ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:22:03.353154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.751289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T10:22:03.353209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.819933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T10:22:03.353236Z","caller":"traceutil/trace.go:171","msg":"trace[1926793009] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:6361; }","duration":"467.86257ms","start":"2026-04-20T10:22:02.885354Z","end":"2026-04-20T10:22:03.353217Z","steps":["trace[1926793009] 'agreement among raft nodes before linearized reading' (duration: 467.734699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:03.353341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:02.885325Z","time spent":"468.003823ms","remote":"127.0.0.1:53456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-20T10:22:03.353224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.006739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-04-20T10:22:03.353457Z","caller":"traceutil/trace.go:171","msg":"trace[486805732] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6361; }","duration":"465.223902ms","start":"2026-04-20T10:22:02.888183Z","end":"2026-04-20T10:22:03.353407Z","steps":["trace[486805732] 'agreement among raft nodes before linearized reading' (duration: 464.868167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:03.353601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:02.888176Z","time spent":"465.407875ms","remote":"127.0.0.1:53274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4882,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-04-20T10:22:03.353501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:02.871566Z","time spent":"481.588282ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:22:03.353263Z","caller":"traceutil/trace.go:171","msg":"trace[1153279310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6361; }","duration":"278.918805ms","start":"2026-04-20T10:22:03.07433Z","end":"2026-04-20T10:22:03.353249Z","steps":["trace[1153279310] 'agreement among raft nodes before linearized reading' (duration: 278.776263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:04.127151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.549964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:22:04.127305Z","caller":"traceutil/trace.go:171","msg":"trace[908076545] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:6366; }","duration":"102.762868ms","start":"2026-04-20T10:22:04.024519Z","end":"2026-04-20T10:22:04.127282Z","steps":["trace[908076545] 'count revisions from in-memory index tree' (duration: 102.441962ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:22:04.386435Z","caller":"traceutil/trace.go:171","msg":"trace[870116349] linearizableReadLoop","detail":"{readStateIndex:6592; appliedIndex:6591; }","duration":"214.448676ms","start":"2026-04-20T10:22:04.171967Z","end":"2026-04-20T10:22:04.386415Z","steps":["trace[870116349] 'read index received' (duration: 214.349105ms)","trace[870116349] 'applied index is now lower than readState.Index' (duration: 98.701µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:22:04.386562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.578648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T10:22:04.386625Z","caller":"traceutil/trace.go:171","msg":"trace[2004024197] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:6367; }","duration":"214.6701ms","start":"2026-04-20T10:22:04.17192Z","end":"2026-04-20T10:22:04.38659Z","steps":["trace[2004024197] 'agreement among raft nodes before linearized reading' (duration: 214.565548ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:22:04.386602Z","caller":"traceutil/trace.go:171","msg":"trace[934714101] transaction","detail":"{read_only:false; response_revision:6367; number_of_response:1; }","duration":"221.845124ms","start":"2026-04-20T10:22:04.164721Z","end":"2026-04-20T10:22:04.386566Z","steps":["trace[934714101] 'process raft request' (duration: 221.57544ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:22:10.180052Z","caller":"traceutil/trace.go:171","msg":"trace[1675323266] linearizableReadLoop","detail":"{readStateIndex:6627; appliedIndex:6626; }","duration":"112.967548ms","start":"2026-04-20T10:22:10.067059Z","end":"2026-04-20T10:22:10.180027Z","steps":["trace[1675323266] 'read index received' (duration: 112.724435ms)","trace[1675323266] 'applied index is now lower than readState.Index' (duration: 242.163µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:22:10.180206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.122821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:22:10.180255Z","caller":"traceutil/trace.go:171","msg":"trace[338178530] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:6400; }","duration":"113.240802ms","start":"2026-04-20T10:22:10.066998Z","end":"2026-04-20T10:22:10.180239Z","steps":["trace[338178530] 'agreement among raft nodes before linearized reading' (duration: 113.132061ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:22:10.180257Z","caller":"traceutil/trace.go:171","msg":"trace[706735522] transaction","detail":"{read_only:false; response_revision:6400; number_of_response:1; }","duration":"178.16977ms","start":"2026-04-20T10:22:10.002062Z","end":"2026-04-20T10:22:10.180232Z","steps":["trace[706735522] 'process raft request' (duration: 177.807855ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:22:15.00883Z","caller":"traceutil/trace.go:171","msg":"trace[1848674366] linearizableReadLoop","detail":"{readStateIndex:6652; appliedIndex:6651; }","duration":"231.927925ms","start":"2026-04-20T10:22:14.776875Z","end":"2026-04-20T10:22:15.008803Z","steps":["trace[1848674366] 'read index received' (duration: 231.757312ms)","trace[1848674366] 'applied index is now lower than readState.Index' (duration: 167.583µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:22:15.008863Z","caller":"traceutil/trace.go:171","msg":"trace[815466548] transaction","detail":"{read_only:false; response_revision:6424; number_of_response:1; }","duration":"255.982834ms","start":"2026-04-20T10:22:14.752846Z","end":"2026-04-20T10:22:15.008829Z","steps":["trace[815466548] 'process raft request' (duration: 255.792331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:15.008985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.087997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:22:15.009022Z","caller":"traceutil/trace.go:171","msg":"trace[2134705306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6424; }","duration":"232.173318ms","start":"2026-04-20T10:22:14.776835Z","end":"2026-04-20T10:22:15.009008Z","steps":["trace[2134705306] 'agreement among raft nodes before linearized reading' (duration: 232.100577ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:22:15.011812Z","caller":"traceutil/trace.go:171","msg":"trace[211982741] transaction","detail":"{read_only:false; response_revision:6425; number_of_response:1; }","duration":"220.008541ms","start":"2026-04-20T10:22:14.791787Z","end":"2026-04-20T10:22:15.011795Z","steps":["trace[211982741] 'process raft request' (duration: 219.873039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:15.012171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.084272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-04-20T10:22:15.012252Z","caller":"traceutil/trace.go:171","msg":"trace[72832172] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6427; }","duration":"141.212234ms","start":"2026-04-20T10:22:14.871027Z","end":"2026-04-20T10:22:15.012239Z","steps":["trace[72832172] 'agreement among raft nodes before linearized reading' (duration: 141.034601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:15.012448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.747598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-04-20T10:22:15.012574Z","caller":"traceutil/trace.go:171","msg":"trace[1816952385] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6427; }","duration":"138.9329ms","start":"2026-04-20T10:22:14.873615Z","end":"2026-04-20T10:22:15.012548Z","steps":["trace[1816952385] 'agreement among raft nodes before linearized reading' (duration: 138.539674ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:22:42.774419Z","caller":"traceutil/trace.go:171","msg":"trace[1597531402] transaction","detail":"{read_only:false; response_revision:6624; number_of_response:1; }","duration":"110.277993ms","start":"2026-04-20T10:22:42.664117Z","end":"2026-04-20T10:22:42.774395Z","steps":["trace[1597531402] 'process raft request' (duration: 110.05466ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:22:45.47478Z","caller":"traceutil/trace.go:171","msg":"trace[1325984333] transaction","detail":"{read_only:false; response_revision:6634; number_of_response:1; }","duration":"438.929069ms","start":"2026-04-20T10:22:45.035818Z","end":"2026-04-20T10:22:45.474747Z","steps":["trace[1325984333] 'process raft request' (duration: 438.231979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:45.475234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.035802Z","time spent":"439.119431ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:22:46.03778Z","caller":"traceutil/trace.go:171","msg":"trace[1061466470] linearizableReadLoop","detail":"{readStateIndex:6872; appliedIndex:6869; }","duration":"751.953794ms","start":"2026-04-20T10:22:45.285778Z","end":"2026-04-20T10:22:46.037732Z","steps":["trace[1061466470] 'read index received' (duration: 188.31537ms)","trace[1061466470] 'applied index is now lower than readState.Index' (duration: 563.636964ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:22:46.03784Z","caller":"traceutil/trace.go:171","msg":"trace[1999261446] transaction","detail":"{read_only:false; response_revision:6635; number_of_response:1; }","duration":"907.337387ms","start":"2026-04-20T10:22:45.130468Z","end":"2026-04-20T10:22:46.037805Z","steps":["trace[1999261446] 'process raft request' (duration: 907.107334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:46.037979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.130448Z","time spent":"907.453829ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T10:22:46.038019Z","caller":"traceutil/trace.go:171","msg":"trace[242476274] transaction","detail":"{read_only:false; response_revision:6636; number_of_response:1; }","duration":"894.648477ms","start":"2026-04-20T10:22:45.143238Z","end":"2026-04-20T10:22:46.037886Z","steps":["trace[242476274] 'process raft request' (duration: 894.454954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:46.038171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.143219Z","time spent":"894.876749ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T10:22:46.038215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.412801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:437778"} {"level":"info","ts":"2026-04-20T10:22:46.038261Z","caller":"traceutil/trace.go:171","msg":"trace[1690859913] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6636; }","duration":"752.475003ms","start":"2026-04-20T10:22:45.285773Z","end":"2026-04-20T10:22:46.038248Z","steps":["trace[1690859913] 'agreement among raft nodes before linearized reading' (duration: 752.096596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:46.038299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.285727Z","time spent":"752.563664ms","remote":"127.0.0.1:53132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":25,"response size":437801,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-04-20T10:22:46.200594Z","caller":"traceutil/trace.go:171","msg":"trace[2070564340] linearizableReadLoop","detail":"{readStateIndex:6873; appliedIndex:6872; }","duration":"162.735135ms","start":"2026-04-20T10:22:46.03784Z","end":"2026-04-20T10:22:46.200575Z","steps":["trace[2070564340] 'read index received' (duration: 146.86847ms)","trace[2070564340] 'applied index is now lower than readState.Index' (duration: 15.866035ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:22:46.20076Z","caller":"traceutil/trace.go:171","msg":"trace[666641759] transaction","detail":"{read_only:false; response_revision:6637; number_of_response:1; }","duration":"520.200755ms","start":"2026-04-20T10:22:45.68055Z","end":"2026-04-20T10:22:46.200751Z","steps":["trace[666641759] 'process raft request' (duration: 504.277358ms)","trace[666641759] 'compare' (duration: 15.447149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T10:22:46.200829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.680531Z","time spent":"520.249945ms","remote":"127.0.0.1:53296","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-04-20T10:22:46.201057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.841643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T10:22:46.201086Z","caller":"traceutil/trace.go:171","msg":"trace[635367238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6637; }","duration":"298.927824ms","start":"2026-04-20T10:22:45.902151Z","end":"2026-04-20T10:22:46.201079Z","steps":["trace[635367238] 'agreement among raft nodes before linearized reading' (duration: 298.830443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:46.201209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.513129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T10:22:46.201236Z","caller":"traceutil/trace.go:171","msg":"trace[1077917032] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6637; }","duration":"317.539549ms","start":"2026-04-20T10:22:45.883689Z","end":"2026-04-20T10:22:46.201228Z","steps":["trace[1077917032] 'agreement among raft nodes before linearized reading' (duration: 317.480579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:46.201252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.883587Z","time spent":"317.661202ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T10:22:46.201347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.121987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:22:46.201372Z","caller":"traceutil/trace.go:171","msg":"trace[276501976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6637; }","duration":"424.184598ms","start":"2026-04-20T10:22:45.777182Z","end":"2026-04-20T10:22:46.201367Z","steps":["trace[276501976] 'agreement among raft nodes before linearized reading' (duration: 424.145408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:46.201389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.777162Z","time spent":"424.222809ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T10:22:46.201525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.693494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-20T10:22:46.201549Z","caller":"traceutil/trace.go:171","msg":"trace[1721576506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6637; }","duration":"556.754615ms","start":"2026-04-20T10:22:45.644789Z","end":"2026-04-20T10:22:46.201544Z","steps":["trace[1721576506] 'agreement among raft nodes before linearized reading' (duration: 556.697604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:46.201566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.644767Z","time spent":"556.794645ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T10:22:46.201678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.073704ms","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-04-20T10:22:46.201698Z","caller":"traceutil/trace.go:171","msg":"trace[1433887810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6637; }","duration":"558.133424ms","start":"2026-04-20T10:22:45.64356Z","end":"2026-04-20T10:22:46.201693Z","steps":["trace[1433887810] 'agreement among raft nodes before linearized reading' (duration: 558.082103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:22:46.201712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T10:22:45.643498Z","time spent":"558.210065ms","remote":"127.0.0.1:53296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T10:23:21.125572Z","caller":"traceutil/trace.go:171","msg":"trace[1978706831] transaction","detail":"{read_only:false; response_revision:6841; number_of_response:1; }","duration":"101.192462ms","start":"2026-04-20T10:23:21.024357Z","end":"2026-04-20T10:23:21.12555Z","steps":["trace[1978706831] 'process raft request' (duration: 101.03478ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:32.41021Z","caller":"traceutil/trace.go:171","msg":"trace[1296715819] transaction","detail":"{read_only:false; response_revision:6895; number_of_response:1; }","duration":"174.744726ms","start":"2026-04-20T10:23:32.235439Z","end":"2026-04-20T10:23:32.410183Z","steps":["trace[1296715819] 'process raft request' (duration: 174.580644ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:23:32.44489Z","caller":"traceutil/trace.go:171","msg":"trace[582328722] linearizableReadLoop","detail":"{readStateIndex:7142; appliedIndex:7141; }","duration":"180.560766ms","start":"2026-04-20T10:23:32.26431Z","end":"2026-04-20T10:23:32.444871Z","steps":["trace[582328722] 'read index received' (duration: 145.861235ms)","trace[582328722] 'applied index is now lower than readState.Index' (duration: 34.698701ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T10:23:32.445151Z","caller":"traceutil/trace.go:171","msg":"trace[2112499966] transaction","detail":"{read_only:false; response_revision:6896; number_of_response:1; }","duration":"203.680557ms","start":"2026-04-20T10:23:32.241459Z","end":"2026-04-20T10:23:32.44514Z","steps":["trace[2112499966] 'process raft request' (duration: 203.298362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T10:23:32.445013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.688988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T10:23:32.445356Z","caller":"traceutil/trace.go:171","msg":"trace[852959211] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:6896; }","duration":"181.048963ms","start":"2026-04-20T10:23:32.264295Z","end":"2026-04-20T10:23:32.445344Z","steps":["trace[852959211] 'agreement among raft nodes before linearized reading' (duration: 180.659607ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T10:25:14.130661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5610} {"level":"info","ts":"2026-04-20T10:25:14.209468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5610,"took":"77.557972ms","hash":404456027,"current-db-size-bytes":54640640,"current-db-size":"55 MB","current-db-size-in-use-bytes":18083840,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T10:25:14.209619Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":404456027,"revision":5610,"compact-revision":2892}