{"level":"warn","ts":"2026-04-16T06:05:15.246968Z","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-16T06:05:15.247095Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-16T06:05:15.247183Z","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-16T06:05:15.247195Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-16T06:05:15.247223Z","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-16T06:05:15.24788Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-04-16T06:05:15.247963Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-16T06:05:15.252101Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.895646ms"} {"level":"info","ts":"2026-04-16T06:05:15.260428Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-04-16T06:05:15.260499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T06:05:15.260518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-04-16T06:05:15.260527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T06:05:15.260539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-04-16T06:05:15.260574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-04-16T06:05:15.265892Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T06:05:15.267687Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T06:05:15.268769Z","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-16T06:05:15.270786Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T06:05:15.270887Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T06:05:15.271132Z","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-16T06:05:15.271955Z","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-16T06:05:15.271972Z","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-16T06:05:15.273762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-04-16T06:05:15.274059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-16T06:05:15.275706Z","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-16T06:05:15.275987Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T06:05:15.27604Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T06:05:15.275814Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-16T06:05:15.276569Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-16T06:05:15.461377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T06:05:15.461434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T06:05:15.461471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-04-16T06:05:15.461491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-04-16T06:05:15.461502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-16T06:05:15.461523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-04-16T06:05:15.461537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-16T06:05:15.462406Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T06:05:15.462408Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:05:15.462724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:05:15.46333Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T06:05:15.463358Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T06:05:15.464954Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T06:05:15.465736Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-04-16T06:05:15.47076Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:05:15.478314Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:05:15.478449Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:05:15.478483Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-16T06:05:19.72643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.303166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:05:19.726757Z","caller":"traceutil/trace.go:171","msg":"trace[929371951] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"453.472116ms","start":"2026-04-16T06:05:19.273246Z","end":"2026-04-16T06:05:19.726718Z","steps":["trace[929371951] 'process raft request' (duration: 167.180787ms)","trace[929371951] 'compare' (duration: 285.088369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:05:19.727003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:05:19.273224Z","time spent":"453.724125ms","remote":"127.0.0.1:49964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T06:05:20.173991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.318502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:05:20.174325Z","caller":"traceutil/trace.go:171","msg":"trace[1535879792] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"443.124453ms","start":"2026-04-16T06:05:19.731151Z","end":"2026-04-16T06:05:20.174275Z","steps":["trace[1535879792] 'process raft request' (duration: 325.423398ms)","trace[1535879792] 'compare' (duration: 117.122085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:05:20.174634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:05:19.731119Z","time spent":"443.373221ms","remote":"127.0.0.1:50162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T06:05:20.17434Z","caller":"traceutil/trace.go:171","msg":"trace[1125829411] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"439.313246ms","start":"2026-04-16T06:05:19.734998Z","end":"2026-04-16T06:05:20.174311Z","steps":["trace[1125829411] 'process raft request' (duration: 439.194232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:05:20.175214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:05:19.734976Z","time spent":"439.993748ms","remote":"127.0.0.1:49964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11444,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T06:05:49.421647Z","caller":"traceutil/trace.go:171","msg":"trace[611376368] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"238.635955ms","start":"2026-04-16T06:05:49.182975Z","end":"2026-04-16T06:05:49.421611Z","steps":["trace[611376368] 'read index received' (duration: 236.825689ms)","trace[611376368] 'applied index is now lower than readState.Index' (duration: 1.809006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:05:49.421859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.8492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:05:49.421898Z","caller":"traceutil/trace.go:171","msg":"trace[735353172] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:557; }","duration":"238.919822ms","start":"2026-04-16T06:05:49.182964Z","end":"2026-04-16T06:05:49.421884Z","steps":["trace[735353172] 'agreement among raft nodes before linearized reading' (duration: 238.813949ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:05:49.421845Z","caller":"traceutil/trace.go:171","msg":"trace[1836179254] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"256.891848ms","start":"2026-04-16T06:05:49.16487Z","end":"2026-04-16T06:05:49.421762Z","steps":["trace[1836179254] 'process raft request' (duration: 254.922808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:05:49.422059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.9306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T06:05:49.422179Z","caller":"traceutil/trace.go:171","msg":"trace[34749501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"114.110035ms","start":"2026-04-16T06:05:49.308021Z","end":"2026-04-16T06:05:49.422131Z","steps":["trace[34749501] 'agreement among raft nodes before linearized reading' (duration: 113.823598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:05:49.422061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.734562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T06:05:49.422359Z","caller":"traceutil/trace.go:171","msg":"trace[633344749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"212.071851ms","start":"2026-04-16T06:05:49.210263Z","end":"2026-04-16T06:05:49.422335Z","steps":["trace[633344749] 'agreement among raft nodes before linearized reading' (duration: 211.577049ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:05:52.51191Z","caller":"traceutil/trace.go:171","msg":"trace[973406188] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"223.33965ms","start":"2026-04-16T06:05:52.28853Z","end":"2026-04-16T06:05:52.511869Z","steps":["trace[973406188] 'process raft request' (duration: 223.130195ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:05:52.512495Z","caller":"traceutil/trace.go:171","msg":"trace[744053129] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:600; }","duration":"123.570119ms","start":"2026-04-16T06:05:52.38887Z","end":"2026-04-16T06:05:52.51244Z","steps":["trace[744053129] 'read index received' (duration: 123.560559ms)","trace[744053129] 'applied index is now lower than readState.Index' (duration: 7.48µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:05:52.512731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.794965ms","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-16T06:05:52.5128Z","caller":"traceutil/trace.go:171","msg":"trace[237037821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"123.96823ms","start":"2026-04-16T06:05:52.388814Z","end":"2026-04-16T06:05:52.512782Z","steps":["trace[237037821] 'agreement among raft nodes before linearized reading' (duration: 123.717913ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:05:52.543051Z","caller":"traceutil/trace.go:171","msg":"trace[687260577] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"120.182545ms","start":"2026-04-16T06:05:52.42283Z","end":"2026-04-16T06:05:52.543013Z","steps":["trace[687260577] 'process raft request' (duration: 120.029141ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:06:08.635693Z","caller":"traceutil/trace.go:171","msg":"trace[900829880] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"183.111156ms","start":"2026-04-16T06:06:08.452531Z","end":"2026-04-16T06:06:08.635642Z","steps":["trace[900829880] 'process raft request' (duration: 182.548933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:07:24.938499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.393332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:07:24.938647Z","caller":"traceutil/trace.go:171","msg":"trace[306970142] linearizableReadLoop","detail":"{readStateIndex:1852; appliedIndex:1851; }","duration":"159.835062ms","start":"2026-04-16T06:07:24.77878Z","end":"2026-04-16T06:07:24.938615Z","steps":["trace[306970142] 'read index received' (duration: 45.04µs)","trace[306970142] 'applied index is now lower than readState.Index' (duration: 159.788172ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:07:24.938694Z","caller":"traceutil/trace.go:171","msg":"trace[1562526582] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"304.226903ms","start":"2026-04-16T06:07:24.634427Z","end":"2026-04-16T06:07:24.938654Z","steps":["trace[1562526582] 'process raft request' (duration: 117.392814ms)","trace[1562526582] 'compare' (duration: 185.990436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:07:24.938824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.042845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5f170fb-a1de-4c98-941c-aabe498c2d4e\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-16T06:07:24.939117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:07:24.634393Z","time spent":"304.605139ms","remote":"127.0.0.1:50162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:07:24.939685Z","caller":"traceutil/trace.go:171","msg":"trace[951291296] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5f170fb-a1de-4c98-941c-aabe498c2d4e; range_end:; response_count:1; response_revision:1799; }","duration":"160.375801ms","start":"2026-04-16T06:07:24.778729Z","end":"2026-04-16T06:07:24.939105Z","steps":["trace[951291296] 'agreement among raft nodes before linearized reading' (duration: 159.952254ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:08:50.562911Z","caller":"traceutil/trace.go:171","msg":"trace[608022448] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"105.740235ms","start":"2026-04-16T06:08:50.457148Z","end":"2026-04-16T06:08:50.562889Z","steps":["trace[608022448] 'process raft request' (duration: 105.588563ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:09:17.871717Z","caller":"traceutil/trace.go:171","msg":"trace[1458854400] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"109.427458ms","start":"2026-04-16T06:09:17.762259Z","end":"2026-04-16T06:09:17.871687Z","steps":["trace[1458854400] 'process raft request' (duration: 109.169177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:09:18.167772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.017174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:09:18.16787Z","caller":"traceutil/trace.go:171","msg":"trace[1316698812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2725; }","duration":"196.130879ms","start":"2026-04-16T06:09:17.971716Z","end":"2026-04-16T06:09:18.167847Z","steps":["trace[1316698812] 'range keys from in-memory index tree' (duration: 195.873838ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:10:56.939547Z","caller":"traceutil/trace.go:171","msg":"trace[1914215613] transaction","detail":"{read_only:false; response_revision:3531; number_of_response:1; }","duration":"125.916058ms","start":"2026-04-16T06:10:56.813602Z","end":"2026-04-16T06:10:56.939518Z","steps":["trace[1914215613] 'process raft request' (duration: 63.189921ms)","trace[1914215613] 'compare' (duration: 62.269404ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:11:54.005211Z","caller":"traceutil/trace.go:171","msg":"trace[1030122688] transaction","detail":"{read_only:false; response_revision:4456; number_of_response:1; }","duration":"123.439064ms","start":"2026-04-16T06:11:53.881741Z","end":"2026-04-16T06:11:54.00518Z","steps":["trace[1030122688] 'process raft request' (duration: 123.189938ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:11:54.005963Z","caller":"traceutil/trace.go:171","msg":"trace[938146318] transaction","detail":"{read_only:false; response_revision:4457; number_of_response:1; }","duration":"121.042475ms","start":"2026-04-16T06:11:53.884882Z","end":"2026-04-16T06:11:54.005925Z","steps":["trace[938146318] 'process raft request' (duration: 120.225356ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:11:54.114682Z","caller":"traceutil/trace.go:171","msg":"trace[811154569] transaction","detail":"{read_only:false; response_revision:4458; number_of_response:1; }","duration":"100.40228ms","start":"2026-04-16T06:11:54.014233Z","end":"2026-04-16T06:11:54.114635Z","steps":["trace[811154569] 'process raft request' (duration: 47.439918ms)","trace[811154569] 'compare' (duration: 52.799728ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:11:54.118144Z","caller":"traceutil/trace.go:171","msg":"trace[531448122] transaction","detail":"{read_only:false; response_revision:4459; number_of_response:1; }","duration":"101.764272ms","start":"2026-04-16T06:11:54.016348Z","end":"2026-04-16T06:11:54.118113Z","steps":["trace[531448122] 'process raft request' (duration: 101.467975ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:11:54.118162Z","caller":"traceutil/trace.go:171","msg":"trace[65631348] transaction","detail":"{read_only:false; response_revision:4460; number_of_response:1; }","duration":"101.300081ms","start":"2026-04-16T06:11:54.016833Z","end":"2026-04-16T06:11:54.118133Z","steps":["trace[65631348] 'process raft request' (duration: 101.138227ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:11:54.118408Z","caller":"traceutil/trace.go:171","msg":"trace[2093798165] transaction","detail":"{read_only:false; response_revision:4461; number_of_response:1; }","duration":"101.2568ms","start":"2026-04-16T06:11:54.017122Z","end":"2026-04-16T06:11:54.118379Z","steps":["trace[2093798165] 'process raft request' (duration: 100.916492ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:00.545239Z","caller":"traceutil/trace.go:171","msg":"trace[1941464898] linearizableReadLoop","detail":"{readStateIndex:4768; appliedIndex:4767; }","duration":"361.38536ms","start":"2026-04-16T06:12:00.183823Z","end":"2026-04-16T06:12:00.545209Z","steps":["trace[1941464898] 'read index received' (duration: 361.274187ms)","trace[1941464898] 'applied index is now lower than readState.Index' (duration: 109.953µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:12:00.545615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.755118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-16T06:12:00.545659Z","caller":"traceutil/trace.go:171","msg":"trace[791170926] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4568; }","duration":"361.84488ms","start":"2026-04-16T06:12:00.183802Z","end":"2026-04-16T06:12:00.545647Z","steps":["trace[791170926] 'agreement among raft nodes before linearized reading' (duration: 361.528583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:00.5457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:00.183793Z","time spent":"361.898252ms","remote":"127.0.0.1:50072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1016,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"info","ts":"2026-04-16T06:12:00.546299Z","caller":"traceutil/trace.go:171","msg":"trace[1084519292] transaction","detail":"{read_only:false; response_revision:4568; number_of_response:1; }","duration":"362.907815ms","start":"2026-04-16T06:12:00.18246Z","end":"2026-04-16T06:12:00.545367Z","steps":["trace[1084519292] 'process raft request' (duration: 362.556577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:00.547581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:00.182435Z","time spent":"364.093874ms","remote":"127.0.0.1:49972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:12:00.564314Z","caller":"traceutil/trace.go:171","msg":"trace[1846634836] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"337.375277ms","start":"2026-04-16T06:12:00.226915Z","end":"2026-04-16T06:12:00.56429Z","steps":["trace[1846634836] 'process raft request' (duration: 337.254104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:00.564467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:00.226884Z","time spent":"337.50191ms","remote":"127.0.0.1:42234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2228,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T06:12:00.869988Z","caller":"traceutil/trace.go:171","msg":"trace[52014661] transaction","detail":"{read_only:false; response_revision:4577; number_of_response:1; }","duration":"151.477439ms","start":"2026-04-16T06:12:00.718475Z","end":"2026-04-16T06:12:00.869952Z","steps":["trace[52014661] 'process raft request' (duration: 97.839901ms)","trace[52014661] 'compare' (duration: 53.404482ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:00.870387Z","caller":"traceutil/trace.go:171","msg":"trace[893527080] transaction","detail":"{read_only:false; response_revision:4578; number_of_response:1; }","duration":"150.918956ms","start":"2026-04-16T06:12:00.719456Z","end":"2026-04-16T06:12:00.870375Z","steps":["trace[893527080] 'process raft request' (duration: 150.430134ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:00.870455Z","caller":"traceutil/trace.go:171","msg":"trace[305229672] linearizableReadLoop","detail":"{readStateIndex:4781; appliedIndex:4776; }","duration":"142.761481ms","start":"2026-04-16T06:12:00.727666Z","end":"2026-04-16T06:12:00.870428Z","steps":["trace[305229672] 'read index received' (duration: 88.598861ms)","trace[305229672] 'applied index is now lower than readState.Index' (duration: 54.16051ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:00.870602Z","caller":"traceutil/trace.go:171","msg":"trace[733980218] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"148.915238ms","start":"2026-04-16T06:12:00.721677Z","end":"2026-04-16T06:12:00.870592Z","steps":["trace[733980218] 'process raft request' (duration: 148.626271ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:00.870654Z","caller":"traceutil/trace.go:171","msg":"trace[1760000421] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"150.049165ms","start":"2026-04-16T06:12:00.720565Z","end":"2026-04-16T06:12:00.870614Z","steps":["trace[1760000421] 'process raft request' (duration: 149.601824ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:00.870759Z","caller":"traceutil/trace.go:171","msg":"trace[182709434] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"148.785985ms","start":"2026-04-16T06:12:00.721966Z","end":"2026-04-16T06:12:00.870752Z","steps":["trace[182709434] 'process raft request' (duration: 148.415086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:00.871141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.567531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ae507a44-71c6-4b4e-a945-d59786e419c1\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-04-16T06:12:00.871194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.914673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10616"} {"level":"info","ts":"2026-04-16T06:12:00.871224Z","caller":"traceutil/trace.go:171","msg":"trace[1049004905] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4581; }","duration":"153.010436ms","start":"2026-04-16T06:12:00.718203Z","end":"2026-04-16T06:12:00.871214Z","steps":["trace[1049004905] 'agreement among raft nodes before linearized reading' (duration: 152.872513ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:00.871212Z","caller":"traceutil/trace.go:171","msg":"trace[616593154] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ae507a44-71c6-4b4e-a945-d59786e419c1; range_end:; response_count:1; response_revision:4581; }","duration":"138.692444ms","start":"2026-04-16T06:12:00.7325Z","end":"2026-04-16T06:12:00.871192Z","steps":["trace[616593154] 'agreement among raft nodes before linearized reading' (duration: 138.494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:00.871314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.709008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-04-16T06:12:00.871355Z","caller":"traceutil/trace.go:171","msg":"trace[815733577] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4581; }","duration":"152.800571ms","start":"2026-04-16T06:12:00.71854Z","end":"2026-04-16T06:12:00.87134Z","steps":["trace[815733577] 'agreement among raft nodes before linearized reading' (duration: 152.634897ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:01.222337Z","caller":"traceutil/trace.go:171","msg":"trace[2052343864] linearizableReadLoop","detail":"{readStateIndex:4789; appliedIndex:4788; }","duration":"152.813342ms","start":"2026-04-16T06:12:01.069479Z","end":"2026-04-16T06:12:01.222293Z","steps":["trace[2052343864] 'read index received' (duration: 152.73423ms)","trace[2052343864] 'applied index is now lower than readState.Index' (duration: 78.011µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:01.222762Z","caller":"traceutil/trace.go:171","msg":"trace[2075874917] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"155.430843ms","start":"2026-04-16T06:12:01.067319Z","end":"2026-04-16T06:12:01.22275Z","steps":["trace[2075874917] 'process raft request' (duration: 154.810489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:01.223097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.60135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-16T06:12:01.223137Z","caller":"traceutil/trace.go:171","msg":"trace[1232144932] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4588; }","duration":"153.689152ms","start":"2026-04-16T06:12:01.069437Z","end":"2026-04-16T06:12:01.223126Z","steps":["trace[1232144932] 'agreement among raft nodes before linearized reading' (duration: 153.529349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:01.22966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.105515ms","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-16T06:12:01.229787Z","caller":"traceutil/trace.go:171","msg":"trace[1794067279] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4589; }","duration":"131.3034ms","start":"2026-04-16T06:12:01.098454Z","end":"2026-04-16T06:12:01.229757Z","steps":["trace[1794067279] 'agreement among raft nodes before linearized reading' (duration: 131.075124ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:13.984504Z","caller":"traceutil/trace.go:171","msg":"trace[1872880548] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"278.565009ms","start":"2026-04-16T06:12:13.705907Z","end":"2026-04-16T06:12:13.984472Z","steps":["trace[1872880548] 'process raft request' (duration: 277.796602ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.045362Z","caller":"traceutil/trace.go:171","msg":"trace[237424814] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"337.899827ms","start":"2026-04-16T06:12:13.70743Z","end":"2026-04-16T06:12:14.045329Z","steps":["trace[237424814] 'process raft request' (duration: 337.689872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:14.045534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:13.707424Z","time spent":"338.02956ms","remote":"127.0.0.1:50062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10793,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:12:14.092022Z","caller":"traceutil/trace.go:171","msg":"trace[760827521] linearizableReadLoop","detail":"{readStateIndex:4979; appliedIndex:4977; }","duration":"340.989306ms","start":"2026-04-16T06:12:13.751001Z","end":"2026-04-16T06:12:14.091991Z","steps":["trace[760827521] 'read index received' (duration: 231.861711ms)","trace[760827521] 'applied index is now lower than readState.Index' (duration: 109.126054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:12:14.092383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.385575ms","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-16T06:12:14.092431Z","caller":"traceutil/trace.go:171","msg":"trace[1551443251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4772; }","duration":"341.492137ms","start":"2026-04-16T06:12:13.750928Z","end":"2026-04-16T06:12:14.09242Z","steps":["trace[1551443251] 'agreement among raft nodes before linearized reading' (duration: 341.324224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:14.092463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:13.750881Z","time spent":"341.573909ms","remote":"127.0.0.1:50162","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-16T06:12:14.101392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.089248ms","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-16T06:12:14.101493Z","caller":"traceutil/trace.go:171","msg":"trace[575709462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4773; }","duration":"208.254302ms","start":"2026-04-16T06:12:13.89322Z","end":"2026-04-16T06:12:14.101474Z","steps":["trace[575709462] 'agreement among raft nodes before linearized reading' (duration: 208.007936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:14.10165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.9876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T06:12:14.101753Z","caller":"traceutil/trace.go:171","msg":"trace[2006187536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4773; }","duration":"203.159214ms","start":"2026-04-16T06:12:13.898571Z","end":"2026-04-16T06:12:14.10173Z","steps":["trace[2006187536] 'agreement among raft nodes before linearized reading' (duration: 202.803285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:14.10197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.340242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-04-16T06:12:14.102056Z","caller":"traceutil/trace.go:171","msg":"trace[960136458] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4773; }","duration":"110.493236ms","start":"2026-04-16T06:12:13.991544Z","end":"2026-04-16T06:12:14.102037Z","steps":["trace[960136458] 'agreement among raft nodes before linearized reading' (duration: 110.23132ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.203221Z","caller":"traceutil/trace.go:171","msg":"trace[662093557] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"102.605302ms","start":"2026-04-16T06:12:14.100591Z","end":"2026-04-16T06:12:14.203196Z","steps":["trace[662093557] 'process raft request' (duration: 102.554911ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.203422Z","caller":"traceutil/trace.go:171","msg":"trace[1743265362] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"104.656069ms","start":"2026-04-16T06:12:14.098729Z","end":"2026-04-16T06:12:14.203385Z","steps":["trace[1743265362] 'process raft request' (duration: 104.097066ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.203786Z","caller":"traceutil/trace.go:171","msg":"trace[76359820] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"104.380853ms","start":"2026-04-16T06:12:14.099387Z","end":"2026-04-16T06:12:14.203768Z","steps":["trace[76359820] 'process raft request' (duration: 103.601215ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.204045Z","caller":"traceutil/trace.go:171","msg":"trace[737102695] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"104.484046ms","start":"2026-04-16T06:12:14.099546Z","end":"2026-04-16T06:12:14.204031Z","steps":["trace[737102695] 'process raft request' (duration: 103.510563ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.204723Z","caller":"traceutil/trace.go:171","msg":"trace[1557062829] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"103.860141ms","start":"2026-04-16T06:12:14.100303Z","end":"2026-04-16T06:12:14.204163Z","steps":["trace[1557062829] 'process raft request' (duration: 102.797106ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.224847Z","caller":"traceutil/trace.go:171","msg":"trace[1543761847] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"120.118546ms","start":"2026-04-16T06:12:14.10468Z","end":"2026-04-16T06:12:14.224798Z","steps":["trace[1543761847] 'process raft request' (duration: 119.85303ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.224858Z","caller":"traceutil/trace.go:171","msg":"trace[566690796] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"118.541219ms","start":"2026-04-16T06:12:14.106285Z","end":"2026-04-16T06:12:14.224826Z","steps":["trace[566690796] 'process raft request' (duration: 118.477458ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.224929Z","caller":"traceutil/trace.go:171","msg":"trace[1394630102] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"118.690313ms","start":"2026-04-16T06:12:14.106203Z","end":"2026-04-16T06:12:14.224893Z","steps":["trace[1394630102] 'process raft request' (duration: 118.513979ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.225301Z","caller":"traceutil/trace.go:171","msg":"trace[2114905670] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"113.739618ms","start":"2026-04-16T06:12:14.11155Z","end":"2026-04-16T06:12:14.22529Z","steps":["trace[2114905670] 'process raft request' (duration: 113.674507ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.225451Z","caller":"traceutil/trace.go:171","msg":"trace[928378022] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"119.051791ms","start":"2026-04-16T06:12:14.106322Z","end":"2026-04-16T06:12:14.225373Z","steps":["trace[928378022] 'process raft request' (duration: 118.485538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:14.393462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.512899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:12:14.393719Z","caller":"traceutil/trace.go:171","msg":"trace[1311409294] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"155.00631ms","start":"2026-04-16T06:12:14.238691Z","end":"2026-04-16T06:12:14.393697Z","steps":["trace[1311409294] 'process raft request' (duration: 154.896267ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:14.393781Z","caller":"traceutil/trace.go:171","msg":"trace[846021502] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"158.447208ms","start":"2026-04-16T06:12:14.235263Z","end":"2026-04-16T06:12:14.393711Z","steps":["trace[846021502] 'process raft request' (duration: 52.510749ms)","trace[846021502] 'compare' (duration: 105.345186ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:51.227182Z","caller":"traceutil/trace.go:171","msg":"trace[942752177] linearizableReadLoop","detail":"{readStateIndex:5381; appliedIndex:5380; }","duration":"187.457443ms","start":"2026-04-16T06:12:51.039689Z","end":"2026-04-16T06:12:51.227146Z","steps":["trace[942752177] 'read index received' (duration: 187.144816ms)","trace[942752177] 'applied index is now lower than readState.Index' (duration: 311.267µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:51.227222Z","caller":"traceutil/trace.go:171","msg":"trace[651086717] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"256.317921ms","start":"2026-04-16T06:12:50.970871Z","end":"2026-04-16T06:12:51.227189Z","steps":["trace[651086717] 'process raft request' (duration: 256.075095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:51.227509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.467798ms","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-16T06:12:51.227568Z","caller":"traceutil/trace.go:171","msg":"trace[1206767498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5158; }","duration":"183.57772ms","start":"2026-04-16T06:12:51.043975Z","end":"2026-04-16T06:12:51.227552Z","steps":["trace[1206767498] 'agreement among raft nodes before linearized reading' (duration: 183.363596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:51.227742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.010144ms","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-16T06:12:51.227877Z","caller":"traceutil/trace.go:171","msg":"trace[2059219355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5158; }","duration":"188.229389ms","start":"2026-04-16T06:12:51.039623Z","end":"2026-04-16T06:12:51.227852Z","steps":["trace[2059219355] 'agreement among raft nodes before linearized reading' (duration: 187.639766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:53.794808Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954075008346941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T06:12:54.217845Z","caller":"traceutil/trace.go:171","msg":"trace[1350873739] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"972.667609ms","start":"2026-04-16T06:12:53.244955Z","end":"2026-04-16T06:12:54.217623Z","steps":["trace[1350873739] 'process raft request' (duration: 972.439475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:54.218071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.244939Z","time spent":"973.002526ms","remote":"127.0.0.1:50162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:12:55.690452Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.47300712s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T06:12:55.695923Z","caller":"traceutil/trace.go:171","msg":"trace[139351313] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"2.447530068s","start":"2026-04-16T06:12:53.248361Z","end":"2026-04-16T06:12:55.695892Z","steps":["trace[139351313] 'process raft request' (duration: 2.447363005s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.696261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.248335Z","time spent":"2.447679301s","remote":"127.0.0.1:50124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6244,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:12:55.696306Z","caller":"traceutil/trace.go:171","msg":"trace[625765270] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"2.451051983s","start":"2026-04-16T06:12:53.245209Z","end":"2026-04-16T06:12:55.696261Z","steps":["trace[625765270] 'process raft request' (duration: 2.445600198s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.696342Z","caller":"traceutil/trace.go:171","msg":"trace[1759630509] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"2.180384191s","start":"2026-04-16T06:12:53.515939Z","end":"2026-04-16T06:12:55.696323Z","steps":["trace[1759630509] 'process raft request' (duration: 2.179886441s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.696443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.515909Z","time spent":"2.180489783s","remote":"127.0.0.1:50162","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-16T06:12:55.696483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.245198Z","time spent":"2.451175976s","remote":"127.0.0.1:50162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:12:55.701407Z","caller":"traceutil/trace.go:171","msg":"trace[371114560] linearizableReadLoop","detail":"{readStateIndex:5403; appliedIndex:5399; }","duration":"2.411914932s","start":"2026-04-16T06:12:53.289477Z","end":"2026-04-16T06:12:55.701392Z","steps":["trace[371114560] 'read index received' (duration: 928.011712ms)","trace[371114560] 'applied index is now lower than readState.Index' (duration: 1.48390169s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:12:55.701624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.713393931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T06:12:55.701617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.330001694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.701694Z","caller":"traceutil/trace.go:171","msg":"trace[1730573052] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5180; }","duration":"2.330138707s","start":"2026-04-16T06:12:53.371539Z","end":"2026-04-16T06:12:55.701677Z","steps":["trace[1730573052] 'agreement among raft nodes before linearized reading' (duration: 2.330012374s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.701745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256737249s","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-16T06:12:55.701764Z","caller":"traceutil/trace.go:171","msg":"trace[2141904242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5180; }","duration":"1.256787741s","start":"2026-04-16T06:12:54.444972Z","end":"2026-04-16T06:12:55.701759Z","steps":["trace[2141904242] 'agreement among raft nodes before linearized reading' (duration: 1.256697709s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.701758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.371512Z","time spent":"2.330237019s","remote":"127.0.0.1:50124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-16T06:12:55.701783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.444952Z","time spent":"1.256827711s","remote":"127.0.0.1:50162","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-16T06:12:55.701939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637126195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.701965Z","caller":"traceutil/trace.go:171","msg":"trace[1431523752] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5180; }","duration":"1.637151436s","start":"2026-04-16T06:12:54.064805Z","end":"2026-04-16T06:12:55.701957Z","steps":["trace[1431523752] 'agreement among raft nodes before linearized reading' (duration: 1.637107135s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.701982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.064777Z","time spent":"1.637199637s","remote":"127.0.0.1:53430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:55.702091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.327883919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6259"} {"level":"info","ts":"2026-04-16T06:12:55.702122Z","caller":"traceutil/trace.go:171","msg":"trace[125631852] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5180; }","duration":"2.32792941s","start":"2026-04-16T06:12:53.374185Z","end":"2026-04-16T06:12:55.702114Z","steps":["trace[125631852] 'agreement among raft nodes before linearized reading' (duration: 2.327815158s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.702143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.374172Z","time spent":"2.327964851s","remote":"127.0.0.1:50124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6282,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-04-16T06:12:55.701949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270354638s","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":"warn","ts":"2026-04-16T06:12:55.702246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.329398841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.702265Z","caller":"traceutil/trace.go:171","msg":"trace[1066314516] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5180; }","duration":"2.329459472s","start":"2026-04-16T06:12:53.3728Z","end":"2026-04-16T06:12:55.702259Z","steps":["trace[1066314516] 'agreement among raft nodes before linearized reading' (duration: 2.329430812s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.702268Z","caller":"traceutil/trace.go:171","msg":"trace[1438575219] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5180; }","duration":"1.270747946s","start":"2026-04-16T06:12:54.431504Z","end":"2026-04-16T06:12:55.702252Z","steps":["trace[1438575219] 'agreement among raft nodes before linearized reading' (duration: 1.270355938s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.70263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714571097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.702659Z","caller":"traceutil/trace.go:171","msg":"trace[1232149507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5180; }","duration":"1.714655809s","start":"2026-04-16T06:12:53.987997Z","end":"2026-04-16T06:12:55.702653Z","steps":["trace[1232149507] 'agreement among raft nodes before linearized reading' (duration: 1.714607848s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.702678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.98797Z","time spent":"1.71470312s","remote":"127.0.0.1:49878","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-16T06:12:55.701695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.906471449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T06:12:55.702724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.936205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.702779Z","caller":"traceutil/trace.go:171","msg":"trace[1648743515] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5180; }","duration":"136.999216ms","start":"2026-04-16T06:12:55.565765Z","end":"2026-04-16T06:12:55.702765Z","steps":["trace[1648743515] 'agreement among raft nodes before linearized reading' (duration: 136.907734ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.702994Z","caller":"traceutil/trace.go:171","msg":"trace[1207097733] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"151.3641ms","start":"2026-04-16T06:12:55.551621Z","end":"2026-04-16T06:12:55.702985Z","steps":["trace[1207097733] 'process raft request' (duration: 149.559321ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.702769Z","caller":"traceutil/trace.go:171","msg":"trace[617847385] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:5180; }","duration":"1.907596753s","start":"2026-04-16T06:12:53.795158Z","end":"2026-04-16T06:12:55.702755Z","steps":["trace[617847385] 'agreement among raft nodes before linearized reading' (duration: 1.906465549s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.703117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.431468Z","time spent":"1.271552473s","remote":"127.0.0.1:36298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:55.703155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.795134Z","time spent":"1.908008001s","remote":"127.0.0.1:50072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":38,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:55.702295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.372764Z","time spent":"2.329521084s","remote":"127.0.0.1:50124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"info","ts":"2026-04-16T06:12:55.701656Z","caller":"traceutil/trace.go:171","msg":"trace[976107382] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5180; }","duration":"1.713448983s","start":"2026-04-16T06:12:53.988198Z","end":"2026-04-16T06:12:55.701647Z","steps":["trace[976107382] 'agreement among raft nodes before linearized reading' (duration: 1.713391132s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.703495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.98819Z","time spent":"1.715293482s","remote":"127.0.0.1:50186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":30,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:55.704342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.414867885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-16T06:12:55.704383Z","caller":"traceutil/trace.go:171","msg":"trace[1048834348] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5180; }","duration":"2.414957207s","start":"2026-04-16T06:12:53.289411Z","end":"2026-04-16T06:12:55.704369Z","steps":["trace[1048834348] 'agreement among raft nodes before linearized reading' (duration: 2.412068016s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.704548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.289395Z","time spent":"2.415087709s","remote":"127.0.0.1:49972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-16T06:12:55.70463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253722696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-16T06:12:55.704711Z","caller":"traceutil/trace.go:171","msg":"trace[200685152] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5180; }","duration":"1.253821218s","start":"2026-04-16T06:12:54.450878Z","end":"2026-04-16T06:12:55.704699Z","steps":["trace[200685152] 'agreement among raft nodes before linearized reading' (duration: 1.253565342s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.704752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.450851Z","time spent":"1.253881519s","remote":"127.0.0.1:50162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-16T06:12:55.704985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253564553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T06:12:55.705025Z","caller":"traceutil/trace.go:171","msg":"trace[1697683930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5180; }","duration":"1.253618283s","start":"2026-04-16T06:12:54.4514Z","end":"2026-04-16T06:12:55.705018Z","steps":["trace[1697683930] 'agreement among raft nodes before linearized reading' (duration: 1.251296344s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.705044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.451392Z","time spent":"1.253646944s","remote":"127.0.0.1:50162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T06:12:55.705644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004459756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-16T06:12:55.705688Z","caller":"traceutil/trace.go:171","msg":"trace[1941237397] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5180; }","duration":"1.005683622s","start":"2026-04-16T06:12:54.699991Z","end":"2026-04-16T06:12:55.705675Z","steps":["trace[1941237397] 'agreement among raft nodes before linearized reading' (duration: 1.004435826s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.705834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.699963Z","time spent":"1.005859416s","remote":"127.0.0.1:49972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-16T06:12:55.707009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253035811s","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-16T06:12:55.707215Z","caller":"traceutil/trace.go:171","msg":"trace[1252304953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5180; }","duration":"1.257929735s","start":"2026-04-16T06:12:54.449273Z","end":"2026-04-16T06:12:55.707203Z","steps":["trace[1252304953] 'agreement among raft nodes before linearized reading' (duration: 1.252307216s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.707244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.449264Z","time spent":"1.257969955s","remote":"127.0.0.1:50162","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-16T06:12:55.954191Z","caller":"traceutil/trace.go:171","msg":"trace[1349210095] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"247.053482ms","start":"2026-04-16T06:12:55.707111Z","end":"2026-04-16T06:12:55.954164Z","steps":["trace[1349210095] 'process raft request' (duration: 246.898689ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.957862Z","caller":"traceutil/trace.go:171","msg":"trace[2006702361] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"248.79656ms","start":"2026-04-16T06:12:55.709028Z","end":"2026-04-16T06:12:55.957825Z","steps":["trace[2006702361] 'process raft request' (duration: 248.483193ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.957849Z","caller":"traceutil/trace.go:171","msg":"trace[369888951] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"248.479312ms","start":"2026-04-16T06:12:55.709335Z","end":"2026-04-16T06:12:55.957814Z","steps":["trace[369888951] 'process raft request' (duration: 248.36939ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.9582Z","caller":"traceutil/trace.go:171","msg":"trace[8102245] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"248.546224ms","start":"2026-04-16T06:12:55.709624Z","end":"2026-04-16T06:12:55.95817Z","steps":["trace[8102245] 'process raft request' (duration: 248.144705ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.958288Z","caller":"traceutil/trace.go:171","msg":"trace[35402175] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"248.463012ms","start":"2026-04-16T06:12:55.709817Z","end":"2026-04-16T06:12:55.95828Z","steps":["trace[35402175] 'process raft request' (duration: 248.174986ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.958451Z","caller":"traceutil/trace.go:171","msg":"trace[324224411] linearizableReadLoop","detail":"{readStateIndex:5411; appliedIndex:5405; }","duration":"247.590664ms","start":"2026-04-16T06:12:55.710844Z","end":"2026-04-16T06:12:55.958435Z","steps":["trace[324224411] 'read index received' (duration: 243.13393ms)","trace[324224411] 'applied index is now lower than readState.Index' (duration: 4.455374ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:55.958553Z","caller":"traceutil/trace.go:171","msg":"trace[1634168932] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"248.481563ms","start":"2026-04-16T06:12:55.71006Z","end":"2026-04-16T06:12:55.958541Z","steps":["trace[1634168932] 'process raft request' (duration: 248.157346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.958739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.8684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-04-16T06:12:55.95879Z","caller":"traceutil/trace.go:171","msg":"trace[1841094618] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5186; }","duration":"247.944642ms","start":"2026-04-16T06:12:55.710831Z","end":"2026-04-16T06:12:55.958776Z","steps":["trace[1841094618] 'agreement among raft nodes before linearized reading' (duration: 247.779218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.959486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.266027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.959581Z","caller":"traceutil/trace.go:171","msg":"trace[868162904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5186; }","duration":"248.272329ms","start":"2026-04-16T06:12:55.711296Z","end":"2026-04-16T06:12:55.959569Z","steps":["trace[868162904] 'agreement among raft nodes before linearized reading' (duration: 247.252817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.468014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954075008346959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:12:56.57838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.696063ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.578494Z","caller":"traceutil/trace.go:171","msg":"trace[513634530] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5186; }","duration":"443.850706ms","start":"2026-04-16T06:12:56.134627Z","end":"2026-04-16T06:12:56.578477Z","steps":["trace[513634530] 'range keys from in-memory index tree' (duration: 443.674571ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.586599Z","caller":"traceutil/trace.go:171","msg":"trace[554914094] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"620.961395ms","start":"2026-04-16T06:12:55.965615Z","end":"2026-04-16T06:12:56.586577Z","steps":["trace[554914094] 'process raft request' (duration: 620.810132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.586748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:55.965589Z","time spent":"621.075037ms","remote":"127.0.0.1:50124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6238,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:12:56.587859Z","caller":"traceutil/trace.go:171","msg":"trace[441755892] linearizableReadLoop","detail":"{readStateIndex:5413; appliedIndex:5411; }","duration":"620.836121ms","start":"2026-04-16T06:12:55.966999Z","end":"2026-04-16T06:12:56.587835Z","steps":["trace[441755892] 'read index received' (duration: 619.483903ms)","trace[441755892] 'applied index is now lower than readState.Index' (duration: 1.351238ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:56.588287Z","caller":"traceutil/trace.go:171","msg":"trace[725290895] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"622.464426ms","start":"2026-04-16T06:12:55.965809Z","end":"2026-04-16T06:12:56.588274Z","steps":["trace[725290895] 'process raft request' (duration: 621.871513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.588512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:55.965773Z","time spent":"622.578738ms","remote":"127.0.0.1:49972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:12:56.588574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.216999ms","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-16T06:12:56.588619Z","caller":"traceutil/trace.go:171","msg":"trace[2109972844] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5188; }","duration":"376.297841ms","start":"2026-04-16T06:12:56.212311Z","end":"2026-04-16T06:12:56.588608Z","steps":["trace[2109972844] 'agreement among raft nodes before linearized reading' (duration: 376.24381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.588647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.8841ms","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-16T06:12:56.588684Z","caller":"traceutil/trace.go:171","msg":"trace[316933626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5188; }","duration":"365.966882ms","start":"2026-04-16T06:12:56.222707Z","end":"2026-04-16T06:12:56.588674Z","steps":["trace[316933626] 'agreement among raft nodes before linearized reading' (duration: 365.825649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.588701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.212286Z","time spent":"376.408323ms","remote":"127.0.0.1:36336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:56.588716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.222682Z","time spent":"366.028244ms","remote":"127.0.0.1:50162","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-16T06:12:56.588531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.804559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.588945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.917645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-16T06:12:56.58898Z","caller":"traceutil/trace.go:171","msg":"trace[84274947] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5188; }","duration":"621.975996ms","start":"2026-04-16T06:12:55.966993Z","end":"2026-04-16T06:12:56.588969Z","steps":["trace[84274947] 'agreement among raft nodes before linearized reading' (duration: 621.864684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.589005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:55.966952Z","time spent":"622.047217ms","remote":"127.0.0.1:50162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-16T06:12:56.58896Z","caller":"traceutil/trace.go:171","msg":"trace[447420576] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5188; }","duration":"611.235668ms","start":"2026-04-16T06:12:55.977689Z","end":"2026-04-16T06:12:56.588925Z","steps":["trace[447420576] 'agreement among raft nodes before linearized reading' (duration: 610.780399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.589161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:55.977633Z","time spent":"611.488705ms","remote":"127.0.0.1:50102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:15:15.72311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3200} {"level":"info","ts":"2026-04-16T06:15:15.818591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3200,"took":"93.562107ms","hash":3008303524,"current-db-size-bytes":58699776,"current-db-size":"59 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-16T06:15:15.818693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3008303524,"revision":3200,"compact-revision":-1} {"level":"warn","ts":"2026-04-16T06:15:57.047808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.27747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:15:57.048092Z","caller":"traceutil/trace.go:171","msg":"trace[2142727228] transaction","detail":"{read_only:false; response_revision:6677; number_of_response:1; }","duration":"181.085284ms","start":"2026-04-16T06:15:56.866976Z","end":"2026-04-16T06:15:57.048061Z","steps":["trace[2142727228] 'process raft request' (duration: 64.416358ms)","trace[2142727228] 'compare' (duration: 116.041046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:16:19.183182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.939678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:16:19.18452Z","caller":"traceutil/trace.go:171","msg":"trace[2096921240] linearizableReadLoop","detail":"{readStateIndex:7192; appliedIndex:7191; }","duration":"216.369196ms","start":"2026-04-16T06:16:18.968135Z","end":"2026-04-16T06:16:19.184505Z","steps":["trace[2096921240] 'read index received' (duration: 30.895601ms)","trace[2096921240] 'applied index is now lower than readState.Index' (duration: 185.471865ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:16:19.184662Z","caller":"traceutil/trace.go:171","msg":"trace[1932316378] transaction","detail":"{read_only:false; response_revision:6875; number_of_response:1; }","duration":"219.754003ms","start":"2026-04-16T06:16:18.964897Z","end":"2026-04-16T06:16:19.184651Z","steps":["trace[1932316378] 'process raft request' (duration: 34.260308ms)","trace[1932316378] 'compare' (duration: 183.815756ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:16:19.18512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.964526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-16T06:16:19.18521Z","caller":"traceutil/trace.go:171","msg":"trace[2074683991] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6875; }","duration":"217.097057ms","start":"2026-04-16T06:16:18.96809Z","end":"2026-04-16T06:16:19.185187Z","steps":["trace[2074683991] 'agreement among raft nodes before linearized reading' (duration: 216.941645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:16:19.185417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.452122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:16:19.185452Z","caller":"traceutil/trace.go:171","msg":"trace[889502115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6875; }","duration":"214.504583ms","start":"2026-04-16T06:16:18.970939Z","end":"2026-04-16T06:16:19.185444Z","steps":["trace[889502115] 'agreement among raft nodes before linearized reading' (duration: 214.453292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:16:19.191302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.325251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-16T06:16:19.191461Z","caller":"traceutil/trace.go:171","msg":"trace[1891542354] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6875; }","duration":"210.523404ms","start":"2026-04-16T06:16:18.980907Z","end":"2026-04-16T06:16:19.19143Z","steps":["trace[1891542354] 'agreement among raft nodes before linearized reading' (duration: 204.235187ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:05.701721Z","caller":"traceutil/trace.go:171","msg":"trace[484970041] transaction","detail":"{read_only:false; response_revision:7188; number_of_response:1; }","duration":"258.888126ms","start":"2026-04-16T06:17:05.44282Z","end":"2026-04-16T06:17:05.701708Z","steps":["trace[484970041] 'process raft request' (duration: 258.659032ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:05.701638Z","caller":"traceutil/trace.go:171","msg":"trace[1897270384] linearizableReadLoop","detail":"{readStateIndex:7524; appliedIndex:7523; }","duration":"195.238933ms","start":"2026-04-16T06:17:05.506382Z","end":"2026-04-16T06:17:05.701621Z","steps":["trace[1897270384] 'read index received' (duration: 195.0978ms)","trace[1897270384] 'applied index is now lower than readState.Index' (duration: 140.313µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:17:05.701956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.552188ms","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-16T06:17:05.702044Z","caller":"traceutil/trace.go:171","msg":"trace[393559722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7188; }","duration":"195.646309ms","start":"2026-04-16T06:17:05.506375Z","end":"2026-04-16T06:17:05.702021Z","steps":["trace[393559722] 'agreement among raft nodes before linearized reading' (duration: 195.428606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.708292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.114912ms","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-16T06:17:05.708358Z","caller":"traceutil/trace.go:171","msg":"trace[601192278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7189; }","duration":"201.242584ms","start":"2026-04-16T06:17:05.507099Z","end":"2026-04-16T06:17:05.708342Z","steps":["trace[601192278] 'agreement among raft nodes before linearized reading' (duration: 201.069361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.708344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.198918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:17:05.708429Z","caller":"traceutil/trace.go:171","msg":"trace[920920970] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:7189; }","duration":"126.347431ms","start":"2026-04-16T06:17:05.582064Z","end":"2026-04-16T06:17:05.708411Z","steps":["trace[920920970] 'agreement among raft nodes before linearized reading' (duration: 126.118237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.708574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.665394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T06:17:05.708617Z","caller":"traceutil/trace.go:171","msg":"trace[327637679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7189; }","duration":"197.745454ms","start":"2026-04-16T06:17:05.51086Z","end":"2026-04-16T06:17:05.708606Z","steps":["trace[327637679] 'agreement among raft nodes before linearized reading' (duration: 197.654593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.708838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.28422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-16T06:17:05.708883Z","caller":"traceutil/trace.go:171","msg":"trace[1714806845] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7189; }","duration":"196.787449ms","start":"2026-04-16T06:17:05.512083Z","end":"2026-04-16T06:17:05.708871Z","steps":["trace[1714806845] 'agreement among raft nodes before linearized reading' (duration: 196.196509ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:05.709273Z","caller":"traceutil/trace.go:171","msg":"trace[1219744608] transaction","detail":"{read_only:false; response_revision:7189; number_of_response:1; }","duration":"158.523363ms","start":"2026-04-16T06:17:05.550711Z","end":"2026-04-16T06:17:05.709235Z","steps":["trace[1219744608] 'process raft request' (duration: 157.321813ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:06.474814Z","caller":"traceutil/trace.go:171","msg":"trace[2063858326] transaction","detail":"{read_only:false; response_revision:7199; number_of_response:1; }","duration":"155.554272ms","start":"2026-04-16T06:17:06.319237Z","end":"2026-04-16T06:17:06.474791Z","steps":["trace[2063858326] 'process raft request' (duration: 155.39293ms)"],"step_count":1}