{"level":"warn","ts":"2026-04-28T03:18:14.464251Z","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-28T03:18:14.46436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.181:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.181:2380","--initial-cluster=instance=https://199.19.213.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.181: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-28T03:18:14.464455Z","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-28T03:18:14.464495Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-04-28T03:18:14.46452Z","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-28T03:18:14.465676Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"]} {"level":"info","ts":"2026-04-28T03:18:14.465805Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.181: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-28T03:18:14.468519Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.421523ms"} {"level":"info","ts":"2026-04-28T03:18:14.480288Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1a532052a1358225","cluster-id":"b34324311008a1d3"} {"level":"info","ts":"2026-04-28T03:18:14.480396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T03:18:14.480435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 0"} {"level":"info","ts":"2026-04-28T03:18:14.480468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1a532052a1358225 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T03:18:14.480503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 1"} {"level":"info","ts":"2026-04-28T03:18:14.480554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"warn","ts":"2026-04-28T03:18:14.491073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T03:18:14.493736Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T03:18:14.496411Z","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-28T03:18:14.499067Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1a532052a1358225","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T03:18:14.49942Z","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-28T03:18:14.499549Z","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-28T03:18:14.499624Z","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-28T03:18:14.500001Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1a532052a1358225","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T03:18:14.500544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"info","ts":"2026-04-28T03:18:14.501276Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","added-peer-id":"1a532052a1358225","added-peer-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-04-28T03:18:14.503883Z","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-28T03:18:14.50411Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1a532052a1358225","initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T03:18:14.504139Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T03:18:14.504778Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-04-28T03:18:14.504805Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-04-28T03:18:14.581228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T03:18:14.581314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T03:18:14.581375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgPreVoteResp from 1a532052a1358225 at term 1"} {"level":"info","ts":"2026-04-28T03:18:14.581408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became candidate at term 2"} {"level":"info","ts":"2026-04-28T03:18:14.581426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgVoteResp from 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-04-28T03:18:14.581451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became leader at term 2"} {"level":"info","ts":"2026-04-28T03:18:14.581473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1a532052a1358225 elected leader 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-04-28T03:18:14.582894Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1a532052a1358225","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.181:2379]}","request-path":"/0/members/1a532052a1358225/attributes","cluster-id":"b34324311008a1d3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T03:18:14.583135Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:18:14.583204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T03:18:14.583439Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:18:14.587014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T03:18:14.587391Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T03:18:14.587436Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T03:18:14.587519Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:18:14.587635Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:18:14.58768Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T03:18:14.592776Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.181:2379"} {"level":"warn","ts":"2026-04-28T03:18:18.63659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.637675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:18:18.636751Z","caller":"traceutil/trace.go:171","msg":"trace[1435100171] linearizableReadLoop","detail":"{readStateIndex:167; appliedIndex:166; }","duration":"324.815245ms","start":"2026-04-28T03:18:18.311918Z","end":"2026-04-28T03:18:18.636733Z","steps":["trace[1435100171] 'read index received' (duration: 58.94µs)","trace[1435100171] 'applied index is now lower than readState.Index' (duration: 324.754955ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:18:18.63691Z","caller":"traceutil/trace.go:171","msg":"trace[870674627] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"767.86787ms","start":"2026-04-28T03:18:17.868982Z","end":"2026-04-28T03:18:18.63685Z","steps":["trace[870674627] 'process raft request' (duration: 442.197441ms)","trace[870674627] 'compare' (duration: 324.505656ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:18:18.637112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:17.868945Z","time spent":"768.086869ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":792,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:18:18.637156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.196292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:18.63739Z","caller":"traceutil/trace.go:171","msg":"trace[501750698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:162; }","duration":"325.43121ms","start":"2026-04-28T03:18:18.311909Z","end":"2026-04-28T03:18:18.637341Z","steps":["trace[501750698] 'agreement among raft nodes before linearized reading' (duration: 325.084202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:18.637496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:18.311846Z","time spent":"325.63357ms","remote":"127.0.0.1:49460","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-28T03:18:19.85943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.780585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:18:19.859596Z","caller":"traceutil/trace.go:171","msg":"trace[761650923] linearizableReadLoop","detail":"{readStateIndex:168; appliedIndex:167; }","duration":"1.222659156s","start":"2026-04-28T03:18:18.63691Z","end":"2026-04-28T03:18:19.85957Z","steps":["trace[761650923] 'read index received' (duration: 345.516493ms)","trace[761650923] 'applied index is now lower than readState.Index' (duration: 877.140913ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:18:19.85968Z","caller":"traceutil/trace.go:171","msg":"trace[1421697143] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"1.539445428s","start":"2026-04-28T03:18:18.320191Z","end":"2026-04-28T03:18:19.859637Z","steps":["trace[1421697143] 'process raft request' (duration: 662.351845ms)","trace[1421697143] 'compare' (duration: 876.545776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:18:19.859821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4984598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:18:19.859869Z","caller":"traceutil/trace.go:171","msg":"trace[233687071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:163; }","duration":"1.49855456s","start":"2026-04-28T03:18:18.361298Z","end":"2026-04-28T03:18:19.859853Z","steps":["trace[233687071] 'agreement among raft nodes before linearized reading' (duration: 1.498407691s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:19.859871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:18.320165Z","time spent":"1.539606657s","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:18:19.859922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:18.361279Z","time spent":"1.49862821s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:18:19.859943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221041487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:19.860035Z","caller":"traceutil/trace.go:171","msg":"trace[410246724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:163; }","duration":"1.221146386s","start":"2026-04-28T03:18:18.638862Z","end":"2026-04-28T03:18:19.860009Z","steps":["trace[410246724] 'agreement among raft nodes before linearized reading' (duration: 1.220980327s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:19.860091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:18.638838Z","time spent":"1.221240346s","remote":"127.0.0.1:49456","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-28T03:18:19.860113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.409403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:19.86015Z","caller":"traceutil/trace.go:171","msg":"trace[931979114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:163; }","duration":"501.487293ms","start":"2026-04-28T03:18:19.358652Z","end":"2026-04-28T03:18:19.860139Z","steps":["trace[931979114] 'agreement among raft nodes before linearized reading' (duration: 501.417203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:19.860178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:19.358622Z","time spent":"501.550112ms","remote":"127.0.0.1:49460","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-28T03:18:19.860393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50041169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T03:18:19.860367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22062557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:19.860462Z","caller":"traceutil/trace.go:171","msg":"trace[1196392033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:163; }","duration":"1.500509549s","start":"2026-04-28T03:18:18.359937Z","end":"2026-04-28T03:18:19.860447Z","steps":["trace[1196392033] 'agreement among raft nodes before linearized reading' (duration: 1.5003667s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:19.860497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:18.35989Z","time spent":"1.500599229s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T03:18:19.860494Z","caller":"traceutil/trace.go:171","msg":"trace[1853004088] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:0; response_revision:163; }","duration":"1.220792858s","start":"2026-04-28T03:18:18.63967Z","end":"2026-04-28T03:18:19.860462Z","steps":["trace[1853004088] 'agreement among raft nodes before linearized reading' (duration: 1.220577479s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:19.860559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:18.639658Z","time spent":"1.220886238s","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" "} {"level":"info","ts":"2026-04-28T03:18:55.547557Z","caller":"traceutil/trace.go:171","msg":"trace[961346556] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"154.385816ms","start":"2026-04-28T03:18:55.393151Z","end":"2026-04-28T03:18:55.547537Z","steps":["trace[961346556] 'process raft request' (duration: 147.756604ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:55.550805Z","caller":"traceutil/trace.go:171","msg":"trace[623187462] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"142.952325ms","start":"2026-04-28T03:18:55.407816Z","end":"2026-04-28T03:18:55.550768Z","steps":["trace[623187462] 'process raft request' (duration: 142.656896ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:55.551079Z","caller":"traceutil/trace.go:171","msg":"trace[273492063] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"127.862438ms","start":"2026-04-28T03:18:55.423205Z","end":"2026-04-28T03:18:55.551067Z","steps":["trace[273492063] 'process raft request' (duration: 127.449296ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:55.56374Z","caller":"traceutil/trace.go:171","msg":"trace[552988931] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:593; }","duration":"137.075015ms","start":"2026-04-28T03:18:55.426642Z","end":"2026-04-28T03:18:55.563717Z","steps":["trace[552988931] 'read index received' (duration: 114.181851ms)","trace[552988931] 'applied index is now lower than readState.Index' (duration: 22.892044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:18:55.564188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.514937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:18:55.564225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.515107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:55.564272Z","caller":"traceutil/trace.go:171","msg":"trace[1832853063] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:570; }","duration":"137.61511ms","start":"2026-04-28T03:18:55.426639Z","end":"2026-04-28T03:18:55.564254Z","steps":["trace[1832853063] 'agreement among raft nodes before linearized reading' (duration: 137.446525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:55.564634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.23775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:55.564672Z","caller":"traceutil/trace.go:171","msg":"trace[70292618] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:570; }","duration":"107.289602ms","start":"2026-04-28T03:18:55.457361Z","end":"2026-04-28T03:18:55.56465Z","steps":["trace[70292618] 'agreement among raft nodes before linearized reading' (duration: 107.259631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:55.564751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.409234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:55.564768Z","caller":"traceutil/trace.go:171","msg":"trace[1164349749] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:570; }","duration":"107.437396ms","start":"2026-04-28T03:18:55.457327Z","end":"2026-04-28T03:18:55.564764Z","steps":["trace[1164349749] 'agreement among raft nodes before linearized reading' (duration: 107.411375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:55.564869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.557029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:55.564885Z","caller":"traceutil/trace.go:171","msg":"trace[904148271] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:570; }","duration":"107.631201ms","start":"2026-04-28T03:18:55.45725Z","end":"2026-04-28T03:18:55.564882Z","steps":["trace[904148271] 'agreement among raft nodes before linearized reading' (duration: 107.607621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:55.564944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.035059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:55.564959Z","caller":"traceutil/trace.go:171","msg":"trace[487752804] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:570; }","duration":"121.08058ms","start":"2026-04-28T03:18:55.443874Z","end":"2026-04-28T03:18:55.564955Z","steps":["trace[487752804] 'agreement among raft nodes before linearized reading' (duration: 121.05538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:55.565048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.205714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:55.565065Z","caller":"traceutil/trace.go:171","msg":"trace[534793162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:570; }","duration":"121.246316ms","start":"2026-04-28T03:18:55.443815Z","end":"2026-04-28T03:18:55.565061Z","steps":["trace[534793162] 'agreement among raft nodes before linearized reading' (duration: 121.220845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:55.565127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.078963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:55.565142Z","caller":"traceutil/trace.go:171","msg":"trace[493095723] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:570; }","duration":"138.105035ms","start":"2026-04-28T03:18:55.427033Z","end":"2026-04-28T03:18:55.565138Z","steps":["trace[493095723] 'agreement among raft nodes before linearized reading' (duration: 138.081544ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:55.564281Z","caller":"traceutil/trace.go:171","msg":"trace[392771302] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:570; }","duration":"137.59727ms","start":"2026-04-28T03:18:55.42667Z","end":"2026-04-28T03:18:55.564267Z","steps":["trace[392771302] 'agreement among raft nodes before linearized reading' (duration: 137.485006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:56.211825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378075324743603421,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T03:18:56.333478Z","caller":"traceutil/trace.go:171","msg":"trace[19350295] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"631.117596ms","start":"2026-04-28T03:18:55.702338Z","end":"2026-04-28T03:18:56.333456Z","steps":["trace[19350295] 'process raft request' (duration: 630.996202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:56.333933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:55.702312Z","time spent":"631.23653ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:18:56.346167Z","caller":"traceutil/trace.go:171","msg":"trace[1654010486] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:608; }","duration":"635.221513ms","start":"2026-04-28T03:18:55.710919Z","end":"2026-04-28T03:18:56.34614Z","steps":["trace[1654010486] 'read index received' (duration: 622.837676ms)","trace[1654010486] 'applied index is now lower than readState.Index' (duration: 12.382477ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:18:56.346302Z","caller":"traceutil/trace.go:171","msg":"trace[1283640944] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"543.209776ms","start":"2026-04-28T03:18:55.803063Z","end":"2026-04-28T03:18:56.346272Z","steps":["trace[1283640944] 'process raft request' (duration: 542.960519ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:56.346411Z","caller":"traceutil/trace.go:171","msg":"trace[1472840543] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"142.470119ms","start":"2026-04-28T03:18:56.203927Z","end":"2026-04-28T03:18:56.346397Z","steps":["trace[1472840543] 'process raft request' (duration: 142.1753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:56.34648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.531523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:18:56.346478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.918527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:18:56.346545Z","caller":"traceutil/trace.go:171","msg":"trace[850960551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"635.640226ms","start":"2026-04-28T03:18:55.710886Z","end":"2026-04-28T03:18:56.346526Z","steps":["trace[850960551] 'agreement among raft nodes before linearized reading' (duration: 635.491201ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:56.346571Z","caller":"traceutil/trace.go:171","msg":"trace[1349081140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"374.045051ms","start":"2026-04-28T03:18:55.972514Z","end":"2026-04-28T03:18:56.346559Z","steps":["trace[1349081140] 'agreement among raft nodes before linearized reading' (duration: 373.939908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:56.34656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.2228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-28T03:18:56.346595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:55.972495Z","time spent":"374.093283ms","remote":"127.0.0.1:49456","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-28T03:18:56.34659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:55.710873Z","time spent":"635.705338ms","remote":"127.0.0.1:49460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:18:56.346605Z","caller":"traceutil/trace.go:171","msg":"trace[616928457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"273.300553ms","start":"2026-04-28T03:18:56.073291Z","end":"2026-04-28T03:18:56.346592Z","steps":["trace[616928457] 'agreement among raft nodes before linearized reading' (duration: 273.185369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:56.346659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.450826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T03:18:56.346723Z","caller":"traceutil/trace.go:171","msg":"trace[1489509770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"277.556789ms","start":"2026-04-28T03:18:56.069151Z","end":"2026-04-28T03:18:56.346708Z","steps":["trace[1489509770] 'agreement among raft nodes before linearized reading' (duration: 277.428375ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:18:56.346872Z","caller":"traceutil/trace.go:171","msg":"trace[297136499] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"618.87217ms","start":"2026-04-28T03:18:55.727978Z","end":"2026-04-28T03:18:56.34685Z","steps":["trace[297136499] 'process raft request' (duration: 617.884261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:56.346432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:55.803046Z","time spent":"543.331339ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:18:56.346966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:55.727951Z","time spent":"618.972633ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:18:56.346936Z","caller":"traceutil/trace.go:171","msg":"trace[446531659] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"344.274888ms","start":"2026-04-28T03:18:56.002025Z","end":"2026-04-28T03:18:56.3463Z","steps":["trace[446531659] 'process raft request' (duration: 344.042591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:18:56.347039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:18:56.001998Z","time spent":"345.002849ms","remote":"127.0.0.1:49486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:19:02.56415Z","caller":"traceutil/trace.go:171","msg":"trace[1855076891] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"167.647563ms","start":"2026-04-28T03:19:02.39648Z","end":"2026-04-28T03:19:02.564127Z","steps":["trace[1855076891] 'process raft request' (duration: 167.514438ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:02.621382Z","caller":"traceutil/trace.go:171","msg":"trace[670139281] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"224.484163ms","start":"2026-04-28T03:19:02.396875Z","end":"2026-04-28T03:19:02.621359Z","steps":["trace[670139281] 'process raft request' (duration: 212.325224ms)","trace[670139281] 'compare' (duration: 12.063116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:21.689889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.056042ms","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-28T03:19:21.690035Z","caller":"traceutil/trace.go:171","msg":"trace[674881293] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:774; }","duration":"874.208068ms","start":"2026-04-28T03:19:20.815803Z","end":"2026-04-28T03:19:21.690011Z","steps":["trace[674881293] 'range keys from in-memory index tree' (duration: 874.039831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:21.690709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.006905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:19:21.690802Z","caller":"traceutil/trace.go:171","msg":"trace[321712327] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"752.833826ms","start":"2026-04-28T03:19:20.937953Z","end":"2026-04-28T03:19:21.690787Z","steps":["trace[321712327] 'read index received' (duration: 269.644004ms)","trace[321712327] 'applied index is now lower than readState.Index' (duration: 483.188422ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:21.691048Z","caller":"traceutil/trace.go:171","msg":"trace[518380015] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"991.779886ms","start":"2026-04-28T03:19:20.699247Z","end":"2026-04-28T03:19:21.691027Z","steps":["trace[518380015] 'process raft request' (duration: 508.318582ms)","trace[518380015] 'compare' (duration: 482.573245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:21.691136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:20.699235Z","time spent":"991.85468ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:19:21.691502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.644327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6fgfm\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-28T03:19:21.691549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.257337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:19:21.691582Z","caller":"traceutil/trace.go:171","msg":"trace[1188151003] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6fgfm; range_end:; response_count:1; response_revision:775; }","duration":"560.767331ms","start":"2026-04-28T03:19:21.130794Z","end":"2026-04-28T03:19:21.691562Z","steps":["trace[1188151003] 'agreement among raft nodes before linearized reading' (duration: 560.553472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:21.691568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.071737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:19:21.691599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.640682ms","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-28T03:19:21.691599Z","caller":"traceutil/trace.go:171","msg":"trace[1695441051] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:775; }","duration":"617.342471ms","start":"2026-04-28T03:19:21.074243Z","end":"2026-04-28T03:19:21.691586Z","steps":["trace[1695441051] 'agreement among raft nodes before linearized reading' (duration: 617.234626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:21.691634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:21.130776Z","time spent":"560.846655ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6fgfm\" "} {"level":"info","ts":"2026-04-28T03:19:21.691638Z","caller":"traceutil/trace.go:171","msg":"trace[786309512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"753.704044ms","start":"2026-04-28T03:19:20.937923Z","end":"2026-04-28T03:19:21.691627Z","steps":["trace[786309512] 'agreement among raft nodes before linearized reading' (duration: 753.571429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:21.691646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:21.074224Z","time spent":"617.413124ms","remote":"127.0.0.1:49678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:19:21.691669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:20.937909Z","time spent":"753.754917ms","remote":"127.0.0.1:49704","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":"info","ts":"2026-04-28T03:19:21.691642Z","caller":"traceutil/trace.go:171","msg":"trace[1704175214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"720.184881ms","start":"2026-04-28T03:19:20.97144Z","end":"2026-04-28T03:19:21.691625Z","steps":["trace[1704175214] 'agreement among raft nodes before linearized reading' (duration: 720.064166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:21.691709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:20.971423Z","time spent":"720.278746ms","remote":"127.0.0.1:49460","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-28T03:19:21.691796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.751993ms","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-28T03:19:21.691904Z","caller":"traceutil/trace.go:171","msg":"trace[986732368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"495.90142ms","start":"2026-04-28T03:19:21.195989Z","end":"2026-04-28T03:19:21.69189Z","steps":["trace[986732368] 'agreement among raft nodes before linearized reading' (duration: 495.67855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:21.691952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:21.195964Z","time spent":"495.973023ms","remote":"127.0.0.1:49704","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-28T03:19:21.691987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.760014ms","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-28T03:19:21.692025Z","caller":"traceutil/trace.go:171","msg":"trace[165530761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"752.805756ms","start":"2026-04-28T03:19:20.939211Z","end":"2026-04-28T03:19:21.692017Z","steps":["trace[165530761] 'agreement among raft nodes before linearized reading' (duration: 752.107046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:21.69205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:20.939206Z","time spent":"752.832717ms","remote":"127.0.0.1:49704","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-28T03:19:21.797993Z","caller":"traceutil/trace.go:171","msg":"trace[1252475104] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"101.408923ms","start":"2026-04-28T03:19:21.696562Z","end":"2026-04-28T03:19:21.797971Z","steps":["trace[1252475104] 'process raft request' (duration: 101.11289ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:21.79799Z","caller":"traceutil/trace.go:171","msg":"trace[840790868] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"101.291958ms","start":"2026-04-28T03:19:21.69667Z","end":"2026-04-28T03:19:21.797962Z","steps":["trace[840790868] 'process raft request' (duration: 101.164532ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:49.979013Z","caller":"traceutil/trace.go:171","msg":"trace[230689959] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"127.251465ms","start":"2026-04-28T03:19:49.851726Z","end":"2026-04-28T03:19:49.978977Z","steps":["trace[230689959] 'process raft request' (duration: 127.087076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:50.694837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.653674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:19:50.695069Z","caller":"traceutil/trace.go:171","msg":"trace[1743228102] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1212; }","duration":"476.940612ms","start":"2026-04-28T03:19:50.218115Z","end":"2026-04-28T03:19:50.695056Z","steps":["trace[1743228102] 'read index received' (duration: 279.009023ms)","trace[1743228102] 'applied index is now lower than readState.Index' (duration: 197.930469ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:19:50.69507Z","caller":"traceutil/trace.go:171","msg":"trace[843731021] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"671.882138ms","start":"2026-04-28T03:19:50.023169Z","end":"2026-04-28T03:19:50.695052Z","steps":["trace[843731021] 'process raft request' (duration: 473.929017ms)","trace[843731021] 'compare' (duration: 197.20092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:19:50.695195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:50.02315Z","time spent":"671.990433ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:19:50.695215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.10414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T03:19:50.695245Z","caller":"traceutil/trace.go:171","msg":"trace[2064344001] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:1174; }","duration":"477.167954ms","start":"2026-04-28T03:19:50.218067Z","end":"2026-04-28T03:19:50.695235Z","steps":["trace[2064344001] 'agreement among raft nodes before linearized reading' (duration: 477.040067ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:19:50.695176Z","caller":"traceutil/trace.go:171","msg":"trace[629205091] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"671.702088ms","start":"2026-04-28T03:19:50.023446Z","end":"2026-04-28T03:19:50.695149Z","steps":["trace[629205091] 'process raft request' (duration: 671.493146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:50.695279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:50.21805Z","time spent":"477.222927ms","remote":"127.0.0.1:49472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.181\" "} {"level":"info","ts":"2026-04-28T03:19:50.69523Z","caller":"traceutil/trace.go:171","msg":"trace[1239200951] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"382.779805ms","start":"2026-04-28T03:19:50.312421Z","end":"2026-04-28T03:19:50.6952Z","steps":["trace[1239200951] 'process raft request' (duration: 382.600315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:19:50.695399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:50.023427Z","time spent":"671.906099ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:19:50.695531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:19:50.312397Z","time spent":"383.022288ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:20:34.338285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.388132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T03:20:34.338378Z","caller":"traceutil/trace.go:171","msg":"trace[1583974793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1823; }","duration":"101.533442ms","start":"2026-04-28T03:20:34.236825Z","end":"2026-04-28T03:20:34.338358Z","steps":["trace[1583974793] 'range keys from in-memory index tree' (duration: 101.214701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:34.338426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.121116ms","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-28T03:20:34.338512Z","caller":"traceutil/trace.go:171","msg":"trace[636768207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1823; }","duration":"117.241244ms","start":"2026-04-28T03:20:34.22125Z","end":"2026-04-28T03:20:34.338491Z","steps":["trace[636768207] 'range keys from in-memory index tree' (duration: 116.766793ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:34.587144Z","caller":"traceutil/trace.go:171","msg":"trace[2089982514] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"110.722288ms","start":"2026-04-28T03:20:34.476402Z","end":"2026-04-28T03:20:34.587124Z","steps":["trace[2089982514] 'process raft request' (duration: 110.532995ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:34.890979Z","caller":"traceutil/trace.go:171","msg":"trace[2033146105] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"146.713116ms","start":"2026-04-28T03:20:34.74423Z","end":"2026-04-28T03:20:34.890943Z","steps":["trace[2033146105] 'process raft request' (duration: 89.033803ms)","trace[2033146105] 'compare' (duration: 57.48292ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:20:34.891097Z","caller":"traceutil/trace.go:171","msg":"trace[1492780496] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"117.34216ms","start":"2026-04-28T03:20:34.773703Z","end":"2026-04-28T03:20:34.891045Z","steps":["trace[1492780496] 'read index received' (duration: 59.477916ms)","trace[1492780496] 'applied index is now lower than readState.Index' (duration: 57.861324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:20:34.891232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.503751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:20:34.891362Z","caller":"traceutil/trace.go:171","msg":"trace[1321072515] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1827; }","duration":"117.6504ms","start":"2026-04-28T03:20:34.773663Z","end":"2026-04-28T03:20:34.891313Z","steps":["trace[1321072515] 'agreement among raft nodes before linearized reading' (duration: 117.502821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:20:34.891754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.979231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:20:34.891832Z","caller":"traceutil/trace.go:171","msg":"trace[443276723] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1828; }","duration":"118.099349ms","start":"2026-04-28T03:20:34.773716Z","end":"2026-04-28T03:20:34.891815Z","steps":["trace[443276723] 'agreement among raft nodes before linearized reading' (duration: 117.898316ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:42.0146Z","caller":"traceutil/trace.go:171","msg":"trace[520675167] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"126.794557ms","start":"2026-04-28T03:20:41.887769Z","end":"2026-04-28T03:20:42.014564Z","steps":["trace[520675167] 'process raft request' (duration: 126.508219ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:20:47.599381Z","caller":"traceutil/trace.go:171","msg":"trace[1784729584] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"217.109231ms","start":"2026-04-28T03:20:47.382241Z","end":"2026-04-28T03:20:47.59935Z","steps":["trace[1784729584] 'process raft request' (duration: 216.804832ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:08.723084Z","caller":"traceutil/trace.go:171","msg":"trace[788980107] linearizableReadLoop","detail":"{readStateIndex:2155; appliedIndex:2154; }","duration":"158.136743ms","start":"2026-04-28T03:21:08.564913Z","end":"2026-04-28T03:21:08.72305Z","steps":["trace[788980107] 'read index received' (duration: 157.903587ms)","trace[788980107] 'applied index is now lower than readState.Index' (duration: 232.296µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:08.72322Z","caller":"traceutil/trace.go:171","msg":"trace[278707572] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"159.255349ms","start":"2026-04-28T03:21:08.563918Z","end":"2026-04-28T03:21:08.723174Z","steps":["trace[278707572] 'process raft request' (duration: 158.97005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:08.723298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.360829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:08.723419Z","caller":"traceutil/trace.go:171","msg":"trace[1096160595] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2067; }","duration":"158.504888ms","start":"2026-04-28T03:21:08.564892Z","end":"2026-04-28T03:21:08.723397Z","steps":["trace[1096160595] 'agreement among raft nodes before linearized reading' (duration: 158.309425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:08.723541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.490762ms","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-28T03:21:08.723638Z","caller":"traceutil/trace.go:171","msg":"trace[1405291676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2067; }","duration":"139.760889ms","start":"2026-04-28T03:21:08.583858Z","end":"2026-04-28T03:21:08.723619Z","steps":["trace[1405291676] 'agreement among raft nodes before linearized reading' (duration: 139.395615ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:08.839445Z","caller":"traceutil/trace.go:171","msg":"trace[1122653702] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2155; }","duration":"106.720253ms","start":"2026-04-28T03:21:08.732699Z","end":"2026-04-28T03:21:08.83942Z","steps":["trace[1122653702] 'read index received' (duration: 101.906887ms)","trace[1122653702] 'applied index is now lower than readState.Index' (duration: 4.812276ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:08.83965Z","caller":"traceutil/trace.go:171","msg":"trace[1652911064] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"111.793017ms","start":"2026-04-28T03:21:08.727767Z","end":"2026-04-28T03:21:08.83956Z","steps":["trace[1652911064] 'process raft request' (duration: 106.892995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:08.839847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.12014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-28T03:21:08.840752Z","caller":"traceutil/trace.go:171","msg":"trace[1620483541] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2068; }","duration":"107.317793ms","start":"2026-04-28T03:21:08.732695Z","end":"2026-04-28T03:21:08.840013Z","steps":["trace[1620483541] 'agreement among raft nodes before linearized reading' (duration: 106.816979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:09.261738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.177115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:21:09.262002Z","caller":"traceutil/trace.go:171","msg":"trace[1922583159] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2156; }","duration":"161.912583ms","start":"2026-04-28T03:21:09.100064Z","end":"2026-04-28T03:21:09.261977Z","steps":["trace[1922583159] 'read index received' (duration: 33.623µs)","trace[1922583159] 'applied index is now lower than readState.Index' (duration: 161.87813ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:09.262047Z","caller":"traceutil/trace.go:171","msg":"trace[166821347] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"267.563212ms","start":"2026-04-28T03:21:08.994468Z","end":"2026-04-28T03:21:09.262031Z","steps":["trace[166821347] 'process raft request' (duration: 267.444154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:09.262216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.150758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T03:21:09.262518Z","caller":"traceutil/trace.go:171","msg":"trace[1785339151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2070; }","duration":"162.478701ms","start":"2026-04-28T03:21:09.100017Z","end":"2026-04-28T03:21:09.262496Z","steps":["trace[1785339151] 'agreement among raft nodes before linearized reading' (duration: 162.076864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:09.262589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.401625ms","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-28T03:21:09.262667Z","caller":"traceutil/trace.go:171","msg":"trace[1388908544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2070; }","duration":"158.503301ms","start":"2026-04-28T03:21:09.104141Z","end":"2026-04-28T03:21:09.262645Z","steps":["trace[1388908544] 'agreement among raft nodes before linearized reading' (duration: 158.295407ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:09.262007Z","caller":"traceutil/trace.go:171","msg":"trace[1780144600] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"406.521006ms","start":"2026-04-28T03:21:08.855456Z","end":"2026-04-28T03:21:09.261977Z","steps":["trace[1780144600] 'process raft request' (duration: 210.038901ms)","trace[1780144600] 'compare' (duration: 196.047926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:09.263178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:08.855437Z","time spent":"407.655773ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:21:09.850685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.295991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:21:09.851027Z","caller":"traceutil/trace.go:171","msg":"trace[1227353652] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"332.382947ms","start":"2026-04-28T03:21:09.518625Z","end":"2026-04-28T03:21:09.851008Z","steps":["trace[1227353652] 'process raft request' (duration: 332.339314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:09.851464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:09.518601Z","time spent":"332.818606ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:21:09.851671Z","caller":"traceutil/trace.go:171","msg":"trace[445934716] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"583.207113ms","start":"2026-04-28T03:21:09.26845Z","end":"2026-04-28T03:21:09.851657Z","steps":["trace[445934716] 'process raft request' (duration: 321.792036ms)","trace[445934716] 'compare' (duration: 260.096167ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:09.851732Z","caller":"traceutil/trace.go:171","msg":"trace[1412682618] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"583.086245ms","start":"2026-04-28T03:21:09.268603Z","end":"2026-04-28T03:21:09.851689Z","steps":["trace[1412682618] 'process raft request' (duration: 582.26936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:09.851765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:09.268425Z","time spent":"583.30618ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:09.851885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:09.268592Z","time spent":"583.194303ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:21:09.851966Z","caller":"traceutil/trace.go:171","msg":"trace[52515518] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2159; }","duration":"560.71999ms","start":"2026-04-28T03:21:09.291205Z","end":"2026-04-28T03:21:09.851925Z","steps":["trace[52515518] 'read index received' (duration: 299.015454ms)","trace[52515518] 'applied index is now lower than readState.Index' (duration: 261.701116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:09.852142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.935484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-28T03:21:09.852194Z","caller":"traceutil/trace.go:171","msg":"trace[1936751365] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2074; }","duration":"561.023241ms","start":"2026-04-28T03:21:09.29116Z","end":"2026-04-28T03:21:09.852184Z","steps":["trace[1936751365] 'agreement among raft nodes before linearized reading' (duration: 560.86657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:09.85224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:09.291142Z","time spent":"561.091885ms","remote":"127.0.0.1:38658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-28T03:21:09.852235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.135778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T03:21:09.852315Z","caller":"traceutil/trace.go:171","msg":"trace[13216896] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2074; }","duration":"153.261037ms","start":"2026-04-28T03:21:09.699037Z","end":"2026-04-28T03:21:09.852298Z","steps":["trace[13216896] 'agreement among raft nodes before linearized reading' (duration: 153.072204ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:10.21692Z","caller":"traceutil/trace.go:171","msg":"trace[2035661006] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"360.067349ms","start":"2026-04-28T03:21:09.85682Z","end":"2026-04-28T03:21:10.216887Z","steps":["trace[2035661006] 'process raft request' (duration: 338.636914ms)","trace[2035661006] 'compare' (duration: 21.179128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:21:10.217204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:09.856789Z","time spent":"360.331607ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:21:10.219939Z","caller":"traceutil/trace.go:171","msg":"trace[2068705250] linearizableReadLoop","detail":"{readStateIndex:2164; appliedIndex:2162; }","duration":"247.240653ms","start":"2026-04-28T03:21:09.972675Z","end":"2026-04-28T03:21:10.219916Z","steps":["trace[2068705250] 'read index received' (duration: 222.795254ms)","trace[2068705250] 'applied index is now lower than readState.Index' (duration: 24.444559ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:21:10.219962Z","caller":"traceutil/trace.go:171","msg":"trace[1450447856] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"363.02883ms","start":"2026-04-28T03:21:09.856905Z","end":"2026-04-28T03:21:10.219934Z","steps":["trace[1450447856] 'process raft request' (duration: 362.842578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:10.220098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:21:09.856883Z","time spent":"363.140607ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:21:10.220134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.025057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:21:10.220116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.422296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:21:10.220197Z","caller":"traceutil/trace.go:171","msg":"trace[1089298182] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2077; }","duration":"142.166137ms","start":"2026-04-28T03:21:10.078013Z","end":"2026-04-28T03:21:10.220179Z","steps":["trace[1089298182] 'agreement among raft nodes before linearized reading' (duration: 142.035458ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:10.22022Z","caller":"traceutil/trace.go:171","msg":"trace[653337467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2077; }","duration":"247.570156ms","start":"2026-04-28T03:21:09.972623Z","end":"2026-04-28T03:21:10.220193Z","steps":["trace[653337467] 'agreement among raft nodes before linearized reading' (duration: 247.409645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:21:10.220697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.287996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:21:10.220733Z","caller":"traceutil/trace.go:171","msg":"trace[628936226] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2077; }","duration":"104.357291ms","start":"2026-04-28T03:21:10.116368Z","end":"2026-04-28T03:21:10.220725Z","steps":["trace[628936226] 'agreement among raft nodes before linearized reading' (duration: 103.7471ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:21:44.228482Z","caller":"traceutil/trace.go:171","msg":"trace[1449981626] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"123.753446ms","start":"2026-04-28T03:21:44.104693Z","end":"2026-04-28T03:21:44.228447Z","steps":["trace[1449981626] 'process raft request' (duration: 74.797228ms)","trace[1449981626] 'compare' (duration: 48.747604ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:09.788145Z","caller":"traceutil/trace.go:171","msg":"trace[512980863] linearizableReadLoop","detail":"{readStateIndex:2597; appliedIndex:2596; }","duration":"215.291011ms","start":"2026-04-28T03:22:09.572829Z","end":"2026-04-28T03:22:09.78812Z","steps":["trace[512980863] 'read index received' (duration: 215.192216ms)","trace[512980863] 'applied index is now lower than readState.Index' (duration: 97.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:09.788253Z","caller":"traceutil/trace.go:171","msg":"trace[635350508] transaction","detail":"{read_only:false; response_revision:2495; number_of_response:1; }","duration":"306.159525ms","start":"2026-04-28T03:22:09.482055Z","end":"2026-04-28T03:22:09.788215Z","steps":["trace[635350508] 'process raft request' (duration: 305.902646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:09.788434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:22:09.482031Z","time spent":"306.29617ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:22:09.788459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.602309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:22:09.788523Z","caller":"traceutil/trace.go:171","msg":"trace[1880013397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2495; }","duration":"215.760741ms","start":"2026-04-28T03:22:09.572743Z","end":"2026-04-28T03:22:09.788504Z","steps":["trace[1880013397] 'agreement among raft nodes before linearized reading' (duration: 215.54877ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:42.185422Z","caller":"traceutil/trace.go:171","msg":"trace[1780619944] linearizableReadLoop","detail":"{readStateIndex:2784; appliedIndex:2783; }","duration":"229.006083ms","start":"2026-04-28T03:22:41.956388Z","end":"2026-04-28T03:22:42.185394Z","steps":["trace[1780619944] 'read index received' (duration: 228.733478ms)","trace[1780619944] 'applied index is now lower than readState.Index' (duration: 271.685µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:42.185499Z","caller":"traceutil/trace.go:171","msg":"trace[990575669] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"252.165691ms","start":"2026-04-28T03:22:41.933287Z","end":"2026-04-28T03:22:42.185453Z","steps":["trace[990575669] 'process raft request' (duration: 251.963034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:42.185631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.223219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:22:42.185722Z","caller":"traceutil/trace.go:171","msg":"trace[1714038967] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2674; }","duration":"229.330707ms","start":"2026-04-28T03:22:41.95637Z","end":"2026-04-28T03:22:42.185701Z","steps":["trace[1714038967] 'agreement among raft nodes before linearized reading' (duration: 229.17338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:42.18589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.961217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:22:42.186003Z","caller":"traceutil/trace.go:171","msg":"trace[1320045885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2674; }","duration":"213.138223ms","start":"2026-04-28T03:22:41.972851Z","end":"2026-04-28T03:22:42.185989Z","steps":["trace[1320045885] 'agreement among raft nodes before linearized reading' (duration: 212.894368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:22:42.794193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.701716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:22:42.794476Z","caller":"traceutil/trace.go:171","msg":"trace[1098261223] linearizableReadLoop","detail":"{readStateIndex:2788; appliedIndex:2786; }","duration":"137.744771ms","start":"2026-04-28T03:22:42.656651Z","end":"2026-04-28T03:22:42.794396Z","steps":["trace[1098261223] 'read index received' (duration: 14.76711ms)","trace[1098261223] 'applied index is now lower than readState.Index' (duration: 122.976261ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:22:42.794729Z","caller":"traceutil/trace.go:171","msg":"trace[1276147677] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"248.334157ms","start":"2026-04-28T03:22:42.546375Z","end":"2026-04-28T03:22:42.79471Z","steps":["trace[1276147677] 'process raft request' (duration: 125.028903ms)","trace[1276147677] 'compare' (duration: 122.396172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:22:42.794807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.154134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18aa674385a79d86\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-28T03:22:42.795046Z","caller":"traceutil/trace.go:171","msg":"trace[1250448781] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18aa674385a79d86; range_end:; response_count:1; response_revision:2678; }","duration":"138.294501ms","start":"2026-04-28T03:22:42.656595Z","end":"2026-04-28T03:22:42.79489Z","steps":["trace[1250448781] 'agreement among raft nodes before linearized reading' (duration: 137.972557ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:22:42.794858Z","caller":"traceutil/trace.go:171","msg":"trace[623245923] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"187.613267ms","start":"2026-04-28T03:22:42.607226Z","end":"2026-04-28T03:22:42.794839Z","steps":["trace[623245923] 'process raft request' (duration: 187.073597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:23:28.581052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.228571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T03:23:28.581138Z","caller":"traceutil/trace.go:171","msg":"trace[1732800512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2917; }","duration":"138.409932ms","start":"2026-04-28T03:23:28.442711Z","end":"2026-04-28T03:23:28.581121Z","steps":["trace[1732800512] 'range keys from in-memory index tree' (duration: 138.028209ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:13.593849Z","caller":"traceutil/trace.go:171","msg":"trace[1103242880] transaction","detail":"{read_only:false; response_revision:3552; number_of_response:1; }","duration":"105.303954ms","start":"2026-04-28T03:25:13.488511Z","end":"2026-04-28T03:25:13.593815Z","steps":["trace[1103242880] 'process raft request' (duration: 105.154429ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:25:44.147458Z","caller":"traceutil/trace.go:171","msg":"trace[147259662] transaction","detail":"{read_only:false; response_revision:3723; number_of_response:1; }","duration":"101.097089ms","start":"2026-04-28T03:25:44.046332Z","end":"2026-04-28T03:25:44.147429Z","steps":["trace[147259662] 'process raft request' (duration: 40.131683ms)","trace[147259662] 'compare' (duration: 60.860012ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:25:44.26616Z","caller":"traceutil/trace.go:171","msg":"trace[1408205568] transaction","detail":"{read_only:false; response_revision:3724; number_of_response:1; }","duration":"107.197729ms","start":"2026-04-28T03:25:44.158936Z","end":"2026-04-28T03:25:44.266134Z","steps":["trace[1408205568] 'process raft request' (duration: 105.75984ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:30.227819Z","caller":"traceutil/trace.go:171","msg":"trace[384832284] transaction","detail":"{read_only:false; response_revision:4054; number_of_response:1; }","duration":"122.311978ms","start":"2026-04-28T03:26:30.105483Z","end":"2026-04-28T03:26:30.227795Z","steps":["trace[384832284] 'process raft request' (duration: 41.34573ms)","trace[384832284] 'compare' (duration: 80.653913ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:40.431255Z","caller":"traceutil/trace.go:171","msg":"trace[1584112981] linearizableReadLoop","detail":"{readStateIndex:4713; appliedIndex:4712; }","duration":"189.727844ms","start":"2026-04-28T03:26:40.241509Z","end":"2026-04-28T03:26:40.431237Z","steps":["trace[1584112981] 'read index received' (duration: 189.570256ms)","trace[1584112981] 'applied index is now lower than readState.Index' (duration: 156.738µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:40.431575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.210125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T03:26:40.431573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.060389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T03:26:40.431607Z","caller":"traceutil/trace.go:171","msg":"trace[1484980812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4530; }","duration":"165.292359ms","start":"2026-04-28T03:26:40.266305Z","end":"2026-04-28T03:26:40.431597Z","steps":["trace[1484980812] 'agreement among raft nodes before linearized reading' (duration: 165.177983ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:40.431646Z","caller":"traceutil/trace.go:171","msg":"trace[1930434233] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:4530; }","duration":"190.184945ms","start":"2026-04-28T03:26:40.241433Z","end":"2026-04-28T03:26:40.431618Z","steps":["trace[1930434233] 'agreement among raft nodes before linearized reading' (duration: 189.962974ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:40.431653Z","caller":"traceutil/trace.go:171","msg":"trace[165128261] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"237.977132ms","start":"2026-04-28T03:26:40.193654Z","end":"2026-04-28T03:26:40.431631Z","steps":["trace[165128261] 'process raft request' (duration: 237.452477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:40.431843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.636546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T03:26:40.431879Z","caller":"traceutil/trace.go:171","msg":"trace[126829123] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4530; }","duration":"143.699549ms","start":"2026-04-28T03:26:40.288163Z","end":"2026-04-28T03:26:40.431863Z","steps":["trace[126829123] 'agreement among raft nodes before linearized reading' (duration: 143.601455ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:43.588944Z","caller":"traceutil/trace.go:171","msg":"trace[1321144221] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"296.78834ms","start":"2026-04-28T03:26:43.292116Z","end":"2026-04-28T03:26:43.588904Z","steps":["trace[1321144221] 'process raft request' (duration: 270.579794ms)","trace[1321144221] 'compare' (duration: 25.581706ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:45.521631Z","caller":"traceutil/trace.go:171","msg":"trace[1139564132] linearizableReadLoop","detail":"{readStateIndex:4813; appliedIndex:4812; }","duration":"126.765532ms","start":"2026-04-28T03:26:45.394845Z","end":"2026-04-28T03:26:45.521611Z","steps":["trace[1139564132] 'read index received' (duration: 89.386203ms)","trace[1139564132] 'applied index is now lower than readState.Index' (duration: 37.378469ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:45.521675Z","caller":"traceutil/trace.go:171","msg":"trace[897011791] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"128.178899ms","start":"2026-04-28T03:26:45.39346Z","end":"2026-04-28T03:26:45.521639Z","steps":["trace[897011791] 'process raft request' (duration: 90.822221ms)","trace[897011791] 'compare' (duration: 37.225972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:45.52186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.994143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-28T03:26:45.521893Z","caller":"traceutil/trace.go:171","msg":"trace[1227771736] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4628; }","duration":"127.061176ms","start":"2026-04-28T03:26:45.394823Z","end":"2026-04-28T03:26:45.521884Z","steps":["trace[1227771736] 'agreement among raft nodes before linearized reading' (duration: 126.890298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:45.521918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.440049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-52pp8-tls\" ","response":"range_response_count:1 size:2130"} {"level":"info","ts":"2026-04-28T03:26:45.521984Z","caller":"traceutil/trace.go:171","msg":"trace[608624566] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-52pp8-tls; range_end:; response_count:1; response_revision:4628; }","duration":"124.535184ms","start":"2026-04-28T03:26:45.397435Z","end":"2026-04-28T03:26:45.52197Z","steps":["trace[608624566] 'agreement among raft nodes before linearized reading' (duration: 124.314844ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:45.707882Z","caller":"traceutil/trace.go:171","msg":"trace[1088956883] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"169.309899ms","start":"2026-04-28T03:26:45.538538Z","end":"2026-04-28T03:26:45.707848Z","steps":["trace[1088956883] 'process raft request' (duration: 168.756242ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:45.707911Z","caller":"traceutil/trace.go:171","msg":"trace[213079960] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"113.01336ms","start":"2026-04-28T03:26:45.59487Z","end":"2026-04-28T03:26:45.707883Z","steps":["trace[213079960] 'process raft request' (duration: 112.572908ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:45.740639Z","caller":"traceutil/trace.go:171","msg":"trace[2036774060] linearizableReadLoop","detail":"{readStateIndex:4817; appliedIndex:4816; }","duration":"120.377894ms","start":"2026-04-28T03:26:45.620231Z","end":"2026-04-28T03:26:45.740609Z","steps":["trace[2036774060] 'read index received' (duration: 87.752353ms)","trace[2036774060] 'applied index is now lower than readState.Index' (duration: 32.624001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:45.740885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.114692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T03:26:45.740925Z","caller":"traceutil/trace.go:171","msg":"trace[1184271896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4631; }","duration":"125.190046ms","start":"2026-04-28T03:26:45.615726Z","end":"2026-04-28T03:26:45.740916Z","steps":["trace[1184271896] 'agreement among raft nodes before linearized reading' (duration: 125.015778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:45.741052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.456477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:26:45.741131Z","caller":"traceutil/trace.go:171","msg":"trace[1717098874] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4631; }","duration":"142.615874ms","start":"2026-04-28T03:26:45.598497Z","end":"2026-04-28T03:26:45.741113Z","steps":["trace[1717098874] 'agreement among raft nodes before linearized reading' (duration: 142.418565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:45.741539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.631363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348486"} {"level":"info","ts":"2026-04-28T03:26:45.741602Z","caller":"traceutil/trace.go:171","msg":"trace[1434374100] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4631; }","duration":"127.734328ms","start":"2026-04-28T03:26:45.613854Z","end":"2026-04-28T03:26:45.741588Z","steps":["trace[1434374100] 'agreement among raft nodes before linearized reading' (duration: 126.911529ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:45.978904Z","caller":"traceutil/trace.go:171","msg":"trace[94956815] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"344.441178ms","start":"2026-04-28T03:26:45.63444Z","end":"2026-04-28T03:26:45.978881Z","steps":["trace[94956815] 'process raft request' (duration: 344.301931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:45.979037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:45.63442Z","time spent":"344.555553ms","remote":"127.0.0.1:56598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3387,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:26:46.021982Z","caller":"traceutil/trace.go:171","msg":"trace[1952884788] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"307.534743ms","start":"2026-04-28T03:26:45.714414Z","end":"2026-04-28T03:26:46.021949Z","steps":["trace[1952884788] 'process raft request' (duration: 307.25581ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:46.022023Z","caller":"traceutil/trace.go:171","msg":"trace[841985334] linearizableReadLoop","detail":"{readStateIndex:4819; appliedIndex:4817; }","duration":"281.28842ms","start":"2026-04-28T03:26:45.740711Z","end":"2026-04-28T03:26:46.022Z","steps":["trace[841985334] 'read index received' (duration: 238.079428ms)","trace[841985334] 'applied index is now lower than readState.Index' (duration: 43.208182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:46.022125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:45.714393Z","time spent":"307.67036ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:26:46.022285Z","caller":"traceutil/trace.go:171","msg":"trace[369094836] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"275.245219ms","start":"2026-04-28T03:26:45.747012Z","end":"2026-04-28T03:26:46.022258Z","steps":["trace[369094836] 'process raft request' (duration: 274.820989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:46.022172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.160037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:26:46.022471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.285626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:46.022575Z","caller":"traceutil/trace.go:171","msg":"trace[1375806415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4635; }","duration":"310.413302ms","start":"2026-04-28T03:26:45.712127Z","end":"2026-04-28T03:26:46.022541Z","steps":["trace[1375806415] 'agreement among raft nodes before linearized reading' (duration: 310.221023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:46.022617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:45.712078Z","time spent":"310.531357ms","remote":"127.0.0.1:49460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T03:26:46.022536Z","caller":"traceutil/trace.go:171","msg":"trace[1907727726] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:4635; }","duration":"285.426059ms","start":"2026-04-28T03:26:45.736947Z","end":"2026-04-28T03:26:46.022373Z","steps":["trace[1907727726] 'agreement among raft nodes before linearized reading' (duration: 285.171606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:46.022701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.706983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-28T03:26:46.022826Z","caller":"traceutil/trace.go:171","msg":"trace[1224106425] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4635; }","duration":"397.945415ms","start":"2026-04-28T03:26:45.624847Z","end":"2026-04-28T03:26:46.022793Z","steps":["trace[1224106425] 'agreement among raft nodes before linearized reading' (duration: 397.482783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:46.023075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:45.624826Z","time spent":"398.227649ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1154,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-04-28T03:26:46.023531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.153218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-28T03:26:46.023617Z","caller":"traceutil/trace.go:171","msg":"trace[1854114236] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4635; }","duration":"391.298355ms","start":"2026-04-28T03:26:45.632301Z","end":"2026-04-28T03:26:46.023599Z","steps":["trace[1854114236] 'agreement among raft nodes before linearized reading' (duration: 390.000302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:46.023677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:45.632277Z","time spent":"391.390729ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-28T03:26:46.023569Z","caller":"traceutil/trace.go:171","msg":"trace[1213317883] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"162.610997ms","start":"2026-04-28T03:26:45.860895Z","end":"2026-04-28T03:26:46.023506Z","steps":["trace[1213317883] 'process raft request' (duration: 161.00127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:46.035978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.2532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-28T03:26:46.036042Z","caller":"traceutil/trace.go:171","msg":"trace[487650035] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4643; }","duration":"259.352425ms","start":"2026-04-28T03:26:45.776678Z","end":"2026-04-28T03:26:46.036031Z","steps":["trace[487650035] 'agreement among raft nodes before linearized reading' (duration: 259.181217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:46.035994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.233319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11455"} {"level":"warn","ts":"2026-04-28T03:26:46.036134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.440882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-04-28T03:26:46.03614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.202929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T03:26:46.036195Z","caller":"traceutil/trace.go:171","msg":"trace[125908913] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4643; }","duration":"279.550928ms","start":"2026-04-28T03:26:45.756628Z","end":"2026-04-28T03:26:46.036179Z","steps":["trace[125908913] 'agreement among raft nodes before linearized reading' (duration: 279.381059ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:46.036201Z","caller":"traceutil/trace.go:171","msg":"trace[2026932471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4643; }","duration":"141.296713ms","start":"2026-04-28T03:26:45.89489Z","end":"2026-04-28T03:26:46.036187Z","steps":["trace[2026932471] 'agreement among raft nodes before linearized reading' (duration: 141.158636ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:46.036157Z","caller":"traceutil/trace.go:171","msg":"trace[2146944787] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4643; }","duration":"286.441169ms","start":"2026-04-28T03:26:45.749706Z","end":"2026-04-28T03:26:46.036148Z","steps":["trace[2146944787] 'agreement among raft nodes before linearized reading' (duration: 286.188517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.273297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.325757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-28T03:26:47.27335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.066185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:26:47.273406Z","caller":"traceutil/trace.go:171","msg":"trace[1794122616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4670; }","duration":"300.134568ms","start":"2026-04-28T03:26:46.973254Z","end":"2026-04-28T03:26:47.273389Z","steps":["trace[1794122616] 'range keys from in-memory index tree' (duration: 300.035973ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:47.273404Z","caller":"traceutil/trace.go:171","msg":"trace[2120963246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4670; }","duration":"300.442963ms","start":"2026-04-28T03:26:46.972939Z","end":"2026-04-28T03:26:47.273382Z","steps":["trace[2120963246] 'range keys from in-memory index tree' (duration: 300.204111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:47.273449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:46.973249Z","time spent":"300.185801ms","remote":"127.0.0.1:49460","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-28T03:26:47.273453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:46.972899Z","time spent":"300.541738ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T03:26:53.055498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.093736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:26:53.056224Z","caller":"traceutil/trace.go:171","msg":"trace[567808949] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"794.929283ms","start":"2026-04-28T03:26:52.261269Z","end":"2026-04-28T03:26:53.056198Z","steps":["trace[567808949] 'process raft request' (duration: 235.847929ms)","trace[567808949] 'compare' (duration: 557.775341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:53.057076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:52.261248Z","time spent":"795.06687ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:26:53.092628Z","caller":"traceutil/trace.go:171","msg":"trace[1064533461] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4947; }","duration":"654.059427ms","start":"2026-04-28T03:26:52.438543Z","end":"2026-04-28T03:26:53.092602Z","steps":["trace[1064533461] 'read index received' (duration: 58.583876ms)","trace[1064533461] 'applied index is now lower than readState.Index' (duration: 595.474361ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:53.092906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.347801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e45c7506-3973-44eb-8822-b0cae9923ba0\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-28T03:26:53.092967Z","caller":"traceutil/trace.go:171","msg":"trace[1321214490] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e45c7506-3973-44eb-8822-b0cae9923ba0; range_end:; response_count:1; response_revision:4760; }","duration":"654.459707ms","start":"2026-04-28T03:26:52.438495Z","end":"2026-04-28T03:26:53.092955Z","steps":["trace[1321214490] 'agreement among raft nodes before linearized reading' (duration: 654.233046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:53.092997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:52.438477Z","time spent":"654.5141ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e45c7506-3973-44eb-8822-b0cae9923ba0\" "} {"level":"info","ts":"2026-04-28T03:26:53.092925Z","caller":"traceutil/trace.go:171","msg":"trace[1206824755] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"822.989954ms","start":"2026-04-28T03:26:52.269901Z","end":"2026-04-28T03:26:53.092891Z","steps":["trace[1206824755] 'process raft request' (duration: 822.561203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:53.093246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:52.269888Z","time spent":"823.294539ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:26:53.293175Z","caller":"traceutil/trace.go:171","msg":"trace[332631809] linearizableReadLoop","detail":"{readStateIndex:4950; appliedIndex:4949; }","duration":"200.460172ms","start":"2026-04-28T03:26:53.092696Z","end":"2026-04-28T03:26:53.293156Z","steps":["trace[332631809] 'read index received' (duration: 175.720503ms)","trace[332631809] 'applied index is now lower than readState.Index' (duration: 24.738599ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:53.293301Z","caller":"traceutil/trace.go:171","msg":"trace[664061104] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"677.55206ms","start":"2026-04-28T03:26:52.615734Z","end":"2026-04-28T03:26:53.293287Z","steps":["trace[664061104] 'process raft request' (duration: 652.590451ms)","trace[664061104] 'compare' (duration: 24.739608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:53.293383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:52.615712Z","time spent":"677.615723ms","remote":"127.0.0.1:49704","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-28T03:26:53.293377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.370728ms","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-28T03:26:53.293447Z","caller":"traceutil/trace.go:171","msg":"trace[685989031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4761; }","duration":"704.456182ms","start":"2026-04-28T03:26:52.588975Z","end":"2026-04-28T03:26:53.293431Z","steps":["trace[685989031] 'agreement among raft nodes before linearized reading' (duration: 704.227981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:53.29348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:52.588914Z","time spent":"704.560227ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:26:53.293572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.728883ms","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-28T03:26:53.293642Z","caller":"traceutil/trace.go:171","msg":"trace[1848426521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4761; }","duration":"738.826358ms","start":"2026-04-28T03:26:52.554798Z","end":"2026-04-28T03:26:53.293624Z","steps":["trace[1848426521] 'agreement among raft nodes before linearized reading' (duration: 738.638978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:53.293669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.299964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T03:26:53.293707Z","caller":"traceutil/trace.go:171","msg":"trace[1836933220] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4761; }","duration":"737.349615ms","start":"2026-04-28T03:26:52.556349Z","end":"2026-04-28T03:26:53.293698Z","steps":["trace[1836933220] 'agreement among raft nodes before linearized reading' (duration: 736.922274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:53.293707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:52.554783Z","time spent":"738.911332ms","remote":"127.0.0.1:49704","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-28T03:26:53.293727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:52.556344Z","time spent":"737.378777ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T03:26:53.293792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.194877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-28T03:26:53.293811Z","caller":"traceutil/trace.go:171","msg":"trace[1338689799] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4761; }","duration":"714.239609ms","start":"2026-04-28T03:26:52.579566Z","end":"2026-04-28T03:26:53.293806Z","steps":["trace[1338689799] 'agreement among raft nodes before linearized reading' (duration: 713.6646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:53.293827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:52.579551Z","time spent":"714.2727ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T03:26:53.329321Z","caller":"traceutil/trace.go:171","msg":"trace[1425282081] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"229.990134ms","start":"2026-04-28T03:26:53.099301Z","end":"2026-04-28T03:26:53.329291Z","steps":["trace[1425282081] 'process raft request' (duration: 229.866928ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.329502Z","caller":"traceutil/trace.go:171","msg":"trace[95750230] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"230.437766ms","start":"2026-04-28T03:26:53.09904Z","end":"2026-04-28T03:26:53.329478Z","steps":["trace[95750230] 'process raft request' (duration: 229.942072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:53.329898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.950628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e45c7506-3973-44eb-8822-b0cae9923ba0\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-28T03:26:53.329966Z","caller":"traceutil/trace.go:171","msg":"trace[526067158] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e45c7506-3973-44eb-8822-b0cae9923ba0; range_end:; response_count:1; response_revision:4763; }","duration":"233.071484ms","start":"2026-04-28T03:26:53.096876Z","end":"2026-04-28T03:26:53.329948Z","steps":["trace[526067158] 'agreement among raft nodes before linearized reading' (duration: 232.847643ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.434994Z","caller":"traceutil/trace.go:171","msg":"trace[382098473] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"137.560159ms","start":"2026-04-28T03:26:53.297402Z","end":"2026-04-28T03:26:53.434962Z","steps":["trace[382098473] 'process raft request' (duration: 96.903613ms)","trace[382098473] 'compare' (duration: 40.402524ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:53.435091Z","caller":"traceutil/trace.go:171","msg":"trace[1201150086] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4952; }","duration":"105.812758ms","start":"2026-04-28T03:26:53.329249Z","end":"2026-04-28T03:26:53.435062Z","steps":["trace[1201150086] 'read index received' (duration: 65.080529ms)","trace[1201150086] 'applied index is now lower than readState.Index' (duration: 40.730939ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:53.435244Z","caller":"traceutil/trace.go:171","msg":"trace[1426059650] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"136.723088ms","start":"2026-04-28T03:26:53.298505Z","end":"2026-04-28T03:26:53.435228Z","steps":["trace[1426059650] 'process raft request' (duration: 136.444105ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.435372Z","caller":"traceutil/trace.go:171","msg":"trace[1618778386] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"137.638933ms","start":"2026-04-28T03:26:53.297712Z","end":"2026-04-28T03:26:53.435351Z","steps":["trace[1618778386] 'process raft request' (duration: 137.16854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:53.43566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.315053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T03:26:53.4362Z","caller":"traceutil/trace.go:171","msg":"trace[164493659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4767; }","duration":"132.887871ms","start":"2026-04-28T03:26:53.303299Z","end":"2026-04-28T03:26:53.436187Z","steps":["trace[164493659] 'agreement among raft nodes before linearized reading' (duration: 132.24807ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.43525Z","caller":"traceutil/trace.go:171","msg":"trace[1629401115] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"136.649494ms","start":"2026-04-28T03:26:53.298584Z","end":"2026-04-28T03:26:53.435233Z","steps":["trace[1629401115] 'process raft request' (duration: 136.423533ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.473359Z","caller":"traceutil/trace.go:171","msg":"trace[1124123720] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"136.688787ms","start":"2026-04-28T03:26:53.336651Z","end":"2026-04-28T03:26:53.47334Z","steps":["trace[1124123720] 'process raft request' (duration: 136.516508ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.473359Z","caller":"traceutil/trace.go:171","msg":"trace[1900897522] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"140.66082ms","start":"2026-04-28T03:26:53.332672Z","end":"2026-04-28T03:26:53.473333Z","steps":["trace[1900897522] 'process raft request' (duration: 140.393037ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.596026Z","caller":"traceutil/trace.go:171","msg":"trace[561364233] linearizableReadLoop","detail":"{readStateIndex:4960; appliedIndex:4959; }","duration":"108.380024ms","start":"2026-04-28T03:26:53.487615Z","end":"2026-04-28T03:26:53.595995Z","steps":["trace[561364233] 'read index received' (duration: 62.598308ms)","trace[561364233] 'applied index is now lower than readState.Index' (duration: 45.780396ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:53.596069Z","caller":"traceutil/trace.go:171","msg":"trace[1781583335] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"111.931877ms","start":"2026-04-28T03:26:53.484098Z","end":"2026-04-28T03:26:53.59603Z","steps":["trace[1781583335] 'process raft request' (duration: 66.156891ms)","trace[1781583335] 'compare' (duration: 45.609878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:53.596289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.649737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-28T03:26:53.596348Z","caller":"traceutil/trace.go:171","msg":"trace[433038901] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4771; }","duration":"108.744861ms","start":"2026-04-28T03:26:53.487588Z","end":"2026-04-28T03:26:53.596333Z","steps":["trace[433038901] 'agreement among raft nodes before linearized reading' (duration: 108.50946ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.856226Z","caller":"traceutil/trace.go:171","msg":"trace[427050350] linearizableReadLoop","detail":"{readStateIndex:4961; appliedIndex:4960; }","duration":"252.20792ms","start":"2026-04-28T03:26:53.603993Z","end":"2026-04-28T03:26:53.856201Z","steps":["trace[427050350] 'read index received' (duration: 198.386181ms)","trace[427050350] 'applied index is now lower than readState.Index' (duration: 53.820639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:53.856408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.398009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-04-28T03:26:53.856446Z","caller":"traceutil/trace.go:171","msg":"trace[1744184856] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4772; }","duration":"252.459031ms","start":"2026-04-28T03:26:53.603977Z","end":"2026-04-28T03:26:53.856436Z","steps":["trace[1744184856] 'agreement among raft nodes before linearized reading' (duration: 252.310964ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:53.856442Z","caller":"traceutil/trace.go:171","msg":"trace[1245526199] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"254.338113ms","start":"2026-04-28T03:26:53.602071Z","end":"2026-04-28T03:26:53.856409Z","steps":["trace[1245526199] 'process raft request' (duration: 200.319484ms)","trace[1245526199] 'compare' (duration: 53.706514ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:26:54.619906Z","caller":"traceutil/trace.go:171","msg":"trace[1439286056] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"328.393569ms","start":"2026-04-28T03:26:54.291488Z","end":"2026-04-28T03:26:54.619881Z","steps":["trace[1439286056] 'process raft request' (duration: 230.824934ms)","trace[1439286056] 'compare' (duration: 97.407297ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:26:54.62006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:54.291465Z","time spent":"328.537996ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T03:26:57.846043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.383477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T03:26:57.846106Z","caller":"traceutil/trace.go:171","msg":"trace[1242408614] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4823; }","duration":"395.474241ms","start":"2026-04-28T03:26:57.450619Z","end":"2026-04-28T03:26:57.846093Z","steps":["trace[1242408614] 'range keys from in-memory index tree' (duration: 395.25368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:57.846136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:57.45061Z","time spent":"395.519954ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T03:26:57.846315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.321973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-28T03:26:57.846379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.155273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T03:26:57.846437Z","caller":"traceutil/trace.go:171","msg":"trace[1309266104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4823; }","duration":"349.247278ms","start":"2026-04-28T03:26:57.497173Z","end":"2026-04-28T03:26:57.846421Z","steps":["trace[1309266104] 'range keys from in-memory index tree' (duration: 349.015097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:57.846444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.310892ms","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-28T03:26:57.846474Z","caller":"traceutil/trace.go:171","msg":"trace[690718295] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4823; }","duration":"262.380906ms","start":"2026-04-28T03:26:57.584086Z","end":"2026-04-28T03:26:57.846467Z","steps":["trace[690718295] 'count revisions from in-memory index tree' (duration: 262.243349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:57.846438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.686331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-04-28T03:26:57.846481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:57.497158Z","time spent":"349.312111ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T03:26:57.846488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.568525ms","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-28T03:26:57.846535Z","caller":"traceutil/trace.go:171","msg":"trace[24120899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4823; }","duration":"396.648059ms","start":"2026-04-28T03:26:57.449872Z","end":"2026-04-28T03:26:57.84652Z","steps":["trace[24120899] 'range keys from in-memory index tree' (duration: 396.448409ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:26:57.846538Z","caller":"traceutil/trace.go:171","msg":"trace[1590940767] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4823; }","duration":"395.790266ms","start":"2026-04-28T03:26:57.450716Z","end":"2026-04-28T03:26:57.846506Z","steps":["trace[1590940767] 'range keys from in-memory index tree' (duration: 395.494342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:57.846576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:57.449852Z","time spent":"396.713522ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:26:57.8466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:57.450706Z","time spent":"395.876271ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T03:26:57.846385Z","caller":"traceutil/trace.go:171","msg":"trace[31534819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4823; }","duration":"396.430019ms","start":"2026-04-28T03:26:57.449937Z","end":"2026-04-28T03:26:57.846367Z","steps":["trace[31534819] 'range keys from in-memory index tree' (duration: 396.182276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:26:57.84665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:26:57.449912Z","time spent":"396.731363ms","remote":"127.0.0.1:49704","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":"info","ts":"2026-04-28T03:27:01.61349Z","caller":"traceutil/trace.go:171","msg":"trace[1010641586] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"132.299421ms","start":"2026-04-28T03:27:01.481168Z","end":"2026-04-28T03:27:01.613468Z","steps":["trace[1010641586] 'process raft request' (duration: 132.139133ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:02.012159Z","caller":"traceutil/trace.go:171","msg":"trace[455004802] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"108.779657ms","start":"2026-04-28T03:27:01.903354Z","end":"2026-04-28T03:27:02.012134Z","steps":["trace[455004802] 'process raft request' (duration: 43.61095ms)","trace[455004802] 'compare' (duration: 65.050542ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:02.034569Z","caller":"traceutil/trace.go:171","msg":"trace[687930738] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"127.769359ms","start":"2026-04-28T03:27:01.906779Z","end":"2026-04-28T03:27:02.034549Z","steps":["trace[687930738] 'process raft request' (duration: 127.523577ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:02.034625Z","caller":"traceutil/trace.go:171","msg":"trace[1650514010] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"124.480996ms","start":"2026-04-28T03:27:01.910136Z","end":"2026-04-28T03:27:02.034617Z","steps":["trace[1650514010] 'process raft request' (duration: 124.34321ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:02.034559Z","caller":"traceutil/trace.go:171","msg":"trace[779663991] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"125.5665ms","start":"2026-04-28T03:27:01.908971Z","end":"2026-04-28T03:27:02.034538Z","steps":["trace[779663991] 'process raft request' (duration: 125.470706ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:02.034882Z","caller":"traceutil/trace.go:171","msg":"trace[417315217] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"125.96312ms","start":"2026-04-28T03:27:01.908906Z","end":"2026-04-28T03:27:02.03487Z","steps":["trace[417315217] 'process raft request' (duration: 125.495067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:07.272969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378075324743613800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:27:07.773408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378075324743613800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:27:07.863145Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.107428959s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T03:27:08.009682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.23342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:27:08.009786Z","caller":"traceutil/trace.go:171","msg":"trace[382221398] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"1.254151894s","start":"2026-04-28T03:27:06.755615Z","end":"2026-04-28T03:27:08.009767Z","steps":["trace[382221398] 'process raft request' (duration: 1.107760036s)","trace[382221398] 'compare' (duration: 145.763857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:08.009844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:06.755598Z","time spent":"1.254218277s","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11655,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:08.061376Z","caller":"traceutil/trace.go:171","msg":"trace[1768153307] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"1.154382573s","start":"2026-04-28T03:27:06.906982Z","end":"2026-04-28T03:27:08.061364Z","steps":["trace[1768153307] 'process raft request' (duration: 1.154222775s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.061496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:06.906955Z","time spent":"1.154473427s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:08.061606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289212692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:08.062541Z","caller":"traceutil/trace.go:171","msg":"trace[380353191] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4948; }","duration":"1.29017918s","start":"2026-04-28T03:27:06.772347Z","end":"2026-04-28T03:27:08.062526Z","steps":["trace[380353191] 'agreement among raft nodes before linearized reading' (duration: 1.289171189s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.062585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:06.77233Z","time spent":"1.290243753s","remote":"127.0.0.1:49892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-28T03:27:08.061338Z","caller":"traceutil/trace.go:171","msg":"trace[1496499972] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5141; }","duration":"1.288938469s","start":"2026-04-28T03:27:06.772383Z","end":"2026-04-28T03:27:08.061322Z","steps":["trace[1496499972] 'read index received' (duration: 1.09102128s)","trace[1496499972] 'applied index is now lower than readState.Index' (duration: 197.915329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:08.562835Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9378075324743613807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T03:27:08.971793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000372977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T03:27:08.97189Z","caller":"traceutil/trace.go:171","msg":"trace[1297771768] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000566847s","start":"2026-04-28T03:27:06.971304Z","end":"2026-04-28T03:27:08.97187Z","steps":["trace[1297771768] 'agreement among raft nodes before linearized reading' (duration: 2.000408629s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:08.971941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:06.971283Z","time spent":"2.000648611s","remote":"127.0.0.1:49460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 03:27:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T03:27:09.374119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.356985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:27:09.374507Z","caller":"traceutil/trace.go:171","msg":"trace[2000131071] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"422.780715ms","start":"2026-04-28T03:27:08.951709Z","end":"2026-04-28T03:27:09.37449Z","steps":["trace[2000131071] 'process raft request' (duration: 422.740883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.374576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.951624Z","time spent":"422.924603ms","remote":"127.0.0.1:49504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T03:27:09.374572Z","caller":"traceutil/trace.go:171","msg":"trace[447052689] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"1.357592997s","start":"2026-04-28T03:27:08.01695Z","end":"2026-04-28T03:27:09.374543Z","steps":["trace[447052689] 'process raft request' (duration: 925.694416ms)","trace[447052689] 'compare' (duration: 431.222817ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:09.374761Z","caller":"traceutil/trace.go:171","msg":"trace[1197822602] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"1.357530674s","start":"2026-04-28T03:27:08.017221Z","end":"2026-04-28T03:27:09.374751Z","steps":["trace[1197822602] 'process raft request' (duration: 1.356991527s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.374858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.017194Z","time spent":"1.357597048s","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.374773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.016935Z","time spent":"1.357733005s","remote":"127.0.0.1:49710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:09.375131Z","caller":"traceutil/trace.go:171","msg":"trace[585516411] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"1.355442949s","start":"2026-04-28T03:27:08.019678Z","end":"2026-04-28T03:27:09.37512Z","steps":["trace[585516411] 'process raft request' (duration: 1.354623538s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.375193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.019671Z","time spent":"1.355493542s","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:09.375252Z","caller":"traceutil/trace.go:171","msg":"trace[1321186312] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"1.071082903s","start":"2026-04-28T03:27:08.304156Z","end":"2026-04-28T03:27:09.375239Z","steps":["trace[1321186312] 'process raft request' (duration: 1.070264162s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.375327Z","caller":"traceutil/trace.go:171","msg":"trace[500255526] linearizableReadLoop","detail":"{readStateIndex:5149; appliedIndex:5143; }","duration":"1.31271133s","start":"2026-04-28T03:27:08.062606Z","end":"2026-04-28T03:27:09.375317Z","steps":["trace[500255526] 'read index received' (duration: 879.986437ms)","trace[500255526] 'applied index is now lower than readState.Index' (duration: 432.723813ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:09.375647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31950035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-28T03:27:09.375685Z","caller":"traceutil/trace.go:171","msg":"trace[1847684856] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4955; }","duration":"1.319571084s","start":"2026-04-28T03:27:08.056104Z","end":"2026-04-28T03:27:09.375675Z","steps":["trace[1847684856] 'agreement among raft nodes before linearized reading' (duration: 1.319443928s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.375715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.056053Z","time spent":"1.319655398s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T03:27:09.375884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358824529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-28T03:27:09.375918Z","caller":"traceutil/trace.go:171","msg":"trace[771542277] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4955; }","duration":"1.358871792s","start":"2026-04-28T03:27:08.017037Z","end":"2026-04-28T03:27:09.375908Z","steps":["trace[771542277] 'agreement among raft nodes before linearized reading' (duration: 1.358790148s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.375944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.017031Z","time spent":"1.358907143s","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-28T03:27:09.376943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603310702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:27:09.377238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455392058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-28T03:27:09.377215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.30414Z","time spent":"1.071183008s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:09.377287Z","caller":"traceutil/trace.go:171","msg":"trace[298381695] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4955; }","duration":"1.455506063s","start":"2026-04-28T03:27:07.921769Z","end":"2026-04-28T03:27:09.377276Z","steps":["trace[298381695] 'agreement among raft nodes before linearized reading' (duration: 1.454278842s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.377323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:07.92175Z","time spent":"1.455565296s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T03:27:09.377359Z","caller":"traceutil/trace.go:171","msg":"trace[1981838322] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"1.345226598s","start":"2026-04-28T03:27:08.032105Z","end":"2026-04-28T03:27:09.377332Z","steps":["trace[1981838322] 'process raft request' (duration: 1.342240248s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.377152Z","caller":"traceutil/trace.go:171","msg":"trace[1740092912] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4955; }","duration":"1.603563375s","start":"2026-04-28T03:27:07.773568Z","end":"2026-04-28T03:27:09.377132Z","steps":["trace[1740092912] 'agreement among raft nodes before linearized reading' (duration: 1.603319403s)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.377628Z","caller":"traceutil/trace.go:171","msg":"trace[78772929] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"1.232488804s","start":"2026-04-28T03:27:08.145124Z","end":"2026-04-28T03:27:09.377612Z","steps":["trace[78772929] 'process raft request' (duration: 1.229263812s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.37779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.145099Z","time spent":"1.23260131s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.377969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.87534219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-28T03:27:09.378025Z","caller":"traceutil/trace.go:171","msg":"trace[163766335] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4955; }","duration":"1.875431665s","start":"2026-04-28T03:27:07.502577Z","end":"2026-04-28T03:27:09.378009Z","steps":["trace[163766335] 'agreement among raft nodes before linearized reading' (duration: 1.875274007s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.378063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:07.502557Z","time spent":"1.875498289s","remote":"127.0.0.1:49600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-28T03:27:09.378278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700352503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:09.378323Z","caller":"traceutil/trace.go:171","msg":"trace[1111956362] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4955; }","duration":"1.700436347s","start":"2026-04-28T03:27:07.677875Z","end":"2026-04-28T03:27:09.378311Z","steps":["trace[1111956362] 'agreement among raft nodes before linearized reading' (duration: 1.700370174s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.378352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:07.677855Z","time spent":"1.70048881s","remote":"127.0.0.1:38504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:27:09.377648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:07.773545Z","time spent":"1.604083381s","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:27:09.378726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201013749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T03:27:09.378709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315523351s","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-28T03:27:09.378758Z","caller":"traceutil/trace.go:171","msg":"trace[475392924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4955; }","duration":"1.201068331s","start":"2026-04-28T03:27:08.17768Z","end":"2026-04-28T03:27:09.378748Z","steps":["trace[475392924] 'agreement among raft nodes before linearized reading' (duration: 1.200986017s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.378788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.177669Z","time spent":"1.201111733s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-28T03:27:09.378807Z","caller":"traceutil/trace.go:171","msg":"trace[181321028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4955; }","duration":"1.315638417s","start":"2026-04-28T03:27:08.06312Z","end":"2026-04-28T03:27:09.378759Z","steps":["trace[181321028] 'agreement among raft nodes before linearized reading' (duration: 1.315475659s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.378913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.06311Z","time spent":"1.315790765s","remote":"127.0.0.1:49704","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":"warn","ts":"2026-04-28T03:27:09.379122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.28646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:09.379163Z","caller":"traceutil/trace.go:171","msg":"trace[1730765939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4955; }","duration":"407.364354ms","start":"2026-04-28T03:27:08.971789Z","end":"2026-04-28T03:27:09.379153Z","steps":["trace[1730765939] 'agreement among raft nodes before linearized reading' (duration: 407.311811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.37919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.971773Z","time spent":"407.410666ms","remote":"127.0.0.1:49456","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-28T03:27:09.378567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.302879482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T03:27:09.380002Z","caller":"traceutil/trace.go:171","msg":"trace[241784467] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4955; }","duration":"2.304365426s","start":"2026-04-28T03:27:07.07562Z","end":"2026-04-28T03:27:09.379986Z","steps":["trace[241784467] 'agreement among raft nodes before linearized reading' (duration: 2.302896233s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.380718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:07.0756Z","time spent":"2.305097483s","remote":"127.0.0.1:49752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T03:27:09.380144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316465719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T03:27:09.383748Z","caller":"traceutil/trace.go:171","msg":"trace[864755567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4955; }","duration":"1.320073429s","start":"2026-04-28T03:27:08.06366Z","end":"2026-04-28T03:27:09.383733Z","steps":["trace[864755567] 'agreement among raft nodes before linearized reading' (duration: 1.316439007s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.378643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3134895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T03:27:09.378666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112779051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T03:27:09.379913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.026522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-04-28T03:27:09.378623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.032093Z","time spent":"1.345350065s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.378758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315027927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T03:27:09.384058Z","caller":"traceutil/trace.go:171","msg":"trace[954381479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4955; }","duration":"1.118197113s","start":"2026-04-28T03:27:08.265827Z","end":"2026-04-28T03:27:09.384024Z","steps":["trace[954381479] 'agreement among raft nodes before linearized reading' (duration: 1.112780361s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.384132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.265817Z","time spent":"1.118301367s","remote":"127.0.0.1:49704","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-28T03:27:09.383963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.063654Z","time spent":"1.320294681s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T03:27:09.384049Z","caller":"traceutil/trace.go:171","msg":"trace[1796742645] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4955; }","duration":"485.205421ms","start":"2026-04-28T03:27:08.898836Z","end":"2026-04-28T03:27:09.384041Z","steps":["trace[1796742645] 'agreement among raft nodes before linearized reading' (duration: 480.951927ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.383987Z","caller":"traceutil/trace.go:171","msg":"trace[829956923] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4955; }","duration":"1.318848888s","start":"2026-04-28T03:27:08.065131Z","end":"2026-04-28T03:27:09.38398Z","steps":["trace[829956923] 'agreement among raft nodes before linearized reading' (duration: 1.31348372s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.38421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.898821Z","time spent":"485.37914ms","remote":"127.0.0.1:38434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-28T03:27:09.384214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.065124Z","time spent":"1.31908147s","remote":"127.0.0.1:49638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-28T03:27:09.384123Z","caller":"traceutil/trace.go:171","msg":"trace[345152525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4955; }","duration":"1.320386535s","start":"2026-04-28T03:27:08.063702Z","end":"2026-04-28T03:27:09.384089Z","steps":["trace[345152525] 'agreement among raft nodes before linearized reading' (duration: 1.314956873s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.384295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:08.063688Z","time spent":"1.320593786s","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T03:27:09.785582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.282518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:27:09.785825Z","caller":"traceutil/trace.go:171","msg":"trace[155628839] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"398.164505ms","start":"2026-04-28T03:27:09.387611Z","end":"2026-04-28T03:27:09.785776Z","steps":["trace[155628839] 'process raft request' (duration: 229.611183ms)","trace[155628839] 'compare' (duration: 168.181713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:09.785933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.387594Z","time spent":"398.27737ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:09.786108Z","caller":"traceutil/trace.go:171","msg":"trace[1290318773] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"398.391317ms","start":"2026-04-28T03:27:09.387682Z","end":"2026-04-28T03:27:09.786073Z","steps":["trace[1290318773] 'process raft request' (duration: 397.995367ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.786221Z","caller":"traceutil/trace.go:171","msg":"trace[265218279] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"398.306202ms","start":"2026-04-28T03:27:09.387902Z","end":"2026-04-28T03:27:09.786208Z","steps":["trace[265218279] 'process raft request' (duration: 398.187356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.786321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.387678Z","time spent":"398.491411ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:09.786359Z","caller":"traceutil/trace.go:171","msg":"trace[574622260] linearizableReadLoop","detail":"{readStateIndex:5160; appliedIndex:5151; }","duration":"353.819802ms","start":"2026-04-28T03:27:09.432517Z","end":"2026-04-28T03:27:09.786337Z","steps":["trace[574622260] 'read index received' (duration: 184.640799ms)","trace[574622260] 'applied index is now lower than readState.Index' (duration: 169.178073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:09.786431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.387877Z","time spent":"398.406427ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.786577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.195177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-28T03:27:09.786587Z","caller":"traceutil/trace.go:171","msg":"trace[2091606491] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"398.676581ms","start":"2026-04-28T03:27:09.387898Z","end":"2026-04-28T03:27:09.786575Z","steps":["trace[2091606491] 'process raft request' (duration: 398.097462ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.786615Z","caller":"traceutil/trace.go:171","msg":"trace[1464508321] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4965; }","duration":"399.26354ms","start":"2026-04-28T03:27:09.387342Z","end":"2026-04-28T03:27:09.786605Z","steps":["trace[1464508321] 'agreement among raft nodes before linearized reading' (duration: 399.106882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.786651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.387338Z","time spent":"399.303122ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-28T03:27:09.78686Z","caller":"traceutil/trace.go:171","msg":"trace[2124582780] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"397.636919ms","start":"2026-04-28T03:27:09.389215Z","end":"2026-04-28T03:27:09.786852Z","steps":["trace[2124582780] 'process raft request' (duration: 396.941354ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.786905Z","caller":"traceutil/trace.go:171","msg":"trace[180757058] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"394.977765ms","start":"2026-04-28T03:27:09.391904Z","end":"2026-04-28T03:27:09.786882Z","steps":["trace[180757058] 'process raft request' (duration: 394.314412ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:09.786953Z","caller":"traceutil/trace.go:171","msg":"trace[1930813383] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"395.741323ms","start":"2026-04-28T03:27:09.391196Z","end":"2026-04-28T03:27:09.786937Z","steps":["trace[1930813383] 'process raft request' (duration: 394.992215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.786978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.389205Z","time spent":"397.692661ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.78704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.391897Z","time spent":"395.07573ms","remote":"127.0.0.1:49892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10926,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.787044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.391176Z","time spent":"395.834308ms","remote":"127.0.0.1:49704","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-28T03:27:09.787096Z","caller":"traceutil/trace.go:171","msg":"trace[773601613] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"398.85969ms","start":"2026-04-28T03:27:09.388229Z","end":"2026-04-28T03:27:09.787089Z","steps":["trace[773601613] 'process raft request' (duration: 397.894111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.787132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.910072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-04-28T03:27:09.78717Z","caller":"traceutil/trace.go:171","msg":"trace[1424351962] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4965; }","duration":"397.964165ms","start":"2026-04-28T03:27:09.389197Z","end":"2026-04-28T03:27:09.787162Z","steps":["trace[1424351962] 'agreement among raft nodes before linearized reading' (duration: 397.845059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.787204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.389191Z","time spent":"398.006577ms","remote":"127.0.0.1:49534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1185,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"warn","ts":"2026-04-28T03:27:09.78667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.387885Z","time spent":"398.729684ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:27:09.787281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.38822Z","time spent":"398.955525ms","remote":"127.0.0.1:49704","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-28T03:27:09.787018Z","caller":"traceutil/trace.go:171","msg":"trace[2046519824] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"394.838738ms","start":"2026-04-28T03:27:09.392166Z","end":"2026-04-28T03:27:09.787004Z","steps":["trace[2046519824] 'process raft request' (duration: 394.107442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:09.787395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:27:09.392161Z","time spent":"395.213847ms","remote":"127.0.0.1:49624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T03:27:09.833543Z","caller":"traceutil/trace.go:171","msg":"trace[1077783718] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"102.769793ms","start":"2026-04-28T03:27:09.730214Z","end":"2026-04-28T03:27:09.832984Z","steps":["trace[1077783718] 'process raft request' (duration: 102.616055ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:10.04778Z","caller":"traceutil/trace.go:171","msg":"trace[1442606076] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"252.907835ms","start":"2026-04-28T03:27:09.793586Z","end":"2026-04-28T03:27:10.046494Z","steps":["trace[1442606076] 'process raft request' (duration: 175.927182ms)","trace[1442606076] 'compare' (duration: 76.580003ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:10.048523Z","caller":"traceutil/trace.go:171","msg":"trace[19487878] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"254.738466ms","start":"2026-04-28T03:27:09.793755Z","end":"2026-04-28T03:27:10.048493Z","steps":["trace[19487878] 'process raft request' (duration: 252.481633ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:10.049132Z","caller":"traceutil/trace.go:171","msg":"trace[106328038] linearizableReadLoop","detail":"{readStateIndex:5165; appliedIndex:5161; }","duration":"216.144462ms","start":"2026-04-28T03:27:09.832955Z","end":"2026-04-28T03:27:10.0491Z","steps":["trace[106328038] 'read index received' (duration: 136.553848ms)","trace[106328038] 'applied index is now lower than readState.Index' (duration: 79.589564ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:10.049228Z","caller":"traceutil/trace.go:171","msg":"trace[995668545] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"255.18501ms","start":"2026-04-28T03:27:09.79399Z","end":"2026-04-28T03:27:10.049175Z","steps":["trace[995668545] 'process raft request' (duration: 252.478674ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:10.049261Z","caller":"traceutil/trace.go:171","msg":"trace[273154388] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"254.286045ms","start":"2026-04-28T03:27:09.794928Z","end":"2026-04-28T03:27:10.049214Z","steps":["trace[273154388] 'process raft request' (duration: 253.981479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:10.049833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.355608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-28T03:27:10.049891Z","caller":"traceutil/trace.go:171","msg":"trace[1157021022] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4970; }","duration":"256.430332ms","start":"2026-04-28T03:27:09.793443Z","end":"2026-04-28T03:27:10.049874Z","steps":["trace[1157021022] 'agreement among raft nodes before linearized reading' (duration: 256.056773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:10.051302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.104608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-28T03:27:10.05138Z","caller":"traceutil/trace.go:171","msg":"trace[1235306667] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4970; }","duration":"205.240875ms","start":"2026-04-28T03:27:09.846117Z","end":"2026-04-28T03:27:10.051358Z","steps":["trace[1235306667] 'agreement among raft nodes before linearized reading' (duration: 203.448455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:10.429317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.653113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T03:27:10.429418Z","caller":"traceutil/trace.go:171","msg":"trace[1377881145] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:4973; }","duration":"186.80242ms","start":"2026-04-28T03:27:10.24259Z","end":"2026-04-28T03:27:10.429392Z","steps":["trace[1377881145] 'range keys from in-memory index tree' (duration: 186.467004ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:10.700608Z","caller":"traceutil/trace.go:171","msg":"trace[1890364284] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5169; }","duration":"206.601115ms","start":"2026-04-28T03:27:10.493986Z","end":"2026-04-28T03:27:10.700587Z","steps":["trace[1890364284] 'read index received' (duration: 380.489µs)","trace[1890364284] 'applied index is now lower than readState.Index' (duration: 206.219716ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:10.700819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.819095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-28T03:27:10.700864Z","caller":"traceutil/trace.go:171","msg":"trace[1079188445] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4975; }","duration":"206.90502ms","start":"2026-04-28T03:27:10.493945Z","end":"2026-04-28T03:27:10.70085Z","steps":["trace[1079188445] 'agreement among raft nodes before linearized reading' (duration: 206.762493ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:10.701135Z","caller":"traceutil/trace.go:171","msg":"trace[1005709721] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"229.479494ms","start":"2026-04-28T03:27:10.471182Z","end":"2026-04-28T03:27:10.700662Z","steps":["trace[1005709721] 'process raft request' (duration: 229.247862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:10.701156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.731791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-28T03:27:10.70129Z","caller":"traceutil/trace.go:171","msg":"trace[1391537217] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4975; }","duration":"204.896449ms","start":"2026-04-28T03:27:10.496382Z","end":"2026-04-28T03:27:10.701279Z","steps":["trace[1391537217] 'agreement among raft nodes before linearized reading' (duration: 204.693669ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:42.065244Z","caller":"traceutil/trace.go:171","msg":"trace[2114792607] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"105.001825ms","start":"2026-04-28T03:27:41.960215Z","end":"2026-04-28T03:27:42.065217Z","steps":["trace[2114792607] 'process raft request' (duration: 104.782494ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:42.07053Z","caller":"traceutil/trace.go:171","msg":"trace[514153310] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"109.961735ms","start":"2026-04-28T03:27:41.960543Z","end":"2026-04-28T03:27:42.070505Z","steps":["trace[514153310] 'process raft request' (duration: 109.791416ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:42.070693Z","caller":"traceutil/trace.go:171","msg":"trace[200286358] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5462; }","duration":"108.545641ms","start":"2026-04-28T03:27:41.962129Z","end":"2026-04-28T03:27:42.070674Z","steps":["trace[200286358] 'read index received' (duration: 102.939438ms)","trace[200286358] 'applied index is now lower than readState.Index' (duration: 5.604193ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:42.070745Z","caller":"traceutil/trace.go:171","msg":"trace[859968194] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"109.333192ms","start":"2026-04-28T03:27:41.96138Z","end":"2026-04-28T03:27:42.070713Z","steps":["trace[859968194] 'process raft request' (duration: 109.083999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:42.070932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.73376ms","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-28T03:27:42.070972Z","caller":"traceutil/trace.go:171","msg":"trace[1766744122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5262; }","duration":"108.865848ms","start":"2026-04-28T03:27:41.962095Z","end":"2026-04-28T03:27:42.070961Z","steps":["trace[1766744122] 'agreement among raft nodes before linearized reading' (duration: 108.670248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:42.073531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.736872ms","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-28T03:27:42.073579Z","caller":"traceutil/trace.go:171","msg":"trace[575315439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5262; }","duration":"104.800495ms","start":"2026-04-28T03:27:41.968767Z","end":"2026-04-28T03:27:42.073567Z","steps":["trace[575315439] 'agreement among raft nodes before linearized reading' (duration: 102.152157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:27:42.073576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.425236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T03:27:42.073639Z","caller":"traceutil/trace.go:171","msg":"trace[179990216] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5262; }","duration":"100.518842ms","start":"2026-04-28T03:27:41.973109Z","end":"2026-04-28T03:27:42.073627Z","steps":["trace[179990216] 'agreement among raft nodes before linearized reading' (duration: 97.854252ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:50.584497Z","caller":"traceutil/trace.go:171","msg":"trace[345481153] linearizableReadLoop","detail":"{readStateIndex:5519; appliedIndex:5518; }","duration":"182.52424ms","start":"2026-04-28T03:27:50.401948Z","end":"2026-04-28T03:27:50.584472Z","steps":["trace[345481153] 'read index received' (duration: 155.25687ms)","trace[345481153] 'applied index is now lower than readState.Index' (duration: 27.266129ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:50.584702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.746661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-28T03:27:50.584744Z","caller":"traceutil/trace.go:171","msg":"trace[1238324513] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5314; }","duration":"182.828575ms","start":"2026-04-28T03:27:50.401902Z","end":"2026-04-28T03:27:50.58473Z","steps":["trace[1238324513] 'agreement among raft nodes before linearized reading' (duration: 182.656566ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:27:50.584749Z","caller":"traceutil/trace.go:171","msg":"trace[255994783] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"206.214141ms","start":"2026-04-28T03:27:50.378494Z","end":"2026-04-28T03:27:50.584708Z","steps":["trace[255994783] 'process raft request' (duration: 178.682637ms)","trace[255994783] 'compare' (duration: 26.725141ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:51.701443Z","caller":"traceutil/trace.go:171","msg":"trace[1678322241] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5521; }","duration":"112.028812ms","start":"2026-04-28T03:27:51.589388Z","end":"2026-04-28T03:27:51.701417Z","steps":["trace[1678322241] 'read index received' (duration: 66.983691ms)","trace[1678322241] 'applied index is now lower than readState.Index' (duration: 45.044171ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:27:51.701654Z","caller":"traceutil/trace.go:171","msg":"trace[225881207] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"137.932132ms","start":"2026-04-28T03:27:51.563696Z","end":"2026-04-28T03:27:51.701628Z","steps":["trace[225881207] 'process raft request' (duration: 92.784895ms)","trace[225881207] 'compare' (duration: 44.709713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:27:51.701792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.376361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:27:51.702297Z","caller":"traceutil/trace.go:171","msg":"trace[1063797505] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5317; }","duration":"112.92492ms","start":"2026-04-28T03:27:51.589353Z","end":"2026-04-28T03:27:51.702278Z","steps":["trace[1063797505] 'agreement among raft nodes before linearized reading' (duration: 112.324438ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:28:14.93271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2848} {"level":"info","ts":"2026-04-28T03:28:15.015078Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2848,"took":"80.745602ms","hash":4166996147,"current-db-size-bytes":53043200,"current-db-size":"53 MB","current-db-size-in-use-bytes":29396992,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-28T03:28:15.015226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4166996147,"revision":2848,"compact-revision":-1} {"level":"info","ts":"2026-04-28T03:31:04.648367Z","caller":"traceutil/trace.go:171","msg":"trace[1466207140] transaction","detail":"{read_only:false; response_revision:6690; number_of_response:1; }","duration":"165.350474ms","start":"2026-04-28T03:31:04.482994Z","end":"2026-04-28T03:31:04.648344Z","steps":["trace[1466207140] 'process raft request' (duration: 165.232855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:31:05.526366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.276644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T03:31:05.526455Z","caller":"traceutil/trace.go:171","msg":"trace[966838573] linearizableReadLoop","detail":"{readStateIndex:6945; appliedIndex:6943; }","duration":"340.447498ms","start":"2026-04-28T03:31:05.185995Z","end":"2026-04-28T03:31:05.526443Z","steps":["trace[966838573] 'read index received' (duration: 25.118918ms)","trace[966838573] 'applied index is now lower than readState.Index' (duration: 315.32752ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:31:05.526519Z","caller":"traceutil/trace.go:171","msg":"trace[1621596267] transaction","detail":"{read_only:false; response_revision:6692; number_of_response:1; }","duration":"369.905703ms","start":"2026-04-28T03:31:05.156605Z","end":"2026-04-28T03:31:05.526511Z","steps":["trace[1621596267] 'process raft request' (duration: 216.373172ms)","trace[1621596267] 'compare' (duration: 152.90041ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T03:31:05.526563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:31:05.156589Z","time spent":"369.953482ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T03:31:05.526798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.782153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-28T03:31:05.526962Z","caller":"traceutil/trace.go:171","msg":"trace[1181800192] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6692; }","duration":"340.922011ms","start":"2026-04-28T03:31:05.185958Z","end":"2026-04-28T03:31:05.52688Z","steps":["trace[1181800192] 'agreement among raft nodes before linearized reading' (duration: 340.653605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:31:05.527045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:31:05.185947Z","time spent":"341.056209ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T03:31:05.566492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.219928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T03:31:05.566548Z","caller":"traceutil/trace.go:171","msg":"trace[1070825079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6692; }","duration":"347.299717ms","start":"2026-04-28T03:31:05.219237Z","end":"2026-04-28T03:31:05.566536Z","steps":["trace[1070825079] 'agreement among raft nodes before linearized reading' (duration: 347.15566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:31:05.566569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:31:05.219227Z","time spent":"347.337507ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T03:31:05.566591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.967342ms","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-28T03:31:05.566654Z","caller":"traceutil/trace.go:171","msg":"trace[1780199075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6692; }","duration":"349.073901ms","start":"2026-04-28T03:31:05.217566Z","end":"2026-04-28T03:31:05.56664Z","steps":["trace[1780199075] 'agreement among raft nodes before linearized reading' (duration: 348.813915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:31:05.566695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:31:05.217552Z","time spent":"349.13126ms","remote":"127.0.0.1:49704","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":"warn","ts":"2026-04-28T03:31:05.566844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.404311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T03:31:05.566865Z","caller":"traceutil/trace.go:171","msg":"trace[1464538009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6693; }","duration":"348.442811ms","start":"2026-04-28T03:31:05.218417Z","end":"2026-04-28T03:31:05.56686Z","steps":["trace[1464538009] 'agreement among raft nodes before linearized reading' (duration: 348.384721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:31:05.566883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:31:05.218409Z","time spent":"348.469759ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T03:31:05.56688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.772985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T03:31:05.567Z","caller":"traceutil/trace.go:171","msg":"trace[2125995577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6693; }","duration":"348.913254ms","start":"2026-04-28T03:31:05.218081Z","end":"2026-04-28T03:31:05.566994Z","steps":["trace[2125995577] 'agreement among raft nodes before linearized reading' (duration: 348.763336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:31:05.567016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T03:31:05.218068Z","time spent":"348.942922ms","remote":"127.0.0.1:49704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T03:31:05.835156Z","caller":"traceutil/trace.go:171","msg":"trace[2118510466] transaction","detail":"{read_only:false; response_revision:6694; number_of_response:1; }","duration":"263.348137ms","start":"2026-04-28T03:31:05.571783Z","end":"2026-04-28T03:31:05.835131Z","steps":["trace[2118510466] 'process raft request' (duration: 256.897932ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:31:05.849805Z","caller":"traceutil/trace.go:171","msg":"trace[443804340] linearizableReadLoop","detail":"{readStateIndex:6951; appliedIndex:6947; }","duration":"252.201403ms","start":"2026-04-28T03:31:05.597581Z","end":"2026-04-28T03:31:05.849783Z","steps":["trace[443804340] 'read index received' (duration: 231.106854ms)","trace[443804340] 'applied index is now lower than readState.Index' (duration: 21.093619ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T03:31:05.849985Z","caller":"traceutil/trace.go:171","msg":"trace[1877773493] transaction","detail":"{read_only:false; response_revision:6695; number_of_response:1; }","duration":"277.842123ms","start":"2026-04-28T03:31:05.57213Z","end":"2026-04-28T03:31:05.849972Z","steps":["trace[1877773493] 'process raft request' (duration: 277.446539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T03:31:05.850025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.36242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T03:31:05.850024Z","caller":"traceutil/trace.go:171","msg":"trace[1756955820] transaction","detail":"{read_only:false; response_revision:6696; number_of_response:1; }","duration":"277.864533ms","start":"2026-04-28T03:31:05.572137Z","end":"2026-04-28T03:31:05.850002Z","steps":["trace[1756955820] 'process raft request' (duration: 277.577397ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:31:05.85007Z","caller":"traceutil/trace.go:171","msg":"trace[184107527] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:6697; }","duration":"252.482198ms","start":"2026-04-28T03:31:05.597576Z","end":"2026-04-28T03:31:05.850058Z","steps":["trace[184107527] 'agreement among raft nodes before linearized reading' (duration: 252.299101ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:31:05.850172Z","caller":"traceutil/trace.go:171","msg":"trace[1084476673] transaction","detail":"{read_only:false; response_revision:6697; number_of_response:1; }","duration":"277.979711ms","start":"2026-04-28T03:31:05.572182Z","end":"2026-04-28T03:31:05.850162Z","steps":["trace[1084476673] 'process raft request' (duration: 277.566537ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T03:33:14.938035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5499} {"level":"info","ts":"2026-04-28T03:33:15.012116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5499,"took":"71.477ms","hash":100169915,"current-db-size-bytes":53043200,"current-db-size":"53 MB","current-db-size-in-use-bytes":18391040,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-28T03:33:15.012172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":100169915,"revision":5499,"compact-revision":2848} {"level":"warn","ts":"2026-04-28T03:33:34.579376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.365804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-28T03:33:34.57961Z","caller":"traceutil/trace.go:171","msg":"trace[973818105] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:7473; }","duration":"108.607757ms","start":"2026-04-28T03:33:34.470977Z","end":"2026-04-28T03:33:34.579584Z","steps":["trace[973818105] 'range keys from in-memory index tree' (duration: 108.235432ms)"],"step_count":1}