{"level":"warn","ts":"2026-05-11T10:44:06.279197Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T10:44:06.279276Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.11: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.11:2380","--initial-cluster=instance=https://199.19.213.11:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.11:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.11:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T10:44:06.279344Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T10:44:06.279359Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-05-11T10:44:06.279375Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T10:44:06.280084Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"]} {"level":"info","ts":"2026-05-11T10:44:06.280156Z","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.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.11:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T10:44:06.285019Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.652749ms"} {"level":"info","ts":"2026-05-11T10:44:06.292511Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ce8ad90e35537ee3","cluster-id":"3c17b62c6529d987"} {"level":"info","ts":"2026-05-11T10:44:06.292676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T10:44:06.292708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 0"} {"level":"info","ts":"2026-05-11T10:44:06.292789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ce8ad90e35537ee3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T10:44:06.29283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 1"} {"level":"info","ts":"2026-05-11T10:44:06.292916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"warn","ts":"2026-05-11T10:44:06.300496Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T10:44:06.302219Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T10:44:06.30373Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T10:44:06.306958Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ce8ad90e35537ee3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T10:44:06.30737Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:44:06.308497Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ce8ad90e35537ee3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T10:44:06.309502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"info","ts":"2026-05-11T10:44:06.309678Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","added-peer-id":"ce8ad90e35537ee3","added-peer-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-05-11T10:44:06.309982Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:44:06.31006Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T10:44:06.313336Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T10:44:06.313578Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-05-11T10:44:06.313635Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-05-11T10:44:06.313832Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ce8ad90e35537ee3","initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T10:44:06.313911Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T10:44:06.393525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T10:44:06.393567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T10:44:06.393602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgPreVoteResp from ce8ad90e35537ee3 at term 1"} {"level":"info","ts":"2026-05-11T10:44:06.393619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became candidate at term 2"} {"level":"info","ts":"2026-05-11T10:44:06.39363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgVoteResp from ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-05-11T10:44:06.393645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became leader at term 2"} {"level":"info","ts":"2026-05-11T10:44:06.393658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ce8ad90e35537ee3 elected leader ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-05-11T10:44:06.39473Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ce8ad90e35537ee3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.11:2379]}","request-path":"/0/members/ce8ad90e35537ee3/attributes","cluster-id":"3c17b62c6529d987","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T10:44:06.394884Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T10:44:06.394949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T10:44:06.395423Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:44:06.399157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T10:44:06.399534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.11:2379"} {"level":"info","ts":"2026-05-11T10:44:06.399708Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T10:44:06.39973Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T10:44:06.405091Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:44:06.405217Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:44:06.405301Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:44:09.032791Z","caller":"traceutil/trace.go:171","msg":"trace[490447602] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"111.026019ms","start":"2026-05-11T10:44:08.921739Z","end":"2026-05-11T10:44:09.032765Z","steps":["trace[490447602] 'read index received' (duration: 52.273612ms)","trace[490447602] 'applied index is now lower than readState.Index' (duration: 58.751437ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:44:09.032886Z","caller":"traceutil/trace.go:171","msg":"trace[115687420] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"112.301863ms","start":"2026-05-11T10:44:08.920544Z","end":"2026-05-11T10:44:09.032846Z","steps":["trace[115687420] 'process raft request' (duration: 53.528216ms)","trace[115687420] 'compare' (duration: 58.568516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:44:09.03311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.33759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-11T10:44:09.033233Z","caller":"traceutil/trace.go:171","msg":"trace[1961416851] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"111.500411ms","start":"2026-05-11T10:44:08.921711Z","end":"2026-05-11T10:44:09.033212Z","steps":["trace[1961416851] 'agreement among raft nodes before linearized reading' (duration: 111.205099ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:11.256924Z","caller":"traceutil/trace.go:171","msg":"trace[1378607518] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:215; }","duration":"116.550602ms","start":"2026-05-11T10:44:11.140351Z","end":"2026-05-11T10:44:11.256901Z","steps":["trace[1378607518] 'read index received' (duration: 44.646787ms)","trace[1378607518] 'applied index is now lower than readState.Index' (duration: 71.902955ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:44:11.257044Z","caller":"traceutil/trace.go:171","msg":"trace[694540476] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"153.81869ms","start":"2026-05-11T10:44:11.103186Z","end":"2026-05-11T10:44:11.257004Z","steps":["trace[694540476] 'process raft request' (duration: 81.824074ms)","trace[694540476] 'compare' (duration: 71.761745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:44:11.257131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.775623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:44:11.257199Z","caller":"traceutil/trace.go:171","msg":"trace[1097795158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:210; }","duration":"116.869983ms","start":"2026-05-11T10:44:11.140307Z","end":"2026-05-11T10:44:11.257177Z","steps":["trace[1097795158] 'agreement among raft nodes before linearized reading' (duration: 116.787733ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:11.43835Z","caller":"traceutil/trace.go:171","msg":"trace[1040798134] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:219; }","duration":"144.487672ms","start":"2026-05-11T10:44:11.293831Z","end":"2026-05-11T10:44:11.438319Z","steps":["trace[1040798134] 'read index received' (duration: 91.390833ms)","trace[1040798134] 'applied index is now lower than readState.Index' (duration: 53.095809ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:44:11.438431Z","caller":"traceutil/trace.go:171","msg":"trace[1181555397] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"165.459166ms","start":"2026-05-11T10:44:11.272938Z","end":"2026-05-11T10:44:11.438398Z","steps":["trace[1181555397] 'process raft request' (duration: 112.344646ms)","trace[1181555397] 'compare' (duration: 52.598907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:44:11.438691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.923485ms","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-05-11T10:44:11.438764Z","caller":"traceutil/trace.go:171","msg":"trace[958490463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:214; }","duration":"168.045975ms","start":"2026-05-11T10:44:11.270701Z","end":"2026-05-11T10:44:11.438747Z","steps":["trace[958490463] 'agreement among raft nodes before linearized reading' (duration: 167.740104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:11.438817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.616523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"warn","ts":"2026-05-11T10:44:11.438951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.897855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:44:11.439073Z","caller":"traceutil/trace.go:171","msg":"trace[2051192553] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:214; }","duration":"135.136645ms","start":"2026-05-11T10:44:11.303899Z","end":"2026-05-11T10:44:11.439035Z","steps":["trace[2051192553] 'agreement among raft nodes before linearized reading' (duration: 134.809164ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:11.439091Z","caller":"traceutil/trace.go:171","msg":"trace[1575770263] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"143.479079ms","start":"2026-05-11T10:44:11.295084Z","end":"2026-05-11T10:44:11.438563Z","steps":["trace[1575770263] 'process raft request' (duration: 143.002266ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:11.438923Z","caller":"traceutil/trace.go:171","msg":"trace[210179084] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:214; }","duration":"162.801075ms","start":"2026-05-11T10:44:11.276104Z","end":"2026-05-11T10:44:11.438905Z","steps":["trace[210179084] 'agreement among raft nodes before linearized reading' (duration: 162.308803ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:11.638022Z","caller":"traceutil/trace.go:171","msg":"trace[1895600625] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"101.717123ms","start":"2026-05-11T10:44:11.536277Z","end":"2026-05-11T10:44:11.637994Z","steps":["trace[1895600625] 'process raft request' (duration: 101.549703ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:14.595357Z","caller":"traceutil/trace.go:171","msg":"trace[1249736727] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"100.991272ms","start":"2026-05-11T10:44:14.494323Z","end":"2026-05-11T10:44:14.595314Z","steps":["trace[1249736727] 'process raft request' (duration: 100.858701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:14.747187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.540902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:44:14.74728Z","caller":"traceutil/trace.go:171","msg":"trace[2036875681] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:292; }","duration":"103.702992ms","start":"2026-05-11T10:44:14.643554Z","end":"2026-05-11T10:44:14.747257Z","steps":["trace[2036875681] 'range keys from in-memory index tree' (duration: 103.432422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:15.284109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.178191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-11T10:44:15.284179Z","caller":"traceutil/trace.go:171","msg":"trace[1419797885] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"140.310981ms","start":"2026-05-11T10:44:15.143854Z","end":"2026-05-11T10:44:15.284165Z","steps":["trace[1419797885] 'range keys from in-memory index tree' (duration: 139.97636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:39.269252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.121697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:44:39.269416Z","caller":"traceutil/trace.go:171","msg":"trace[2133686804] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.063306417s","start":"2026-05-11T10:44:38.206081Z","end":"2026-05-11T10:44:39.269387Z","steps":["trace[2133686804] 'process raft request' (duration: 728.161496ms)","trace[2133686804] 'compare' (duration: 333.841709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:44:39.269533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:38.206049Z","time spent":"1.063413724s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:44:39.995378Z","caller":"traceutil/trace.go:171","msg":"trace[1264134045] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.782043632s","start":"2026-05-11T10:44:38.213285Z","end":"2026-05-11T10:44:39.995328Z","steps":["trace[1264134045] 'process raft request' (duration: 1.751653335s)","trace[1264134045] 'compare' (duration: 29.943168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:44:39.995643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:38.213254Z","time spent":"1.782196592s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:44:39.996564Z","caller":"traceutil/trace.go:171","msg":"trace[1303193963] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:555; }","duration":"1.529488672s","start":"2026-05-11T10:44:38.467043Z","end":"2026-05-11T10:44:39.996532Z","steps":["trace[1303193963] 'read index received' (duration: 467.196612ms)","trace[1303193963] 'applied index is now lower than readState.Index' (duration: 1.06229058s)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:44:39.996626Z","caller":"traceutil/trace.go:171","msg":"trace[1001626881] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.545135323s","start":"2026-05-11T10:44:38.45148Z","end":"2026-05-11T10:44:39.996616Z","steps":["trace[1001626881] 'process raft request' (duration: 1.544498133s)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:39.996707Z","caller":"traceutil/trace.go:171","msg":"trace[1887497810] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.321717736s","start":"2026-05-11T10:44:38.674974Z","end":"2026-05-11T10:44:39.996692Z","steps":["trace[1887497810] 'process raft request' (duration: 1.321492512s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:39.996743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:38.451452Z","time spent":"1.545200117s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T10:44:39.996927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:38.674935Z","time spent":"1.321816143s","remote":"127.0.0.1:51970","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-05-11T10:44:39.997643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529904419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T10:44:39.997721Z","caller":"traceutil/trace.go:171","msg":"trace[732595272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"1.530702049s","start":"2026-05-11T10:44:38.467Z","end":"2026-05-11T10:44:39.997702Z","steps":["trace[732595272] 'agreement among raft nodes before linearized reading' (duration: 1.529703836s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:39.997772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:38.466987Z","time spent":"1.530766673s","remote":"127.0.0.1:51970","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-05-11T10:44:40.502699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325488554580349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T10:44:40.658875Z","caller":"traceutil/trace.go:171","msg":"trace[1948808650] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.372013457s","start":"2026-05-11T10:44:39.286833Z","end":"2026-05-11T10:44:40.658847Z","steps":["trace[1948808650] 'process raft request' (duration: 1.363293755s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:40.659048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:39.286791Z","time spent":"1.372177868s","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20842,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:44:40.659102Z","caller":"traceutil/trace.go:171","msg":"trace[189839618] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"535.126427ms","start":"2026-05-11T10:44:40.123955Z","end":"2026-05-11T10:44:40.659081Z","steps":["trace[189839618] 'process raft request' (duration: 534.961087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:40.659198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:40.123903Z","time spent":"535.250975ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T10:44:40.65901Z","caller":"traceutil/trace.go:171","msg":"trace[69935854] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:559; }","duration":"662.32975ms","start":"2026-05-11T10:44:39.996646Z","end":"2026-05-11T10:44:40.658976Z","steps":["trace[69935854] 'read index received' (duration: 653.561095ms)","trace[69935854] 'applied index is now lower than readState.Index' (duration: 8.767655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:44:40.659229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483422123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:44:40.659301Z","caller":"traceutil/trace.go:171","msg":"trace[56091012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"1.483588523s","start":"2026-05-11T10:44:39.175695Z","end":"2026-05-11T10:44:40.659283Z","steps":["trace[56091012] 'agreement among raft nodes before linearized reading' (duration: 1.483398051s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:40.659369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:39.175665Z","time spent":"1.48368942s","remote":"127.0.0.1:51716","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-05-11T10:44:40.659376Z","caller":"traceutil/trace.go:171","msg":"trace[2113051197] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"657.158844ms","start":"2026-05-11T10:44:40.002152Z","end":"2026-05-11T10:44:40.659311Z","steps":["trace[2113051197] 'process raft request' (duration: 656.426648ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:40.659517Z","caller":"traceutil/trace.go:171","msg":"trace[1967410096] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"627.695557ms","start":"2026-05-11T10:44:40.031806Z","end":"2026-05-11T10:44:40.659502Z","steps":["trace[1967410096] 'process raft request' (duration: 626.911967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:40.659548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:40.002136Z","time spent":"657.320674ms","remote":"127.0.0.1:51970","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-05-11T10:44:40.65962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:40.031775Z","time spent":"627.793653ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:44:40.659697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006944229s","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-05-11T10:44:40.659796Z","caller":"traceutil/trace.go:171","msg":"trace[1706328138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"1.007091368s","start":"2026-05-11T10:44:39.652659Z","end":"2026-05-11T10:44:40.65975Z","steps":["trace[1706328138] 'agreement among raft nodes before linearized reading' (duration: 1.006743617s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:40.659826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080905553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-11T10:44:40.659869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.572492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-11T10:44:40.659888Z","caller":"traceutil/trace.go:171","msg":"trace[2020895461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"1.080964446s","start":"2026-05-11T10:44:39.578903Z","end":"2026-05-11T10:44:40.659867Z","steps":["trace[2020895461] 'agreement among raft nodes before linearized reading' (duration: 1.080805587s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:40.659912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:39.652636Z","time spent":"1.007204216s","remote":"127.0.0.1:51970","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-05-11T10:44:40.659962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:39.578837Z","time spent":"1.081102515s","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T10:44:40.660036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207335274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T10:44:40.66011Z","caller":"traceutil/trace.go:171","msg":"trace[50327422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"1.207497414s","start":"2026-05-11T10:44:39.452598Z","end":"2026-05-11T10:44:40.660095Z","steps":["trace[50327422] 'agreement among raft nodes before linearized reading' (duration: 1.207182964s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:40.660176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:39.45257Z","time spent":"1.20759475s","remote":"127.0.0.1:51970","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-05-11T10:44:40.659948Z","caller":"traceutil/trace.go:171","msg":"trace[936030363] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:541; }","duration":"884.722102ms","start":"2026-05-11T10:44:39.775206Z","end":"2026-05-11T10:44:40.659928Z","steps":["trace[936030363] 'agreement among raft nodes before linearized reading' (duration: 884.555982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:40.660297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:39.775179Z","time spent":"885.078084ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.11\" "} {"level":"info","ts":"2026-05-11T10:44:46.812839Z","caller":"traceutil/trace.go:171","msg":"trace[505636776] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"108.626007ms","start":"2026-05-11T10:44:46.704191Z","end":"2026-05-11T10:44:46.812817Z","steps":["trace[505636776] 'read index received' (duration: 108.382123ms)","trace[505636776] 'applied index is now lower than readState.Index' (duration: 243.434µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:44:46.813078Z","caller":"traceutil/trace.go:171","msg":"trace[1500868724] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"120.478639ms","start":"2026-05-11T10:44:46.692589Z","end":"2026-05-11T10:44:46.813068Z","steps":["trace[1500868724] 'process raft request' (duration: 120.040114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:46.813281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.072363ms","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-05-11T10:44:46.813309Z","caller":"traceutil/trace.go:171","msg":"trace[2055893383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"109.127916ms","start":"2026-05-11T10:44:46.704173Z","end":"2026-05-11T10:44:46.813301Z","steps":["trace[2055893383] 'agreement among raft nodes before linearized reading' (duration: 109.0206ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:47.536575Z","caller":"traceutil/trace.go:171","msg":"trace[1887319402] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"346.51826ms","start":"2026-05-11T10:44:47.190034Z","end":"2026-05-11T10:44:47.536552Z","steps":["trace[1887319402] 'read index received' (duration: 331.844557ms)","trace[1887319402] 'applied index is now lower than readState.Index' (duration: 14.672693ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:44:47.536612Z","caller":"traceutil/trace.go:171","msg":"trace[951723222] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"506.371904ms","start":"2026-05-11T10:44:47.030204Z","end":"2026-05-11T10:44:47.536576Z","steps":["trace[951723222] 'process raft request' (duration: 491.777386ms)","trace[951723222] 'compare' (duration: 14.253839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:44:47.536811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.752084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:44:47.536892Z","caller":"traceutil/trace.go:171","msg":"trace[57513591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"346.845289ms","start":"2026-05-11T10:44:47.190027Z","end":"2026-05-11T10:44:47.536873Z","steps":["trace[57513591] 'agreement among raft nodes before linearized reading' (duration: 346.600504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:47.536839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:47.030187Z","time spent":"506.597238ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T10:44:47.536946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:47.18998Z","time spent":"346.954506ms","remote":"127.0.0.1:51716","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-05-11T10:44:47.537191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.470413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5s4g9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-11T10:44:47.537239Z","caller":"traceutil/trace.go:171","msg":"trace[1551501295] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5s4g9; range_end:; response_count:1; response_revision:580; }","duration":"258.562068ms","start":"2026-05-11T10:44:47.278668Z","end":"2026-05-11T10:44:47.53723Z","steps":["trace[1551501295] 'agreement among raft nodes before linearized reading' (duration: 258.472343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:48.73603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325488554580533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T10:44:49.249552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325488554580533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T10:44:49.539456Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.556795689s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T10:44:49.564588Z","caller":"traceutil/trace.go:171","msg":"trace[1717102638] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"1.329317046s","start":"2026-05-11T10:44:48.235243Z","end":"2026-05-11T10:44:49.56456Z","steps":["trace[1717102638] 'read index received' (duration: 1.304472632s)","trace[1717102638] 'applied index is now lower than readState.Index' (duration: 24.843724ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:44:49.564706Z","caller":"traceutil/trace.go:171","msg":"trace[1096498899] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.591452881s","start":"2026-05-11T10:44:47.973183Z","end":"2026-05-11T10:44:49.564636Z","steps":["trace[1096498899] 'process raft request' (duration: 1.566851051s)","trace[1096498899] 'compare' (duration: 23.786485ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:44:49.564926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.454668ms","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-05-11T10:44:49.564947Z","caller":"traceutil/trace.go:171","msg":"trace[1919537031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"710.49193ms","start":"2026-05-11T10:44:48.854446Z","end":"2026-05-11T10:44:49.564938Z","steps":["trace[1919537031] 'agreement among raft nodes before linearized reading' (duration: 710.447618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:49.564973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:48.854432Z","time spent":"710.534763ms","remote":"127.0.0.1:51970","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-05-11T10:44:49.56483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.983425ms","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-05-11T10:44:49.565012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329729179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:44:49.5651Z","caller":"traceutil/trace.go:171","msg":"trace[710775113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"1.329889329s","start":"2026-05-11T10:44:48.235191Z","end":"2026-05-11T10:44:49.56508Z","steps":["trace[710775113] 'agreement among raft nodes before linearized reading' (duration: 1.329477505s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:49.564885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.147937ms","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-05-11T10:44:49.565085Z","caller":"traceutil/trace.go:171","msg":"trace[1652005379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"747.278872ms","start":"2026-05-11T10:44:48.817764Z","end":"2026-05-11T10:44:49.565043Z","steps":["trace[1652005379] 'agreement among raft nodes before linearized reading' (duration: 746.913541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:49.565098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:47.973151Z","time spent":"1.591779288s","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:44:49.565142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:48.235175Z","time spent":"1.329957333s","remote":"127.0.0.1:51716","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-05-11T10:44:49.565171Z","caller":"traceutil/trace.go:171","msg":"trace[43329921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"701.495817ms","start":"2026-05-11T10:44:48.863643Z","end":"2026-05-11T10:44:49.565139Z","steps":["trace[43329921] 'agreement among raft nodes before linearized reading' (duration: 701.20743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:49.565196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:48.81774Z","time spent":"747.41537ms","remote":"127.0.0.1:51970","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-05-11T10:44:49.565217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:48.863628Z","time spent":"701.571752ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T10:44:49.565313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.490087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T10:44:49.565364Z","caller":"traceutil/trace.go:171","msg":"trace[905615998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"701.569481ms","start":"2026-05-11T10:44:48.863779Z","end":"2026-05-11T10:44:49.565348Z","steps":["trace[905615998] 'agreement among raft nodes before linearized reading' (duration: 701.02909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:44:49.565399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:44:48.863762Z","time spent":"701.629574ms","remote":"127.0.0.1:51970","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-05-11T10:44:51.625856Z","caller":"traceutil/trace.go:171","msg":"trace[899020701] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"177.172391ms","start":"2026-05-11T10:44:51.448653Z","end":"2026-05-11T10:44:51.625826Z","steps":["trace[899020701] 'process raft request' (duration: 177.038404ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:44:54.629489Z","caller":"traceutil/trace.go:171","msg":"trace[1471634393] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"156.587454ms","start":"2026-05-11T10:44:54.472877Z","end":"2026-05-11T10:44:54.629464Z","steps":["trace[1471634393] 'process raft request' (duration: 156.414405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:06.254983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325488554580863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T10:45:06.434818Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.117112086s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T10:45:06.435887Z","caller":"traceutil/trace.go:171","msg":"trace[594987901] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.117675071s","start":"2026-05-11T10:45:05.317619Z","end":"2026-05-11T10:45:06.435294Z","steps":["trace[594987901] 'process raft request' (duration: 1.11743649s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:06.436414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:45:05.317601Z","time spent":"1.118670776s","remote":"127.0.0.1:51970","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-05-11T10:45:06.44717Z","caller":"traceutil/trace.go:171","msg":"trace[270989859] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:781; }","duration":"695.414126ms","start":"2026-05-11T10:45:05.751737Z","end":"2026-05-11T10:45:06.447151Z","steps":["trace[270989859] 'read index received' (duration: 683.405899ms)","trace[270989859] 'applied index is now lower than readState.Index' (duration: 12.007437ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:45:06.447205Z","caller":"traceutil/trace.go:171","msg":"trace[807970877] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.047095755s","start":"2026-05-11T10:45:05.400084Z","end":"2026-05-11T10:45:06.44718Z","steps":["trace[807970877] 'process raft request' (duration: 1.046907437s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:06.447318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:45:05.400059Z","time spent":"1.04719562s","remote":"127.0.0.1:51948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:45:06.44737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.617955ms","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-05-11T10:45:06.447471Z","caller":"traceutil/trace.go:171","msg":"trace[1474897243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"695.756721ms","start":"2026-05-11T10:45:05.751705Z","end":"2026-05-11T10:45:06.447462Z","steps":["trace[1474897243] 'agreement among raft nodes before linearized reading' (duration: 695.545592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:06.447499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:45:05.75169Z","time spent":"695.800493ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T10:45:06.447541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.969029ms","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-05-11T10:45:06.447599Z","caller":"traceutil/trace.go:171","msg":"trace[1477365426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"688.048882ms","start":"2026-05-11T10:45:05.759538Z","end":"2026-05-11T10:45:06.447587Z","steps":["trace[1477365426] 'agreement among raft nodes before linearized reading' (duration: 687.908126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:06.447603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.766939ms","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-05-11T10:45:06.447653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:45:05.75953Z","time spent":"688.113985ms","remote":"127.0.0.1:51970","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-05-11T10:45:06.447688Z","caller":"traceutil/trace.go:171","msg":"trace[804824359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"687.873774ms","start":"2026-05-11T10:45:05.759796Z","end":"2026-05-11T10:45:06.44767Z","steps":["trace[804824359] 'agreement among raft nodes before linearized reading' (duration: 687.645024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:06.447737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:45:05.75979Z","time spent":"687.938167ms","remote":"127.0.0.1:51970","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-05-11T10:45:06.447747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.423024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T10:45:06.448622Z","caller":"traceutil/trace.go:171","msg":"trace[1222226203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"690.360915ms","start":"2026-05-11T10:45:05.758245Z","end":"2026-05-11T10:45:06.448606Z","steps":["trace[1222226203] 'agreement among raft nodes before linearized reading' (duration: 689.140261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:06.448662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:45:05.758224Z","time spent":"690.422587ms","remote":"127.0.0.1:51970","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-05-11T10:45:28.683806Z","caller":"traceutil/trace.go:171","msg":"trace[779241707] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"134.857872ms","start":"2026-05-11T10:45:28.54893Z","end":"2026-05-11T10:45:28.683788Z","steps":["trace[779241707] 'read index received' (duration: 134.019383ms)","trace[779241707] 'applied index is now lower than readState.Index' (duration: 837.569µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:45:28.683921Z","caller":"traceutil/trace.go:171","msg":"trace[1159480032] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"172.043234ms","start":"2026-05-11T10:45:28.511867Z","end":"2026-05-11T10:45:28.68391Z","steps":["trace[1159480032] 'process raft request' (duration: 171.230296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:28.684225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.256385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-11T10:45:28.684299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.40169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T10:45:28.684327Z","caller":"traceutil/trace.go:171","msg":"trace[1749251869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"135.410662ms","start":"2026-05-11T10:45:28.548894Z","end":"2026-05-11T10:45:28.684305Z","steps":["trace[1749251869] 'agreement among raft nodes before linearized reading' (duration: 135.135641ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:45:28.684427Z","caller":"traceutil/trace.go:171","msg":"trace[1438716989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1093; }","duration":"117.479582ms","start":"2026-05-11T10:45:28.566853Z","end":"2026-05-11T10:45:28.684333Z","steps":["trace[1438716989] 'agreement among raft nodes before linearized reading' (duration: 117.177231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:28.684592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.70166ms","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-05-11T10:45:28.684662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.517672ms","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-05-11T10:45:28.684666Z","caller":"traceutil/trace.go:171","msg":"trace[1850778832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"117.805223ms","start":"2026-05-11T10:45:28.566842Z","end":"2026-05-11T10:45:28.684647Z","steps":["trace[1850778832] 'agreement among raft nodes before linearized reading' (duration: 117.606376ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:45:28.684697Z","caller":"traceutil/trace.go:171","msg":"trace[1996922568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"121.591305ms","start":"2026-05-11T10:45:28.563097Z","end":"2026-05-11T10:45:28.684688Z","steps":["trace[1996922568] 'agreement among raft nodes before linearized reading' (duration: 121.498421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:28.684395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.728174ms","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-05-11T10:45:28.684778Z","caller":"traceutil/trace.go:171","msg":"trace[100540588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"132.356486ms","start":"2026-05-11T10:45:28.552405Z","end":"2026-05-11T10:45:28.684761Z","steps":["trace[100540588] 'agreement among raft nodes before linearized reading' (duration: 131.567868ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:45:31.823238Z","caller":"traceutil/trace.go:171","msg":"trace[1245197063] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"171.273304ms","start":"2026-05-11T10:45:31.651929Z","end":"2026-05-11T10:45:31.823203Z","steps":["trace[1245197063] 'process raft request' (duration: 170.82837ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:45:31.824244Z","caller":"traceutil/trace.go:171","msg":"trace[1198772460] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"104.30658ms","start":"2026-05-11T10:45:31.719916Z","end":"2026-05-11T10:45:31.824223Z","steps":["trace[1198772460] 'process raft request' (duration: 104.205507ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:45:38.227592Z","caller":"traceutil/trace.go:171","msg":"trace[1955423649] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"381.957641ms","start":"2026-05-11T10:45:37.845608Z","end":"2026-05-11T10:45:38.227566Z","steps":["trace[1955423649] 'process raft request' (duration: 381.791806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:45:38.22775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:45:37.845573Z","time spent":"382.100965ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:46:04.540054Z","caller":"traceutil/trace.go:171","msg":"trace[256911710] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"207.463623ms","start":"2026-05-11T10:46:04.332565Z","end":"2026-05-11T10:46:04.540028Z","steps":["trace[256911710] 'process raft request' (duration: 207.32181ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:04.542422Z","caller":"traceutil/trace.go:171","msg":"trace[1861672049] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"192.269001ms","start":"2026-05-11T10:46:04.35012Z","end":"2026-05-11T10:46:04.542388Z","steps":["trace[1861672049] 'process raft request' (duration: 192.107297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.245553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325488554582995,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T10:46:29.487754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.516525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T10:46:29.48786Z","caller":"traceutil/trace.go:171","msg":"trace[551280125] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1834; }","duration":"952.684147ms","start":"2026-05-11T10:46:28.535153Z","end":"2026-05-11T10:46:29.487837Z","steps":["trace[551280125] 'count revisions from in-memory index tree' (duration: 952.413702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.487911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:28.535133Z","time spent":"952.765699ms","remote":"127.0.0.1:52148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":2,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-11T10:46:29.487882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.56359ms","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-05-11T10:46:29.487977Z","caller":"traceutil/trace.go:171","msg":"trace[1667489055] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1834; }","duration":"923.703372ms","start":"2026-05-11T10:46:28.564256Z","end":"2026-05-11T10:46:29.487959Z","steps":["trace[1667489055] 'range keys from in-memory index tree' (duration: 923.328345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.488028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:28.56423Z","time spent":"923.779743ms","remote":"127.0.0.1:37248","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":"info","ts":"2026-05-11T10:46:29.496193Z","caller":"traceutil/trace.go:171","msg":"trace[2038238389] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"751.27174ms","start":"2026-05-11T10:46:28.744891Z","end":"2026-05-11T10:46:29.496163Z","steps":["trace[2038238389] 'read index received' (duration: 751.048696ms)","trace[2038238389] 'applied index is now lower than readState.Index' (duration: 221.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:46:29.496251Z","caller":"traceutil/trace.go:171","msg":"trace[2011489492] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"872.236778ms","start":"2026-05-11T10:46:28.623969Z","end":"2026-05-11T10:46:29.496206Z","steps":["trace[2011489492] 'process raft request' (duration: 872.004364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.496708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:28.623942Z","time spent":"872.523853ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:46:29.497046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.568266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61bbfd67-fbed-4e96-8ba3-302839172b18\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-11T10:46:29.497222Z","caller":"traceutil/trace.go:171","msg":"trace[917175152] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-61bbfd67-fbed-4e96-8ba3-302839172b18; range_end:; response_count:1; response_revision:1835; }","duration":"752.368432ms","start":"2026-05-11T10:46:28.744818Z","end":"2026-05-11T10:46:29.497187Z","steps":["trace[917175152] 'agreement among raft nodes before linearized reading' (duration: 751.471254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.497298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:28.744789Z","time spent":"752.480544ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61bbfd67-fbed-4e96-8ba3-302839172b18\" "} {"level":"warn","ts":"2026-05-11T10:46:29.515075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.778374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61bbfd67-fbed-4e96-8ba3-302839172b18\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-05-11T10:46:29.51611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.187183ms","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-05-11T10:46:29.516142Z","caller":"traceutil/trace.go:171","msg":"trace[1296020647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1836; }","duration":"425.215984ms","start":"2026-05-11T10:46:29.090915Z","end":"2026-05-11T10:46:29.516131Z","steps":["trace[1296020647] 'agreement among raft nodes before linearized reading' (duration: 425.111322ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:29.516109Z","caller":"traceutil/trace.go:171","msg":"trace[354340277] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-61bbfd67-fbed-4e96-8ba3-302839172b18; range_end:; response_count:1; response_revision:1836; }","duration":"603.833693ms","start":"2026-05-11T10:46:28.912226Z","end":"2026-05-11T10:46:29.516059Z","steps":["trace[354340277] 'agreement among raft nodes before linearized reading' (duration: 602.64308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.516199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:29.09088Z","time spent":"425.305725ms","remote":"127.0.0.1:51970","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":"warn","ts":"2026-05-11T10:46:29.51625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:28.912202Z","time spent":"604.004447ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61bbfd67-fbed-4e96-8ba3-302839172b18\" "} {"level":"info","ts":"2026-05-11T10:46:29.515566Z","caller":"traceutil/trace.go:171","msg":"trace[88404871] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"216.159681ms","start":"2026-05-11T10:46:29.299354Z","end":"2026-05-11T10:46:29.515514Z","steps":["trace[88404871] 'process raft request' (duration: 214.816295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.516456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.643583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-11T10:46:29.516487Z","caller":"traceutil/trace.go:171","msg":"trace[136411718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1836; }","duration":"425.717804ms","start":"2026-05-11T10:46:29.09076Z","end":"2026-05-11T10:46:29.516478Z","steps":["trace[136411718] 'agreement among raft nodes before linearized reading' (duration: 425.604482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.515715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.784279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-05-11T10:46:29.516545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:29.090735Z","time spent":"425.799036ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T10:46:29.516589Z","caller":"traceutil/trace.go:171","msg":"trace[1110462418] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1836; }","duration":"146.652827ms","start":"2026-05-11T10:46:29.369919Z","end":"2026-05-11T10:46:29.516572Z","steps":["trace[1110462418] 'agreement among raft nodes before linearized reading' (duration: 145.692698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.515792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.523706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:46:29.516692Z","caller":"traceutil/trace.go:171","msg":"trace[1538267681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1836; }","duration":"341.478785ms","start":"2026-05-11T10:46:29.175203Z","end":"2026-05-11T10:46:29.516682Z","steps":["trace[1538267681] 'agreement among raft nodes before linearized reading' (duration: 340.559546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.516716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:29.175173Z","time spent":"341.534166ms","remote":"127.0.0.1:51716","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-05-11T10:46:29.516741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.2496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-11T10:46:29.5168Z","caller":"traceutil/trace.go:171","msg":"trace[998169150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1836; }","duration":"435.300511ms","start":"2026-05-11T10:46:29.081484Z","end":"2026-05-11T10:46:29.516784Z","steps":["trace[998169150] 'agreement among raft nodes before linearized reading' (duration: 435.166428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.516833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:29.081425Z","time spent":"435.400823ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T10:46:29.515878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.658584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T10:46:29.517424Z","caller":"traceutil/trace.go:171","msg":"trace[842017451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1836; }","duration":"424.169594ms","start":"2026-05-11T10:46:29.093212Z","end":"2026-05-11T10:46:29.517382Z","steps":["trace[842017451] 'agreement among raft nodes before linearized reading' (duration: 422.594823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.517577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:29.093161Z","time spent":"424.351469ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T10:46:29.515987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.025221ms","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-05-11T10:46:29.51993Z","caller":"traceutil/trace.go:171","msg":"trace[1273832375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1836; }","duration":"429.023328ms","start":"2026-05-11T10:46:29.090893Z","end":"2026-05-11T10:46:29.519917Z","steps":["trace[1273832375] 'agreement among raft nodes before linearized reading' (duration: 424.99902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.519964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:29.090851Z","time spent":"429.10222ms","remote":"127.0.0.1:51970","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":"info","ts":"2026-05-11T10:46:29.649553Z","caller":"traceutil/trace.go:171","msg":"trace[1191376451] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"123.913417ms","start":"2026-05-11T10:46:29.525588Z","end":"2026-05-11T10:46:29.649501Z","steps":["trace[1191376451] 'process raft request' (duration: 121.927938ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:29.649613Z","caller":"traceutil/trace.go:171","msg":"trace[1552321603] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"123.955147ms","start":"2026-05-11T10:46:29.525636Z","end":"2026-05-11T10:46:29.649591Z","steps":["trace[1552321603] 'process raft request' (duration: 122.139702ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:29.650133Z","caller":"traceutil/trace.go:171","msg":"trace[1096768050] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"124.425716ms","start":"2026-05-11T10:46:29.52566Z","end":"2026-05-11T10:46:29.650086Z","steps":["trace[1096768050] 'process raft request' (duration: 122.161832ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:29.649547Z","caller":"traceutil/trace.go:171","msg":"trace[1074478141] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"124.780764ms","start":"2026-05-11T10:46:29.524592Z","end":"2026-05-11T10:46:29.649373Z","steps":["trace[1074478141] 'process raft request' (duration: 116.017755ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:29.650598Z","caller":"traceutil/trace.go:171","msg":"trace[25669635] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"124.832034ms","start":"2026-05-11T10:46:29.525731Z","end":"2026-05-11T10:46:29.650563Z","steps":["trace[25669635] 'process raft request' (duration: 123.855375ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:29.651129Z","caller":"traceutil/trace.go:171","msg":"trace[822008647] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1912; }","duration":"100.943042ms","start":"2026-05-11T10:46:29.55012Z","end":"2026-05-11T10:46:29.651063Z","steps":["trace[822008647] 'read index received' (duration: 7.063696ms)","trace[822008647] 'applied index is now lower than readState.Index' (duration: 93.670522ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:46:29.651159Z","caller":"traceutil/trace.go:171","msg":"trace[1371422317] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"122.949698ms","start":"2026-05-11T10:46:29.528167Z","end":"2026-05-11T10:46:29.651117Z","steps":["trace[1371422317] 'process raft request' (duration: 121.596572ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:29.651525Z","caller":"traceutil/trace.go:171","msg":"trace[1397490834] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"124.774404ms","start":"2026-05-11T10:46:29.526721Z","end":"2026-05-11T10:46:29.651495Z","steps":["trace[1397490834] 'process raft request' (duration: 122.939488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:29.651576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.435401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-11T10:46:29.652316Z","caller":"traceutil/trace.go:171","msg":"trace[2024346625] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1844; }","duration":"102.193466ms","start":"2026-05-11T10:46:29.550105Z","end":"2026-05-11T10:46:29.652298Z","steps":["trace[2024346625] 'agreement among raft nodes before linearized reading' (duration: 101.108735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:30.629364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.630178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:46:30.629612Z","caller":"traceutil/trace.go:171","msg":"trace[258954172] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"677.8978ms","start":"2026-05-11T10:46:29.951678Z","end":"2026-05-11T10:46:30.629575Z","steps":["trace[258954172] 'process raft request' (duration: 372.909046ms)","trace[258954172] 'compare' (duration: 304.20323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:46:30.629741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:29.951655Z","time spent":"678.014003ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:46:30.630166Z","caller":"traceutil/trace.go:171","msg":"trace[1923253606] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"489.647999ms","start":"2026-05-11T10:46:30.140459Z","end":"2026-05-11T10:46:30.630107Z","steps":["trace[1923253606] 'process raft request' (duration: 489.065598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:30.630257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:30.140425Z","time spent":"489.788711ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:46:30.630628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.199014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:46:30.630769Z","caller":"traceutil/trace.go:171","msg":"trace[547930029] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1847; }","duration":"651.437278ms","start":"2026-05-11T10:46:29.979306Z","end":"2026-05-11T10:46:30.630744Z","steps":["trace[547930029] 'agreement among raft nodes before linearized reading' (duration: 650.97059ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:30.630182Z","caller":"traceutil/trace.go:171","msg":"trace[1225723037] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"650.787116ms","start":"2026-05-11T10:46:29.979326Z","end":"2026-05-11T10:46:30.630113Z","steps":["trace[1225723037] 'read index received' (duration: 345.363173ms)","trace[1225723037] 'applied index is now lower than readState.Index' (duration: 305.421403ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:46:30.630819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:29.97929Z","time spent":"651.51489ms","remote":"127.0.0.1:47600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-11T10:46:30.63129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.818311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:46:30.631343Z","caller":"traceutil/trace.go:171","msg":"trace[156687628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1847; }","duration":"454.917813ms","start":"2026-05-11T10:46:30.17641Z","end":"2026-05-11T10:46:30.631328Z","steps":["trace[156687628] 'agreement among raft nodes before linearized reading' (duration: 454.833432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:30.631432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.49991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61bbfd67-fbed-4e96-8ba3-302839172b18\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-05-11T10:46:30.631466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.133085ms","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-05-11T10:46:30.631483Z","caller":"traceutil/trace.go:171","msg":"trace[1364081628] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-61bbfd67-fbed-4e96-8ba3-302839172b18; range_end:; response_count:1; response_revision:1847; }","duration":"112.576832ms","start":"2026-05-11T10:46:30.518892Z","end":"2026-05-11T10:46:30.631469Z","steps":["trace[1364081628] 'agreement among raft nodes before linearized reading' (duration: 112.401819ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:46:30.631543Z","caller":"traceutil/trace.go:171","msg":"trace[56777885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1847; }","duration":"134.207347ms","start":"2026-05-11T10:46:30.497291Z","end":"2026-05-11T10:46:30.631498Z","steps":["trace[56777885] 'agreement among raft nodes before linearized reading' (duration: 134.039843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:30.631475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:30.176385Z","time spent":"455.077386ms","remote":"127.0.0.1:51716","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-05-11T10:46:30.631815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.339329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:46:30.631976Z","caller":"traceutil/trace.go:171","msg":"trace[1707174053] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1847; }","duration":"333.573723ms","start":"2026-05-11T10:46:30.298381Z","end":"2026-05-11T10:46:30.631954Z","steps":["trace[1707174053] 'agreement among raft nodes before linearized reading' (duration: 333.344789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:30.632103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:30.298353Z","time spent":"333.731576ms","remote":"127.0.0.1:60660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-05-11T10:46:45.427168Z","caller":"traceutil/trace.go:171","msg":"trace[1316281348] linearizableReadLoop","detail":"{readStateIndex:2053; appliedIndex:2052; }","duration":"366.91062ms","start":"2026-05-11T10:46:45.060232Z","end":"2026-05-11T10:46:45.427142Z","steps":["trace[1316281348] 'read index received' (duration: 363.260767ms)","trace[1316281348] 'applied index is now lower than readState.Index' (duration: 3.648273ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:46:45.427305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.062802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T10:46:45.427346Z","caller":"traceutil/trace.go:171","msg":"trace[45176860] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1973; }","duration":"367.157124ms","start":"2026-05-11T10:46:45.060176Z","end":"2026-05-11T10:46:45.427333Z","steps":["trace[45176860] 'agreement among raft nodes before linearized reading' (duration: 367.059112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:46:45.427388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:46:45.060155Z","time spent":"367.220245ms","remote":"127.0.0.1:60672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":13,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-11T10:46:45.441224Z","caller":"traceutil/trace.go:171","msg":"trace[2047802921] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"252.634467ms","start":"2026-05-11T10:46:45.188574Z","end":"2026-05-11T10:46:45.441209Z","steps":["trace[2047802921] 'process raft request' (duration: 252.452924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:09.28207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.432247ms","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-05-11T10:47:09.282156Z","caller":"traceutil/trace.go:171","msg":"trace[1728278435] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2111; }","duration":"374.547059ms","start":"2026-05-11T10:47:08.907576Z","end":"2026-05-11T10:47:09.282123Z","steps":["trace[1728278435] 'range keys from in-memory index tree' (duration: 374.263424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:09.282184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:08.907557Z","time spent":"374.6203ms","remote":"127.0.0.1:37248","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":"info","ts":"2026-05-11T10:47:09.959229Z","caller":"traceutil/trace.go:171","msg":"trace[1125510004] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"304.33574ms","start":"2026-05-11T10:47:09.654855Z","end":"2026-05-11T10:47:09.959191Z","steps":["trace[1125510004] 'process raft request' (duration: 304.118577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:09.959458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:09.654825Z","time spent":"304.511693ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:47:10.061385Z","caller":"traceutil/trace.go:171","msg":"trace[1406117579] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2195; }","duration":"321.099918ms","start":"2026-05-11T10:47:09.740251Z","end":"2026-05-11T10:47:10.06135Z","steps":["trace[1406117579] 'read index received' (duration: 218.711321ms)","trace[1406117579] 'applied index is now lower than readState.Index' (duration: 102.387407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:47:10.061952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.093194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-11T10:47:10.062004Z","caller":"traceutil/trace.go:171","msg":"trace[1884853389] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:2113; }","duration":"280.235846ms","start":"2026-05-11T10:47:09.781753Z","end":"2026-05-11T10:47:10.061989Z","steps":["trace[1884853389] 'agreement among raft nodes before linearized reading' (duration: 280.009983ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:47:10.061986Z","caller":"traceutil/trace.go:171","msg":"trace[2026564144] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"385.525401ms","start":"2026-05-11T10:47:09.676373Z","end":"2026-05-11T10:47:10.061899Z","steps":["trace[2026564144] 'process raft request' (duration: 376.0474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.062084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.914446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T10:47:10.062175Z","caller":"traceutil/trace.go:171","msg":"trace[707317485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2113; }","duration":"168.11592ms","start":"2026-05-11T10:47:09.894038Z","end":"2026-05-11T10:47:10.062154Z","steps":["trace[707317485] 'agreement among raft nodes before linearized reading' (duration: 167.836435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.062295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:09.676319Z","time spent":"385.826315ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:47:10.062329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.002607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T10:47:10.062362Z","caller":"traceutil/trace.go:171","msg":"trace[1125878569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2113; }","duration":"190.100108ms","start":"2026-05-11T10:47:09.872253Z","end":"2026-05-11T10:47:10.062353Z","steps":["trace[1125878569] 'agreement among raft nodes before linearized reading' (duration: 190.003257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.062392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.472234ms","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-05-11T10:47:10.062436Z","caller":"traceutil/trace.go:171","msg":"trace[234757389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2113; }","duration":"164.535855ms","start":"2026-05-11T10:47:09.897889Z","end":"2026-05-11T10:47:10.062425Z","steps":["trace[234757389] 'agreement among raft nodes before linearized reading' (duration: 164.423374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.062534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.90152ms","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-05-11T10:47:10.062635Z","caller":"traceutil/trace.go:171","msg":"trace[1769944498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2113; }","duration":"173.041972ms","start":"2026-05-11T10:47:09.88957Z","end":"2026-05-11T10:47:10.062612Z","steps":["trace[1769944498] 'agreement among raft nodes before linearized reading' (duration: 172.698207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.062586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.334636ms","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-05-11T10:47:10.06275Z","caller":"traceutil/trace.go:171","msg":"trace[907480935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2113; }","duration":"322.485077ms","start":"2026-05-11T10:47:09.740212Z","end":"2026-05-11T10:47:10.062697Z","steps":["trace[907480935] 'agreement among raft nodes before linearized reading' (duration: 321.353151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.062794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:09.740191Z","time spent":"322.591019ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T10:47:10.063046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.455883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T10:47:10.063162Z","caller":"traceutil/trace.go:171","msg":"trace[236177232] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2113; }","duration":"182.606947ms","start":"2026-05-11T10:47:09.880531Z","end":"2026-05-11T10:47:10.063138Z","steps":["trace[236177232] 'agreement among raft nodes before linearized reading' (duration: 182.319012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.933399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.119824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T10:47:10.933482Z","caller":"traceutil/trace.go:171","msg":"trace[239926010] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2120; }","duration":"772.234645ms","start":"2026-05-11T10:47:10.161229Z","end":"2026-05-11T10:47:10.933464Z","steps":["trace[239926010] 'range keys from in-memory index tree' (duration: 771.969382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.933525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:10.161096Z","time spent":"772.420348ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-11T10:47:10.933825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.958308ms","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-05-11T10:47:10.933871Z","caller":"traceutil/trace.go:171","msg":"trace[1191748414] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2120; }","duration":"644.109641ms","start":"2026-05-11T10:47:10.289749Z","end":"2026-05-11T10:47:10.933858Z","steps":["trace[1191748414] 'range keys from in-memory index tree' (duration: 643.803646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.933902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:10.289724Z","time spent":"644.168731ms","remote":"127.0.0.1:37248","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-05-11T10:47:10.934206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.82249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:47:10.934315Z","caller":"traceutil/trace.go:171","msg":"trace[1813822084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2120; }","duration":"761.965773ms","start":"2026-05-11T10:47:10.172325Z","end":"2026-05-11T10:47:10.934291Z","steps":["trace[1813822084] 'range keys from in-memory index tree' (duration: 761.75933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:10.934371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:10.172317Z","time spent":"762.043004ms","remote":"127.0.0.1:51716","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-05-11T10:47:10.934378Z","caller":"traceutil/trace.go:171","msg":"trace[1711251522] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"103.419279ms","start":"2026-05-11T10:47:10.830923Z","end":"2026-05-11T10:47:10.934342Z","steps":["trace[1711251522] 'process raft request' (duration: 103.173234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:11.490316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.251816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T10:47:11.490486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.246994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T10:47:11.490599Z","caller":"traceutil/trace.go:171","msg":"trace[11590302] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2121; }","duration":"175.508868ms","start":"2026-05-11T10:47:11.315068Z","end":"2026-05-11T10:47:11.490577Z","steps":["trace[11590302] 'count revisions from in-memory index tree' (duration: 175.143222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:11.490499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.636408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T10:47:11.490713Z","caller":"traceutil/trace.go:171","msg":"trace[1864437457] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2121; }","duration":"252.877921ms","start":"2026-05-11T10:47:11.237815Z","end":"2026-05-11T10:47:11.490693Z","steps":["trace[1864437457] 'count revisions from in-memory index tree' (duration: 252.527507ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:47:11.490401Z","caller":"traceutil/trace.go:171","msg":"trace[1806576293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2121; }","duration":"313.406918ms","start":"2026-05-11T10:47:11.176973Z","end":"2026-05-11T10:47:11.49038Z","steps":["trace[1806576293] 'range keys from in-memory index tree' (duration: 313.126793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:11.491324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:11.176937Z","time spent":"314.371421ms","remote":"127.0.0.1:51716","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-05-11T10:47:12.29408Z","caller":"traceutil/trace.go:171","msg":"trace[2078856382] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"328.452018ms","start":"2026-05-11T10:47:11.965603Z","end":"2026-05-11T10:47:12.294055Z","steps":["trace[2078856382] 'process raft request' (duration: 328.250595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:12.294259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:47:11.965586Z","time spent":"328.56997ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:47:12.302488Z","caller":"traceutil/trace.go:171","msg":"trace[1029766798] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2206; }","duration":"164.163738ms","start":"2026-05-11T10:47:12.138301Z","end":"2026-05-11T10:47:12.302464Z","steps":["trace[1029766798] 'read index received' (duration: 155.657732ms)","trace[1029766798] 'applied index is now lower than readState.Index' (duration: 8.504496ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:47:12.302586Z","caller":"traceutil/trace.go:171","msg":"trace[2077474581] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"234.167928ms","start":"2026-05-11T10:47:12.068395Z","end":"2026-05-11T10:47:12.302563Z","steps":["trace[2077474581] 'process raft request' (duration: 228.929021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:12.302735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.422771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18ae7d1189502055\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-11T10:47:12.302801Z","caller":"traceutil/trace.go:171","msg":"trace[633804534] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18ae7d1189502055; range_end:; response_count:1; response_revision:2123; }","duration":"164.538583ms","start":"2026-05-11T10:47:12.138243Z","end":"2026-05-11T10:47:12.302781Z","steps":["trace[633804534] 'agreement among raft nodes before linearized reading' (duration: 164.34739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:12.303565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.919827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T10:47:12.303666Z","caller":"traceutil/trace.go:171","msg":"trace[123116507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2123; }","duration":"152.066638ms","start":"2026-05-11T10:47:12.151576Z","end":"2026-05-11T10:47:12.303642Z","steps":["trace[123116507] 'agreement among raft nodes before linearized reading' (duration: 151.752584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:12.303953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.260057ms","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-05-11T10:47:12.304014Z","caller":"traceutil/trace.go:171","msg":"trace[750071792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2123; }","duration":"151.341318ms","start":"2026-05-11T10:47:12.152659Z","end":"2026-05-11T10:47:12.304Z","steps":["trace[750071792] 'agreement among raft nodes before linearized reading' (duration: 151.120834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:12.303991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.551091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-11T10:47:12.304061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.83766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T10:47:12.304121Z","caller":"traceutil/trace.go:171","msg":"trace[414673249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2123; }","duration":"151.698923ms","start":"2026-05-11T10:47:12.152408Z","end":"2026-05-11T10:47:12.304107Z","steps":["trace[414673249] 'agreement among raft nodes before linearized reading' (duration: 151.4812ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:47:12.304168Z","caller":"traceutil/trace.go:171","msg":"trace[1893150627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2123; }","duration":"150.996153ms","start":"2026-05-11T10:47:12.153149Z","end":"2026-05-11T10:47:12.304145Z","steps":["trace[1893150627] 'agreement among raft nodes before linearized reading' (duration: 150.599066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:12.304352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.487587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T10:47:12.30437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.11254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T10:47:12.304407Z","caller":"traceutil/trace.go:171","msg":"trace[1722728694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2123; }","duration":"129.536428ms","start":"2026-05-11T10:47:12.174852Z","end":"2026-05-11T10:47:12.304388Z","steps":["trace[1722728694] 'agreement among raft nodes before linearized reading' (duration: 129.466127ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:47:12.304416Z","caller":"traceutil/trace.go:171","msg":"trace[318066088] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2123; }","duration":"152.215771ms","start":"2026-05-11T10:47:12.152187Z","end":"2026-05-11T10:47:12.304402Z","steps":["trace[318066088] 'agreement among raft nodes before linearized reading' (duration: 152.09483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:47:12.304414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.725124ms","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-05-11T10:47:12.304567Z","caller":"traceutil/trace.go:171","msg":"trace[1334804116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2123; }","duration":"149.905457ms","start":"2026-05-11T10:47:12.154652Z","end":"2026-05-11T10:47:12.304557Z","steps":["trace[1334804116] 'agreement among raft nodes before linearized reading' (duration: 149.675764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:06.40643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.02784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-11T10:48:06.406545Z","caller":"traceutil/trace.go:171","msg":"trace[624287568] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2461; }","duration":"186.216702ms","start":"2026-05-11T10:48:06.220304Z","end":"2026-05-11T10:48:06.40652Z","steps":["trace[624287568] 'range keys from in-memory index tree' (duration: 185.645395ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:14.756045Z","caller":"traceutil/trace.go:171","msg":"trace[601283811] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"190.512198ms","start":"2026-05-11T10:48:14.565513Z","end":"2026-05-11T10:48:14.756025Z","steps":["trace[601283811] 'process raft request' (duration: 190.375516ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:14.758071Z","caller":"traceutil/trace.go:171","msg":"trace[1167032716] linearizableReadLoop","detail":"{readStateIndex:2660; appliedIndex:2658; }","duration":"106.554442ms","start":"2026-05-11T10:48:14.651493Z","end":"2026-05-11T10:48:14.758047Z","steps":["trace[1167032716] 'read index received' (duration: 104.398636ms)","trace[1167032716] 'applied index is now lower than readState.Index' (duration: 2.151896ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:48:14.758287Z","caller":"traceutil/trace.go:171","msg":"trace[1423945814] transaction","detail":"{read_only:false; response_revision:2561; number_of_response:1; }","duration":"192.562252ms","start":"2026-05-11T10:48:14.565715Z","end":"2026-05-11T10:48:14.758277Z","steps":["trace[1423945814] 'process raft request' (duration: 192.203488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:14.758507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.002647ms","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-05-11T10:48:14.758546Z","caller":"traceutil/trace.go:171","msg":"trace[819823547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2561; }","duration":"107.081477ms","start":"2026-05-11T10:48:14.651454Z","end":"2026-05-11T10:48:14.758536Z","steps":["trace[819823547] 'agreement among raft nodes before linearized reading' (duration: 106.956226ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:15.573801Z","caller":"traceutil/trace.go:171","msg":"trace[573231315] linearizableReadLoop","detail":"{readStateIndex:2669; appliedIndex:2668; }","duration":"430.485475ms","start":"2026-05-11T10:48:15.14329Z","end":"2026-05-11T10:48:15.573776Z","steps":["trace[573231315] 'read index received' (duration: 422.504131ms)","trace[573231315] 'applied index is now lower than readState.Index' (duration: 7.980004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:48:15.574004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.683197ms","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":"info","ts":"2026-05-11T10:48:15.574043Z","caller":"traceutil/trace.go:171","msg":"trace[1349982287] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2569; }","duration":"430.782568ms","start":"2026-05-11T10:48:15.143248Z","end":"2026-05-11T10:48:15.574031Z","steps":["trace[1349982287] 'agreement among raft nodes before linearized reading' (duration: 430.659937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:15.574087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:15.143231Z","time spent":"430.84497ms","remote":"127.0.0.1:57696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-05-11T10:48:15.577584Z","caller":"traceutil/trace.go:171","msg":"trace[1931044890] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"375.769083ms","start":"2026-05-11T10:48:15.201788Z","end":"2026-05-11T10:48:15.577557Z","steps":["trace[1931044890] 'process raft request' (duration: 375.567671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:15.577593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.553187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:48:15.577677Z","caller":"traceutil/trace.go:171","msg":"trace[961705430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2570; }","duration":"402.666328ms","start":"2026-05-11T10:48:15.174993Z","end":"2026-05-11T10:48:15.577659Z","steps":["trace[961705430] 'agreement among raft nodes before linearized reading' (duration: 402.520367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:15.577706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:15.201768Z","time spent":"375.872334ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:48:15.577739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:15.174984Z","time spent":"402.745599ms","remote":"127.0.0.1:51732","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-05-11T10:48:47.953816Z","caller":"traceutil/trace.go:171","msg":"trace[564862854] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"242.592763ms","start":"2026-05-11T10:48:47.711203Z","end":"2026-05-11T10:48:47.953796Z","steps":["trace[564862854] 'process raft request' (duration: 242.454631ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:48.0228Z","caller":"traceutil/trace.go:171","msg":"trace[238765826] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"199.71918ms","start":"2026-05-11T10:48:47.823041Z","end":"2026-05-11T10:48:48.02276Z","steps":["trace[238765826] 'process raft request' (duration: 195.367823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.331581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155929502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:48:49.331757Z","caller":"traceutil/trace.go:171","msg":"trace[846923673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2748; }","duration":"1.156177154s","start":"2026-05-11T10:48:48.17555Z","end":"2026-05-11T10:48:49.331727Z","steps":["trace[846923673] 'range keys from in-memory index tree' (duration: 1.155794481s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.332267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.139018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:48:49.332636Z","caller":"traceutil/trace.go:171","msg":"trace[226515506] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"413.19036ms","start":"2026-05-11T10:48:48.919418Z","end":"2026-05-11T10:48:49.332609Z","steps":["trace[226515506] 'process raft request' (duration: 413.045669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.332723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.919384Z","time spent":"413.302902ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:48:49.333211Z","caller":"traceutil/trace.go:171","msg":"trace[1570303370] linearizableReadLoop","detail":"{readStateIndex:2856; appliedIndex:2855; }","duration":"964.144831ms","start":"2026-05-11T10:48:48.369051Z","end":"2026-05-11T10:48:49.333196Z","steps":["trace[1570303370] 'read index received' (duration: 451.932861ms)","trace[1570303370] 'applied index is now lower than readState.Index' (duration: 512.20999ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:48:49.333183Z","caller":"traceutil/trace.go:171","msg":"trace[1068019143] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"1.007297926s","start":"2026-05-11T10:48:48.325609Z","end":"2026-05-11T10:48:49.332907Z","steps":["trace[1068019143] 'process raft request' (duration: 495.319159ms)","trace[1068019143] 'compare' (duration: 510.294849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:48:49.331816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.175519Z","time spent":"1.156283726s","remote":"127.0.0.1:51716","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-05-11T10:48:49.333359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.307103ms","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-05-11T10:48:49.333391Z","caller":"traceutil/trace.go:171","msg":"trace[324419158] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2750; }","duration":"964.377313ms","start":"2026-05-11T10:48:48.369001Z","end":"2026-05-11T10:48:49.333379Z","steps":["trace[324419158] 'agreement among raft nodes before linearized reading' (duration: 964.280042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.333424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.368988Z","time spent":"964.427625ms","remote":"127.0.0.1:37248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-11T10:48:49.333412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.325575Z","time spent":"1.007711871s","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:48:49.342875Z","caller":"traceutil/trace.go:171","msg":"trace[423437309] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"423.310372ms","start":"2026-05-11T10:48:48.919537Z","end":"2026-05-11T10:48:49.342847Z","steps":["trace[423437309] 'process raft request' (duration: 423.081108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.343153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.919491Z","time spent":"423.476634ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:48:49.343652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.349305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-11T10:48:49.343665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.869618ms","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-05-11T10:48:49.343683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.495315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T10:48:49.34371Z","caller":"traceutil/trace.go:171","msg":"trace[122047931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2751; }","duration":"376.457957ms","start":"2026-05-11T10:48:48.967237Z","end":"2026-05-11T10:48:49.343695Z","steps":["trace[122047931] 'agreement among raft nodes before linearized reading' (duration: 376.271775ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:49.343719Z","caller":"traceutil/trace.go:171","msg":"trace[648022671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2751; }","duration":"363.990849ms","start":"2026-05-11T10:48:48.979716Z","end":"2026-05-11T10:48:49.343707Z","steps":["trace[648022671] 'agreement among raft nodes before linearized reading' (duration: 363.802767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.34374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.967213Z","time spent":"376.521367ms","remote":"127.0.0.1:51970","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-05-11T10:48:49.343747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.979708Z","time spent":"364.03324ms","remote":"127.0.0.1:51970","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-05-11T10:48:49.34378Z","caller":"traceutil/trace.go:171","msg":"trace[772188184] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2751; }","duration":"364.615325ms","start":"2026-05-11T10:48:48.979119Z","end":"2026-05-11T10:48:49.343734Z","steps":["trace[772188184] 'agreement among raft nodes before linearized reading' (duration: 364.428493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.343857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.979091Z","time spent":"364.729866ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-11T10:48:49.343976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.243813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-11T10:48:49.34403Z","caller":"traceutil/trace.go:171","msg":"trace[889247846] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2751; }","duration":"223.347664ms","start":"2026-05-11T10:48:49.120674Z","end":"2026-05-11T10:48:49.344022Z","steps":["trace[889247846] 'agreement among raft nodes before linearized reading' (duration: 223.163202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.344154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.115777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-11T10:48:49.344181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.449155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ae7d26c970b8fd\" ","response":"range_response_count:1 size:781"} {"level":"warn","ts":"2026-05-11T10:48:49.344315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.477929ms","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-05-11T10:48:49.344439Z","caller":"traceutil/trace.go:171","msg":"trace[282303820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2751; }","duration":"379.765042ms","start":"2026-05-11T10:48:48.964662Z","end":"2026-05-11T10:48:49.344427Z","steps":["trace[282303820] 'agreement among raft nodes before linearized reading' (duration: 378.866832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.344583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.964651Z","time spent":"379.916693ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T10:48:49.344367Z","caller":"traceutil/trace.go:171","msg":"trace[185893640] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ae7d26c970b8fd; range_end:; response_count:1; response_revision:2751; }","duration":"934.665337ms","start":"2026-05-11T10:48:48.409619Z","end":"2026-05-11T10:48:49.344285Z","steps":["trace[185893640] 'agreement among raft nodes before linearized reading' (duration: 934.304963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.344979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.409594Z","time spent":"935.373115ms","remote":"127.0.0.1:51768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18ae7d26c970b8fd\" "} {"level":"info","ts":"2026-05-11T10:48:49.344196Z","caller":"traceutil/trace.go:171","msg":"trace[743219226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2751; }","duration":"401.178948ms","start":"2026-05-11T10:48:48.943006Z","end":"2026-05-11T10:48:49.344185Z","steps":["trace[743219226] 'agreement among raft nodes before linearized reading' (duration: 400.982036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.345283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.942996Z","time spent":"402.24407ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T10:48:49.344355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.958738ms","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-05-11T10:48:49.345484Z","caller":"traceutil/trace.go:171","msg":"trace[1722699119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2751; }","duration":"404.11609ms","start":"2026-05-11T10:48:48.941355Z","end":"2026-05-11T10:48:49.345471Z","steps":["trace[1722699119] 'agreement among raft nodes before linearized reading' (duration: 402.899757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.345561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:48:48.941341Z","time spent":"404.207652ms","remote":"127.0.0.1:51970","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-05-11T10:48:49.473438Z","caller":"traceutil/trace.go:171","msg":"trace[1706973636] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"124.888262ms","start":"2026-05-11T10:48:49.348511Z","end":"2026-05-11T10:48:49.4734Z","steps":["trace[1706973636] 'process raft request' (duration: 96.362078ms)","trace[1706973636] 'compare' (duration: 28.264291ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:48:49.474284Z","caller":"traceutil/trace.go:171","msg":"trace[623696913] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"125.68012ms","start":"2026-05-11T10:48:49.348573Z","end":"2026-05-11T10:48:49.474253Z","steps":["trace[623696913] 'process raft request' (duration: 124.75934ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:49.474576Z","caller":"traceutil/trace.go:171","msg":"trace[2050100604] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"124.336486ms","start":"2026-05-11T10:48:49.350226Z","end":"2026-05-11T10:48:49.474562Z","steps":["trace[2050100604] 'process raft request' (duration: 123.665128ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:49.475135Z","caller":"traceutil/trace.go:171","msg":"trace[1053826279] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"123.673409ms","start":"2026-05-11T10:48:49.351423Z","end":"2026-05-11T10:48:49.475096Z","steps":["trace[1053826279] 'process raft request' (duration: 123.051222ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:49.47562Z","caller":"traceutil/trace.go:171","msg":"trace[508316975] linearizableReadLoop","detail":"{readStateIndex:2862; appliedIndex:2858; }","duration":"124.281195ms","start":"2026-05-11T10:48:49.351308Z","end":"2026-05-11T10:48:49.475589Z","steps":["trace[508316975] 'read index received' (duration: 7.723964ms)","trace[508316975] 'applied index is now lower than readState.Index' (duration: 116.555951ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:48:49.475764Z","caller":"traceutil/trace.go:171","msg":"trace[741268373] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"122.767269ms","start":"2026-05-11T10:48:49.352976Z","end":"2026-05-11T10:48:49.475743Z","steps":["trace[741268373] 'process raft request' (duration: 121.996941ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:48:49.475825Z","caller":"traceutil/trace.go:171","msg":"trace[226579259] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"122.411605ms","start":"2026-05-11T10:48:49.353405Z","end":"2026-05-11T10:48:49.475816Z","steps":["trace[226579259] 'process raft request' (duration: 122.012001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.475912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.575648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1445"} {"level":"info","ts":"2026-05-11T10:48:49.47685Z","caller":"traceutil/trace.go:171","msg":"trace[926038943] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2758; }","duration":"125.553588ms","start":"2026-05-11T10:48:49.351275Z","end":"2026-05-11T10:48:49.476829Z","steps":["trace[926038943] 'agreement among raft nodes before linearized reading' (duration: 124.417246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:48:49.476973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.901139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:48:49.478367Z","caller":"traceutil/trace.go:171","msg":"trace[1369172728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2758; }","duration":"124.337185ms","start":"2026-05-11T10:48:49.354017Z","end":"2026-05-11T10:48:49.478354Z","steps":["trace[1369172728] 'agreement among raft nodes before linearized reading' (duration: 122.883669ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:43.937667Z","caller":"traceutil/trace.go:171","msg":"trace[567050204] linearizableReadLoop","detail":"{readStateIndex:3157; appliedIndex:3155; }","duration":"136.548689ms","start":"2026-05-11T10:49:43.801086Z","end":"2026-05-11T10:49:43.937635Z","steps":["trace[567050204] 'read index received' (duration: 41.96257ms)","trace[567050204] 'applied index is now lower than readState.Index' (duration: 94.584599ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:49:43.937774Z","caller":"traceutil/trace.go:171","msg":"trace[214089558] transaction","detail":"{read_only:false; response_revision:3039; number_of_response:1; }","duration":"135.527516ms","start":"2026-05-11T10:49:43.802206Z","end":"2026-05-11T10:49:43.937733Z","steps":["trace[214089558] 'process raft request' (duration: 135.351151ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:43.937723Z","caller":"traceutil/trace.go:171","msg":"trace[234252756] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"138.759879ms","start":"2026-05-11T10:49:43.798911Z","end":"2026-05-11T10:49:43.937671Z","steps":["trace[234252756] 'process raft request' (duration: 83.488137ms)","trace[234252756] 'compare' (duration: 54.977733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:49:43.938107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.982493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T10:49:43.938205Z","caller":"traceutil/trace.go:171","msg":"trace[183663039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3039; }","duration":"137.100006ms","start":"2026-05-11T10:49:43.801083Z","end":"2026-05-11T10:49:43.938183Z","steps":["trace[183663039] 'agreement among raft nodes before linearized reading' (duration: 136.691334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:43.93834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.69057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T10:49:43.938418Z","caller":"traceutil/trace.go:171","msg":"trace[820809579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3039; }","duration":"134.913417ms","start":"2026-05-11T10:49:43.803482Z","end":"2026-05-11T10:49:43.938396Z","steps":["trace[820809579] 'agreement among raft nodes before linearized reading' (duration: 134.508664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:43.939217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.523127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T10:49:43.9395Z","caller":"traceutil/trace.go:171","msg":"trace[2111949080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3039; }","duration":"130.824888ms","start":"2026-05-11T10:49:43.808652Z","end":"2026-05-11T10:49:43.939476Z","steps":["trace[2111949080] 'agreement among raft nodes before linearized reading' (duration: 129.462264ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:46.222553Z","caller":"traceutil/trace.go:171","msg":"trace[1557401246] transaction","detail":"{read_only:false; response_revision:3054; number_of_response:1; }","duration":"121.052497ms","start":"2026-05-11T10:49:46.101462Z","end":"2026-05-11T10:49:46.222514Z","steps":["trace[1557401246] 'process raft request' (duration: 75.812022ms)","trace[1557401246] 'compare' (duration: 44.916554ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:49:48.093818Z","caller":"traceutil/trace.go:171","msg":"trace[1117198197] transaction","detail":"{read_only:false; response_revision:3060; number_of_response:1; }","duration":"122.312396ms","start":"2026-05-11T10:49:47.971471Z","end":"2026-05-11T10:49:48.093783Z","steps":["trace[1117198197] 'process raft request' (duration: 122.095099ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:48.093755Z","caller":"traceutil/trace.go:171","msg":"trace[1408718894] linearizableReadLoop","detail":"{readStateIndex:3180; appliedIndex:3179; }","duration":"109.613461ms","start":"2026-05-11T10:49:47.984118Z","end":"2026-05-11T10:49:48.093732Z","steps":["trace[1408718894] 'read index received' (duration: 109.427945ms)","trace[1408718894] 'applied index is now lower than readState.Index' (duration: 184.336µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:49:48.094096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.960622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T10:49:48.094181Z","caller":"traceutil/trace.go:171","msg":"trace[181331571] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3060; }","duration":"110.086926ms","start":"2026-05-11T10:49:47.98407Z","end":"2026-05-11T10:49:48.094157Z","steps":["trace[181331571] 'agreement among raft nodes before linearized reading' (duration: 109.814148ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:50.239451Z","caller":"traceutil/trace.go:171","msg":"trace[1929493081] linearizableReadLoop","detail":"{readStateIndex:3192; appliedIndex:3191; }","duration":"114.028113ms","start":"2026-05-11T10:49:50.1254Z","end":"2026-05-11T10:49:50.239428Z","steps":["trace[1929493081] 'read index received' (duration: 113.830897ms)","trace[1929493081] 'applied index is now lower than readState.Index' (duration: 196.596µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:49:50.239594Z","caller":"traceutil/trace.go:171","msg":"trace[372342797] transaction","detail":"{read_only:false; response_revision:3071; number_of_response:1; }","duration":"136.565735ms","start":"2026-05-11T10:49:50.102983Z","end":"2026-05-11T10:49:50.239549Z","steps":["trace[372342797] 'process raft request' (duration: 136.302877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:50.239694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.274911ms","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-05-11T10:49:50.239739Z","caller":"traceutil/trace.go:171","msg":"trace[561130304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3071; }","duration":"114.376204ms","start":"2026-05-11T10:49:50.125352Z","end":"2026-05-11T10:49:50.239728Z","steps":["trace[561130304] 'agreement among raft nodes before linearized reading' (duration: 114.210588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:50.240068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.132477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T10:49:50.240204Z","caller":"traceutil/trace.go:171","msg":"trace[79872612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3071; }","duration":"114.355064ms","start":"2026-05-11T10:49:50.125824Z","end":"2026-05-11T10:49:50.240179Z","steps":["trace[79872612] 'agreement among raft nodes before linearized reading' (duration: 113.988163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:50.240312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.90699ms","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-05-11T10:49:50.240461Z","caller":"traceutil/trace.go:171","msg":"trace[56576920] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3071; }","duration":"113.193218ms","start":"2026-05-11T10:49:50.127231Z","end":"2026-05-11T10:49:50.240425Z","steps":["trace[56576920] 'agreement among raft nodes before linearized reading' (duration: 112.665472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:50.240119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.487646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T10:49:50.241266Z","caller":"traceutil/trace.go:171","msg":"trace[604550148] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3071; }","duration":"113.61423ms","start":"2026-05-11T10:49:50.127563Z","end":"2026-05-11T10:49:50.241177Z","steps":["trace[604550148] 'agreement among raft nodes before linearized reading' (duration: 112.246289ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:50.633278Z","caller":"traceutil/trace.go:171","msg":"trace[712922640] transaction","detail":"{read_only:false; response_revision:3077; number_of_response:1; }","duration":"224.162307ms","start":"2026-05-11T10:49:50.409079Z","end":"2026-05-11T10:49:50.633242Z","steps":["trace[712922640] 'process raft request' (duration: 215.274974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:58.855179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.612552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-11T10:49:58.855268Z","caller":"traceutil/trace.go:171","msg":"trace[1003835514] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3117; }","duration":"108.761796ms","start":"2026-05-11T10:49:58.746486Z","end":"2026-05-11T10:49:58.855248Z","steps":["trace[1003835514] 'range keys from in-memory index tree' (duration: 108.387025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:04.158755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.462303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-11T10:50:04.158854Z","caller":"traceutil/trace.go:171","msg":"trace[1196214965] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3142; }","duration":"189.604456ms","start":"2026-05-11T10:50:03.969231Z","end":"2026-05-11T10:50:04.158835Z","steps":["trace[1196214965] 'range keys from in-memory index tree' (duration: 189.275247ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:04.158982Z","caller":"traceutil/trace.go:171","msg":"trace[1321125004] transaction","detail":"{read_only:false; response_revision:3143; number_of_response:1; }","duration":"158.632597ms","start":"2026-05-11T10:50:04.000086Z","end":"2026-05-11T10:50:04.158719Z","steps":["trace[1321125004] 'process raft request' (duration: 158.336878ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:04.856938Z","caller":"traceutil/trace.go:171","msg":"trace[608517803] linearizableReadLoop","detail":"{readStateIndex:3270; appliedIndex:3268; }","duration":"147.721511ms","start":"2026-05-11T10:50:04.709186Z","end":"2026-05-11T10:50:04.856908Z","steps":["trace[608517803] 'read index received' (duration: 28.890755ms)","trace[608517803] 'applied index is now lower than readState.Index' (duration: 118.829556ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:04.857113Z","caller":"traceutil/trace.go:171","msg":"trace[121440720] transaction","detail":"{read_only:false; response_revision:3147; number_of_response:1; }","duration":"163.94918ms","start":"2026-05-11T10:50:04.693122Z","end":"2026-05-11T10:50:04.857071Z","steps":["trace[121440720] 'process raft request' (duration: 132.962294ms)","trace[121440720] 'compare' (duration: 30.678417ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:50:04.857406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.62775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-05-11T10:50:04.857404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.132463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T10:50:04.8575Z","caller":"traceutil/trace.go:171","msg":"trace[1985946347] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3147; }","duration":"128.780784ms","start":"2026-05-11T10:50:04.728704Z","end":"2026-05-11T10:50:04.857485Z","steps":["trace[1985946347] 'agreement among raft nodes before linearized reading' (duration: 128.449494ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:04.857543Z","caller":"traceutil/trace.go:171","msg":"trace[1874422178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3147; }","duration":"148.37665ms","start":"2026-05-11T10:50:04.709145Z","end":"2026-05-11T10:50:04.857521Z","steps":["trace[1874422178] 'agreement among raft nodes before linearized reading' (duration: 147.980118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:04.857662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.981322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T10:50:04.857721Z","caller":"traceutil/trace.go:171","msg":"trace[123109481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3147; }","duration":"128.069264ms","start":"2026-05-11T10:50:04.729638Z","end":"2026-05-11T10:50:04.857708Z","steps":["trace[123109481] 'agreement among raft nodes before linearized reading' (duration: 127.859348ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:04.980867Z","caller":"traceutil/trace.go:171","msg":"trace[568202350] transaction","detail":"{read_only:false; response_revision:3149; number_of_response:1; }","duration":"119.010592ms","start":"2026-05-11T10:50:04.861823Z","end":"2026-05-11T10:50:04.980834Z","steps":["trace[568202350] 'process raft request' (duration: 98.665913ms)","trace[568202350] 'compare' (duration: 20.153453ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:04.981122Z","caller":"traceutil/trace.go:171","msg":"trace[971308938] transaction","detail":"{read_only:false; response_revision:3150; number_of_response:1; }","duration":"118.355713ms","start":"2026-05-11T10:50:04.862732Z","end":"2026-05-11T10:50:04.981088Z","steps":["trace[971308938] 'process raft request' (duration: 118.034444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:05.237402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.873091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-11T10:50:05.237492Z","caller":"traceutil/trace.go:171","msg":"trace[784532384] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3150; }","duration":"129.016425ms","start":"2026-05-11T10:50:05.108453Z","end":"2026-05-11T10:50:05.23747Z","steps":["trace[784532384] 'range keys from in-memory index tree' (duration: 128.655034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:07.020979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.034202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:50:07.021204Z","caller":"traceutil/trace.go:171","msg":"trace[1592249240] transaction","detail":"{read_only:false; response_revision:3156; number_of_response:1; }","duration":"156.939018ms","start":"2026-05-11T10:50:06.864218Z","end":"2026-05-11T10:50:07.021157Z","steps":["trace[1592249240] 'process raft request' (duration: 38.640308ms)","trace[1592249240] 'compare' (duration: 117.892188ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:07.022417Z","caller":"traceutil/trace.go:171","msg":"trace[1822280954] transaction","detail":"{read_only:false; response_revision:3157; number_of_response:1; }","duration":"124.721114ms","start":"2026-05-11T10:50:06.897681Z","end":"2026-05-11T10:50:07.022402Z","steps":["trace[1822280954] 'process raft request' (duration: 123.409637ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:07.17183Z","caller":"traceutil/trace.go:171","msg":"trace[1949229425] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"144.099135ms","start":"2026-05-11T10:50:07.027714Z","end":"2026-05-11T10:50:07.171813Z","steps":["trace[1949229425] 'process raft request' (duration: 142.413206ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:07.172149Z","caller":"traceutil/trace.go:171","msg":"trace[799248241] transaction","detail":"{read_only:false; response_revision:3160; number_of_response:1; }","duration":"128.54196ms","start":"2026-05-11T10:50:07.043578Z","end":"2026-05-11T10:50:07.17212Z","steps":["trace[799248241] 'process raft request' (duration: 128.210081ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:09.364987Z","caller":"traceutil/trace.go:171","msg":"trace[1268406336] transaction","detail":"{read_only:false; response_revision:3171; number_of_response:1; }","duration":"107.751622ms","start":"2026-05-11T10:50:09.257208Z","end":"2026-05-11T10:50:09.36496Z","steps":["trace[1268406336] 'process raft request' (duration: 107.146044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:09.365415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.151286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-11T10:50:09.3655Z","caller":"traceutil/trace.go:171","msg":"trace[1999066766] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3171; }","duration":"100.264999ms","start":"2026-05-11T10:50:09.265217Z","end":"2026-05-11T10:50:09.365482Z","steps":["trace[1999066766] 'agreement among raft nodes before linearized reading' (duration: 99.368283ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:17.286042Z","caller":"traceutil/trace.go:171","msg":"trace[1552165576] transaction","detail":"{read_only:false; response_revision:3209; number_of_response:1; }","duration":"100.519064ms","start":"2026-05-11T10:50:17.185492Z","end":"2026-05-11T10:50:17.286011Z","steps":["trace[1552165576] 'process raft request' (duration: 100.310069ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:17.689128Z","caller":"traceutil/trace.go:171","msg":"trace[1844608917] transaction","detail":"{read_only:false; response_revision:3213; number_of_response:1; }","duration":"119.978459ms","start":"2026-05-11T10:50:17.569116Z","end":"2026-05-11T10:50:17.689094Z","steps":["trace[1844608917] 'process raft request' (duration: 43.704849ms)","trace[1844608917] 'compare' (duration: 76.091035ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:25.261772Z","caller":"traceutil/trace.go:171","msg":"trace[1761006659] transaction","detail":"{read_only:false; response_revision:3248; number_of_response:1; }","duration":"102.868572ms","start":"2026-05-11T10:50:25.158869Z","end":"2026-05-11T10:50:25.261738Z","steps":["trace[1761006659] 'process raft request' (duration: 102.640316ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:26.672072Z","caller":"traceutil/trace.go:171","msg":"trace[186881696] transaction","detail":"{read_only:false; response_revision:3254; number_of_response:1; }","duration":"138.840893ms","start":"2026-05-11T10:50:26.533187Z","end":"2026-05-11T10:50:26.672027Z","steps":["trace[186881696] 'process raft request' (duration: 40.946273ms)","trace[186881696] 'compare' (duration: 97.624453ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:30.06267Z","caller":"traceutil/trace.go:171","msg":"trace[1383069450] linearizableReadLoop","detail":"{readStateIndex:3399; appliedIndex:3398; }","duration":"162.768149ms","start":"2026-05-11T10:50:29.899812Z","end":"2026-05-11T10:50:30.06258Z","steps":["trace[1383069450] 'read index received' (duration: 113.850215ms)","trace[1383069450] 'applied index is now lower than readState.Index' (duration: 48.916804ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:30.0628Z","caller":"traceutil/trace.go:171","msg":"trace[328125955] transaction","detail":"{read_only:false; response_revision:3270; number_of_response:1; }","duration":"164.70197ms","start":"2026-05-11T10:50:29.898042Z","end":"2026-05-11T10:50:30.062744Z","steps":["trace[328125955] 'process raft request' (duration: 115.646063ms)","trace[328125955] 'compare' (duration: 48.692668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:50:30.06296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.560417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T10:50:30.06302Z","caller":"traceutil/trace.go:171","msg":"trace[908579550] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:3270; }","duration":"117.66481ms","start":"2026-05-11T10:50:29.945342Z","end":"2026-05-11T10:50:30.063006Z","steps":["trace[908579550] 'agreement among raft nodes before linearized reading' (duration: 117.549117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:30.063387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.533329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-11T10:50:30.063503Z","caller":"traceutil/trace.go:171","msg":"trace[324469034] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:3270; }","duration":"163.689072ms","start":"2026-05-11T10:50:29.899787Z","end":"2026-05-11T10:50:30.063476Z","steps":["trace[324469034] 'agreement among raft nodes before linearized reading' (duration: 163.434146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:30.287974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.764809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:50:30.288069Z","caller":"traceutil/trace.go:171","msg":"trace[931211743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3273; }","duration":"114.928263ms","start":"2026-05-11T10:50:30.173121Z","end":"2026-05-11T10:50:30.288049Z","steps":["trace[931211743] 'range keys from in-memory index tree' (duration: 114.698427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:30.288264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.360038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9280"} {"level":"info","ts":"2026-05-11T10:50:30.28839Z","caller":"traceutil/trace.go:171","msg":"trace[1286793839] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:3273; }","duration":"124.539324ms","start":"2026-05-11T10:50:30.163822Z","end":"2026-05-11T10:50:30.288361Z","steps":["trace[1286793839] 'range keys from in-memory index tree' (duration: 124.135553ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:19.043907Z","caller":"traceutil/trace.go:171","msg":"trace[63571057] transaction","detail":"{read_only:false; response_revision:3518; number_of_response:1; }","duration":"104.699527ms","start":"2026-05-11T10:51:18.93917Z","end":"2026-05-11T10:51:19.043869Z","steps":["trace[63571057] 'process raft request' (duration: 104.220326ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:45.430084Z","caller":"traceutil/trace.go:171","msg":"trace[377432675] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"173.339465ms","start":"2026-05-11T10:51:45.256687Z","end":"2026-05-11T10:51:45.430026Z","steps":["trace[377432675] 'process raft request' (duration: 91.550466ms)","trace[377432675] 'compare' (duration: 81.470543ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:34.922138Z","caller":"traceutil/trace.go:171","msg":"trace[1877612394] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"100.654245ms","start":"2026-05-11T10:53:34.821463Z","end":"2026-05-11T10:53:34.922118Z","steps":["trace[1877612394] 'process raft request' (duration: 100.255939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:35.958888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.701342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:53:35.958993Z","caller":"traceutil/trace.go:171","msg":"trace[52024909] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"287.005994ms","start":"2026-05-11T10:53:35.671969Z","end":"2026-05-11T10:53:35.958975Z","steps":["trace[52024909] 'process raft request' (duration: 126.157379ms)","trace[52024909] 'compare' (duration: 160.036512ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:35.960858Z","caller":"traceutil/trace.go:171","msg":"trace[2018223522] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"180.117969ms","start":"2026-05-11T10:53:35.780726Z","end":"2026-05-11T10:53:35.960844Z","steps":["trace[2018223522] 'process raft request' (duration: 180.061839ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:35.961331Z","caller":"traceutil/trace.go:171","msg":"trace[776040849] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"201.674912ms","start":"2026-05-11T10:53:35.759638Z","end":"2026-05-11T10:53:35.961313Z","steps":["trace[776040849] 'process raft request' (duration: 201.029952ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:35.961421Z","caller":"traceutil/trace.go:171","msg":"trace[167008931] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"240.82936ms","start":"2026-05-11T10:53:35.72056Z","end":"2026-05-11T10:53:35.961389Z","steps":["trace[167008931] 'process raft request' (duration: 240.038728ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:35.965652Z","caller":"traceutil/trace.go:171","msg":"trace[1194423226] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"142.78532ms","start":"2026-05-11T10:53:35.82285Z","end":"2026-05-11T10:53:35.965635Z","steps":["trace[1194423226] 'process raft request' (duration: 142.669467ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:39.504384Z","caller":"traceutil/trace.go:171","msg":"trace[85437257] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"291.082245ms","start":"2026-05-11T10:53:39.21328Z","end":"2026-05-11T10:53:39.504363Z","steps":["trace[85437257] 'process raft request' (duration: 235.155247ms)","trace[85437257] 'compare' (duration: 55.621603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:53:39.751662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.170088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-11T10:53:39.75175Z","caller":"traceutil/trace.go:171","msg":"trace[909114425] linearizableReadLoop","detail":"{readStateIndex:5084; appliedIndex:5083; }","duration":"119.436709ms","start":"2026-05-11T10:53:39.632301Z","end":"2026-05-11T10:53:39.751738Z","steps":["trace[909114425] 'read index received' (duration: 27.921µs)","trace[909114425] 'applied index is now lower than readState.Index' (duration: 119.407738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:53:39.752087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.780834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f8178b66-80e5-4247-84d5-a277ef017f80\" ","response":"range_response_count:1 size:4066"} {"level":"info","ts":"2026-05-11T10:53:39.752058Z","caller":"traceutil/trace.go:171","msg":"trace[1718947821] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4893; }","duration":"159.604881ms","start":"2026-05-11T10:53:39.592424Z","end":"2026-05-11T10:53:39.752029Z","steps":["trace[1718947821] 'compare' (duration: 141.986915ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:39.752131Z","caller":"traceutil/trace.go:171","msg":"trace[1156059678] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f8178b66-80e5-4247-84d5-a277ef017f80; range_end:; response_count:1; response_revision:4893; }","duration":"119.853205ms","start":"2026-05-11T10:53:39.632269Z","end":"2026-05-11T10:53:39.752122Z","steps":["trace[1156059678] 'agreement among raft nodes before linearized reading' (duration: 119.500869ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:42.470242Z","caller":"traceutil/trace.go:171","msg":"trace[292439893] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"106.304647ms","start":"2026-05-11T10:53:42.363915Z","end":"2026-05-11T10:53:42.47022Z","steps":["trace[292439893] 'process raft request' (duration: 106.172485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:42.961603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.856887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:53:42.961741Z","caller":"traceutil/trace.go:171","msg":"trace[452121952] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"282.626968ms","start":"2026-05-11T10:53:42.679092Z","end":"2026-05-11T10:53:42.961719Z","steps":["trace[452121952] 'process raft request' (duration: 175.337134ms)","trace[452121952] 'compare' (duration: 106.677684ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:42.964136Z","caller":"traceutil/trace.go:171","msg":"trace[594158855] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5161; }","duration":"280.464263ms","start":"2026-05-11T10:53:42.683649Z","end":"2026-05-11T10:53:42.964113Z","steps":["trace[594158855] 'read index received' (duration: 170.79646ms)","trace[594158855] 'applied index is now lower than readState.Index' (duration: 109.666583ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:42.964251Z","caller":"traceutil/trace.go:171","msg":"trace[788600710] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"280.949611ms","start":"2026-05-11T10:53:42.683291Z","end":"2026-05-11T10:53:42.96424Z","steps":["trace[788600710] 'process raft request' (duration: 280.470853ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:42.964507Z","caller":"traceutil/trace.go:171","msg":"trace[186636493] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"281.110444ms","start":"2026-05-11T10:53:42.683383Z","end":"2026-05-11T10:53:42.964493Z","steps":["trace[186636493] 'process raft request' (duration: 280.484394ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:42.964638Z","caller":"traceutil/trace.go:171","msg":"trace[505163259] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"281.093043ms","start":"2026-05-11T10:53:42.683537Z","end":"2026-05-11T10:53:42.96463Z","steps":["trace[505163259] 'process raft request' (duration: 280.363221ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:42.964754Z","caller":"traceutil/trace.go:171","msg":"trace[2140845342] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"281.200894ms","start":"2026-05-11T10:53:42.683543Z","end":"2026-05-11T10:53:42.964744Z","steps":["trace[2140845342] 'process raft request' (duration: 280.394312ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:42.96492Z","caller":"traceutil/trace.go:171","msg":"trace[725516449] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"280.88744ms","start":"2026-05-11T10:53:42.684013Z","end":"2026-05-11T10:53:42.964901Z","steps":["trace[725516449] 'process raft request' (duration: 279.988906ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:42.965234Z","caller":"traceutil/trace.go:171","msg":"trace[176426326] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"274.627498ms","start":"2026-05-11T10:53:42.690595Z","end":"2026-05-11T10:53:42.965222Z","steps":["trace[176426326] 'process raft request' (duration: 273.450639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:42.96539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.726013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:42.965436Z","caller":"traceutil/trace.go:171","msg":"trace[575413029] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4973; }","duration":"281.816774ms","start":"2026-05-11T10:53:42.683606Z","end":"2026-05-11T10:53:42.965423Z","steps":["trace[575413029] 'agreement among raft nodes before linearized reading' (duration: 281.741463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:42.966326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.107378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195922"} {"level":"info","ts":"2026-05-11T10:53:42.966376Z","caller":"traceutil/trace.go:171","msg":"trace[1758389195] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4973; }","duration":"238.2013ms","start":"2026-05-11T10:53:42.728161Z","end":"2026-05-11T10:53:42.966362Z","steps":["trace[1758389195] 'agreement among raft nodes before linearized reading' (duration: 237.392736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:42.96722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.340603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:42.967255Z","caller":"traceutil/trace.go:171","msg":"trace[618780744] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4973; }","duration":"188.381314ms","start":"2026-05-11T10:53:42.778864Z","end":"2026-05-11T10:53:42.967246Z","steps":["trace[618780744] 'agreement among raft nodes before linearized reading' (duration: 188.319863ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:43.998633Z","caller":"traceutil/trace.go:171","msg":"trace[237173967] linearizableReadLoop","detail":"{readStateIndex:5186; appliedIndex:5185; }","duration":"403.767426ms","start":"2026-05-11T10:53:43.594846Z","end":"2026-05-11T10:53:43.998614Z","steps":["trace[237173967] 'read index received' (duration: 403.640604ms)","trace[237173967] 'applied index is now lower than readState.Index' (duration: 125.712µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:43.998899Z","caller":"traceutil/trace.go:171","msg":"trace[351249800] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"427.728174ms","start":"2026-05-11T10:53:43.571159Z","end":"2026-05-11T10:53:43.998887Z","steps":["trace[351249800] 'process raft request' (duration: 427.339448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:43.999019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:43.57114Z","time spent":"427.824555ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:43.999222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.376446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T10:53:43.999254Z","caller":"traceutil/trace.go:171","msg":"trace[947557593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4989; }","duration":"404.452148ms","start":"2026-05-11T10:53:43.594794Z","end":"2026-05-11T10:53:43.999246Z","steps":["trace[947557593] 'agreement among raft nodes before linearized reading' (duration: 404.355906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:43.999276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:43.594772Z","time spent":"404.497788ms","remote":"127.0.0.1:51970","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-05-11T10:53:44.001686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.708596ms","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-05-11T10:53:44.001719Z","caller":"traceutil/trace.go:171","msg":"trace[767906038] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4991; }","duration":"403.758827ms","start":"2026-05-11T10:53:43.597952Z","end":"2026-05-11T10:53:44.001711Z","steps":["trace[767906038] 'agreement among raft nodes before linearized reading' (duration: 403.630395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:44.001748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:43.597944Z","time spent":"403.799397ms","remote":"127.0.0.1:51970","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":"info","ts":"2026-05-11T10:53:44.002172Z","caller":"traceutil/trace.go:171","msg":"trace[391184618] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"103.004042ms","start":"2026-05-11T10:53:43.899159Z","end":"2026-05-11T10:53:44.002163Z","steps":["trace[391184618] 'process raft request' (duration: 102.337581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:49.740193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143325488554591060,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T10:53:49.89479Z","caller":"traceutil/trace.go:171","msg":"trace[543363112] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"656.719315ms","start":"2026-05-11T10:53:49.238042Z","end":"2026-05-11T10:53:49.894762Z","steps":["trace[543363112] 'process raft request' (duration: 656.548603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:49.89493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.238033Z","time spent":"656.828777ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13278,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:50.442275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.320754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:53:50.442477Z","caller":"traceutil/trace.go:171","msg":"trace[719841631] linearizableReadLoop","detail":"{readStateIndex:5286; appliedIndex:5284; }","duration":"1.203069814s","start":"2026-05-11T10:53:49.239388Z","end":"2026-05-11T10:53:50.442458Z","steps":["trace[719841631] 'read index received' (duration: 655.907762ms)","trace[719841631] 'applied index is now lower than readState.Index' (duration: 547.160972ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:50.442505Z","caller":"traceutil/trace.go:171","msg":"trace[668114938] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"1.203083084s","start":"2026-05-11T10:53:49.239384Z","end":"2026-05-11T10:53:50.442467Z","steps":["trace[668114938] 'process raft request' (duration: 860.485886ms)","trace[668114938] 'compare' (duration: 341.511311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:53:50.442653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.239364Z","time spent":"1.203212986s","remote":"127.0.0.1:51768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:50.443252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203831206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4848"} {"level":"info","ts":"2026-05-11T10:53:50.443309Z","caller":"traceutil/trace.go:171","msg":"trace[856545379] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"899.934711ms","start":"2026-05-11T10:53:49.543352Z","end":"2026-05-11T10:53:50.443287Z","steps":["trace[856545379] 'process raft request' (duration: 899.018515ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:50.443335Z","caller":"traceutil/trace.go:171","msg":"trace[837837151] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5087; }","duration":"1.203932668s","start":"2026-05-11T10:53:49.239382Z","end":"2026-05-11T10:53:50.443314Z","steps":["trace[837837151] 'agreement among raft nodes before linearized reading' (duration: 1.203691394s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.443368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.239328Z","time spent":"1.20403372s","remote":"127.0.0.1:33264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4871,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-05-11T10:53:50.443411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.543326Z","time spent":"900.031523ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:53:50.454431Z","caller":"traceutil/trace.go:171","msg":"trace[339313477] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"551.628923ms","start":"2026-05-11T10:53:49.902787Z","end":"2026-05-11T10:53:50.454415Z","steps":["trace[339313477] 'process raft request' (duration: 551.564362ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:50.454452Z","caller":"traceutil/trace.go:171","msg":"trace[1769968418] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"552.920685ms","start":"2026-05-11T10:53:49.90151Z","end":"2026-05-11T10:53:50.454431Z","steps":["trace[1769968418] 'process raft request' (duration: 552.716961ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:50.454455Z","caller":"traceutil/trace.go:171","msg":"trace[1077705528] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"551.873177ms","start":"2026-05-11T10:53:49.902545Z","end":"2026-05-11T10:53:50.454418Z","steps":["trace[1077705528] 'process raft request' (duration: 551.773406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.454549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.902782Z","time spent":"551.699334ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:50.454576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.901484Z","time spent":"553.024006ms","remote":"127.0.0.1:51866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:50.454578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.902532Z","time spent":"551.981579ms","remote":"127.0.0.1:51866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:53:50.454804Z","caller":"traceutil/trace.go:171","msg":"trace[2006697399] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"551.949428ms","start":"2026-05-11T10:53:49.902843Z","end":"2026-05-11T10:53:50.454793Z","steps":["trace[2006697399] 'process raft request' (duration: 551.543612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.45486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.902818Z","time spent":"552.015409ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:50.454995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.368102ms","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-05-11T10:53:50.455038Z","caller":"traceutil/trace.go:171","msg":"trace[1639459312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5092; }","duration":"420.438853ms","start":"2026-05-11T10:53:50.034588Z","end":"2026-05-11T10:53:50.455027Z","steps":["trace[1639459312] 'agreement among raft nodes before linearized reading' (duration: 420.299371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.455063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:50.034577Z","time spent":"420.481513ms","remote":"127.0.0.1:51970","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-05-11T10:53:50.45511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213945099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"warn","ts":"2026-05-11T10:53:50.455135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.162851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7508838b-7ac8-41e4-b9b6-40e27827bc33\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-05-11T10:53:50.455153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14785613s","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-05-11T10:53:50.45518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.403723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:50.455206Z","caller":"traceutil/trace.go:171","msg":"trace[891721887] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"423.381831ms","start":"2026-05-11T10:53:50.031817Z","end":"2026-05-11T10:53:50.455199Z","steps":["trace[891721887] 'process raft request' (duration: 422.822332ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:50.455206Z","caller":"traceutil/trace.go:171","msg":"trace[747570750] 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:5092; }","duration":"1.147912311s","start":"2026-05-11T10:53:49.307281Z","end":"2026-05-11T10:53:50.455193Z","steps":["trace[747570750] 'agreement among raft nodes before linearized reading' (duration: 1.147790479s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.455269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:50.031791Z","time spent":"423.433711ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:50.455325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.307259Z","time spent":"1.148003983s","remote":"127.0.0.1:51862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-11T10:53:50.455655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082551534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-05-11T10:53:50.455696Z","caller":"traceutil/trace.go:171","msg":"trace[1069274952] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5092; }","duration":"1.082616964s","start":"2026-05-11T10:53:49.373068Z","end":"2026-05-11T10:53:50.455685Z","steps":["trace[1069274952] 'agreement among raft nodes before linearized reading' (duration: 1.082501932s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.454991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.739144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-11T10:53:50.455726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.373057Z","time spent":"1.082661926s","remote":"127.0.0.1:51914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":920,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-05-11T10:53:50.455777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.187105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-11T10:53:50.455794Z","caller":"traceutil/trace.go:171","msg":"trace[2013712962] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5092; }","duration":"416.236446ms","start":"2026-05-11T10:53:50.03955Z","end":"2026-05-11T10:53:50.455787Z","steps":["trace[2013712962] 'agreement among raft nodes before linearized reading' (duration: 416.192545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.455808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:50.039534Z","time spent":"416.270087ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-11T10:53:50.455207Z","caller":"traceutil/trace.go:171","msg":"trace[40377979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5092; }","duration":"280.474235ms","start":"2026-05-11T10:53:50.174727Z","end":"2026-05-11T10:53:50.455201Z","steps":["trace[40377979] 'agreement among raft nodes before linearized reading' (duration: 280.434884ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:50.45573Z","caller":"traceutil/trace.go:171","msg":"trace[726717014] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:5092; }","duration":"650.528997ms","start":"2026-05-11T10:53:49.805194Z","end":"2026-05-11T10:53:50.455723Z","steps":["trace[726717014] 'agreement among raft nodes before linearized reading' (duration: 649.698574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.455983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.805173Z","time spent":"650.798741ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.11\" "} {"level":"warn","ts":"2026-05-11T10:53:50.456007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.578461ms","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-05-11T10:53:50.456024Z","caller":"traceutil/trace.go:171","msg":"trace[1813575278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5092; }","duration":"416.625942ms","start":"2026-05-11T10:53:50.039393Z","end":"2026-05-11T10:53:50.456019Z","steps":["trace[1813575278] 'agreement among raft nodes before linearized reading' (duration: 416.577032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.456039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:50.039383Z","time spent":"416.652103ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-11T10:53:50.45517Z","caller":"traceutil/trace.go:171","msg":"trace[980971108] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7508838b-7ac8-41e4-b9b6-40e27827bc33; range_end:; response_count:1; response_revision:5092; }","duration":"272.197702ms","start":"2026-05-11T10:53:50.182963Z","end":"2026-05-11T10:53:50.455161Z","steps":["trace[980971108] 'agreement among raft nodes before linearized reading' (duration: 272.08646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.455065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.162684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T10:53:50.456213Z","caller":"traceutil/trace.go:171","msg":"trace[671555462] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5092; }","duration":"665.359683ms","start":"2026-05-11T10:53:49.790848Z","end":"2026-05-11T10:53:50.456208Z","steps":["trace[671555462] 'agreement among raft nodes before linearized reading' (duration: 664.176735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.456232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.790829Z","time spent":"665.397974ms","remote":"127.0.0.1:60718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":27,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-11T10:53:50.455172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215570014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:50.456378Z","caller":"traceutil/trace.go:171","msg":"trace[1892601441] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5092; }","duration":"1.216788963s","start":"2026-05-11T10:53:49.239585Z","end":"2026-05-11T10:53:50.456374Z","steps":["trace[1892601441] 'agreement among raft nodes before linearized reading' (duration: 1.215572734s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.456394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.23958Z","time spent":"1.216809663s","remote":"127.0.0.1:52132","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":"warn","ts":"2026-05-11T10:53:50.456411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100849657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T10:53:50.456435Z","caller":"traceutil/trace.go:171","msg":"trace[66089982] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5092; }","duration":"1.100903798s","start":"2026-05-11T10:53:49.355525Z","end":"2026-05-11T10:53:50.456429Z","steps":["trace[66089982] 'agreement among raft nodes before linearized reading' (duration: 1.100854707s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.456454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.355509Z","time spent":"1.100940538s","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":16,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-05-11T10:53:50.455143Z","caller":"traceutil/trace.go:171","msg":"trace[1913514485] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5092; }","duration":"1.21397116s","start":"2026-05-11T10:53:49.241162Z","end":"2026-05-11T10:53:50.455133Z","steps":["trace[1913514485] 'agreement among raft nodes before linearized reading' (duration: 1.213913109s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.456507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.241104Z","time spent":"1.215398161s","remote":"127.0.0.1:52148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9660,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-05-11T10:53:50.456619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094016628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195922"} {"level":"info","ts":"2026-05-11T10:53:50.456666Z","caller":"traceutil/trace.go:171","msg":"trace[2094753131] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5092; }","duration":"1.094102438s","start":"2026-05-11T10:53:49.36255Z","end":"2026-05-11T10:53:50.456652Z","steps":["trace[2094753131] 'agreement among raft nodes before linearized reading' (duration: 1.093299436s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.456712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:49.36252Z","time spent":"1.0941773s","remote":"127.0.0.1:51800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2195945,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-05-11T10:53:50.458653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.107055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T10:53:50.458686Z","caller":"traceutil/trace.go:171","msg":"trace[506611579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5092; }","duration":"301.175225ms","start":"2026-05-11T10:53:50.157503Z","end":"2026-05-11T10:53:50.458678Z","steps":["trace[506611579] 'agreement among raft nodes before linearized reading' (duration: 297.71796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:50.458702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:50.157483Z","time spent":"301.215506ms","remote":"127.0.0.1:51970","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-05-11T10:53:51.114601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.426463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:51.114683Z","caller":"traceutil/trace.go:171","msg":"trace[1801848752] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5105; }","duration":"135.547754ms","start":"2026-05-11T10:53:50.979115Z","end":"2026-05-11T10:53:51.114663Z","steps":["trace[1801848752] 'count revisions from in-memory index tree' (duration: 135.370021ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:51.114691Z","caller":"traceutil/trace.go:171","msg":"trace[368458676] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"135.218679ms","start":"2026-05-11T10:53:50.979448Z","end":"2026-05-11T10:53:51.114666Z","steps":["trace[368458676] 'process raft request' (duration: 128.113947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:51.115381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.708517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195922"} {"level":"info","ts":"2026-05-11T10:53:51.115461Z","caller":"traceutil/trace.go:171","msg":"trace[2020037603] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5105; }","duration":"215.829848ms","start":"2026-05-11T10:53:50.899614Z","end":"2026-05-11T10:53:51.115444Z","steps":["trace[2020037603] 'range keys from in-memory index tree' (duration: 214.877972ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:53:51.420949Z","caller":"traceutil/trace.go:171","msg":"trace[170876552] linearizableReadLoop","detail":"{readStateIndex:5307; appliedIndex:5306; }","duration":"154.951145ms","start":"2026-05-11T10:53:51.265976Z","end":"2026-05-11T10:53:51.420927Z","steps":["trace[170876552] 'read index received' (duration: 154.758632ms)","trace[170876552] 'applied index is now lower than readState.Index' (duration: 191.673µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:51.421036Z","caller":"traceutil/trace.go:171","msg":"trace[1493705872] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"224.338502ms","start":"2026-05-11T10:53:51.196666Z","end":"2026-05-11T10:53:51.421005Z","steps":["trace[1493705872] 'process raft request' (duration: 224.120609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:51.421099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.107377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:51.421344Z","caller":"traceutil/trace.go:171","msg":"trace[480007225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5107; }","duration":"155.417942ms","start":"2026-05-11T10:53:51.265909Z","end":"2026-05-11T10:53:51.421327Z","steps":["trace[480007225] 'agreement among raft nodes before linearized reading' (duration: 155.106407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:51.87914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.210484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:53:51.879218Z","caller":"traceutil/trace.go:171","msg":"trace[1677837434] linearizableReadLoop","detail":"{readStateIndex:5308; appliedIndex:5307; }","duration":"379.801345ms","start":"2026-05-11T10:53:51.499405Z","end":"2026-05-11T10:53:51.879206Z","steps":["trace[1677837434] 'read index received' (duration: 79.49449ms)","trace[1677837434] 'applied index is now lower than readState.Index' (duration: 300.306115ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:51.879395Z","caller":"traceutil/trace.go:171","msg":"trace[1410240578] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"413.791627ms","start":"2026-05-11T10:53:51.465594Z","end":"2026-05-11T10:53:51.879386Z","steps":["trace[1410240578] 'process raft request' (duration: 113.282168ms)","trace[1410240578] 'compare' (duration: 300.095773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:53:51.879436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:51.465574Z","time spent":"413.839838ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:51.879527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.044829ms","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-05-11T10:53:51.879573Z","caller":"traceutil/trace.go:171","msg":"trace[355152488] 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:5108; }","duration":"333.131891ms","start":"2026-05-11T10:53:51.546428Z","end":"2026-05-11T10:53:51.87956Z","steps":["trace[355152488] 'agreement among raft nodes before linearized reading' (duration: 332.976418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:51.879597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:51.546409Z","time spent":"333.181691ms","remote":"127.0.0.1:51862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-11T10:53:51.879626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.202001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:51.879694Z","caller":"traceutil/trace.go:171","msg":"trace[917118880] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5108; }","duration":"380.325744ms","start":"2026-05-11T10:53:51.499351Z","end":"2026-05-11T10:53:51.879676Z","steps":["trace[917118880] 'agreement among raft nodes before linearized reading' (duration: 380.188571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:51.879737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:51.499331Z","time spent":"380.397254ms","remote":"127.0.0.1:52088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-05-11T10:53:53.512586Z","caller":"traceutil/trace.go:171","msg":"trace[683067099] linearizableReadLoop","detail":"{readStateIndex:5331; appliedIndex:5330; }","duration":"337.712989ms","start":"2026-05-11T10:53:53.174856Z","end":"2026-05-11T10:53:53.512569Z","steps":["trace[683067099] 'read index received' (duration: 337.533296ms)","trace[683067099] 'applied index is now lower than readState.Index' (duration: 179.053µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:53:53.512669Z","caller":"traceutil/trace.go:171","msg":"trace[830975785] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"444.854227ms","start":"2026-05-11T10:53:53.067807Z","end":"2026-05-11T10:53:53.512661Z","steps":["trace[830975785] 'process raft request' (duration: 444.625674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:53.512749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:53.067782Z","time spent":"444.905118ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:53:53.513174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.309039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:53:53.513237Z","caller":"traceutil/trace.go:171","msg":"trace[1790743065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5131; }","duration":"338.37985ms","start":"2026-05-11T10:53:53.17484Z","end":"2026-05-11T10:53:53.51322Z","steps":["trace[1790743065] 'agreement among raft nodes before linearized reading' (duration: 338.290299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:53:53.51327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:53:53.174784Z","time spent":"338.479622ms","remote":"127.0.0.1:51716","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-05-11T10:54:03.018418Z","caller":"traceutil/trace.go:171","msg":"trace[1710507596] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"510.752926ms","start":"2026-05-11T10:54:02.507638Z","end":"2026-05-11T10:54:03.018391Z","steps":["trace[1710507596] 'process raft request' (duration: 510.586413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.018736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.507611Z","time spent":"510.886199ms","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:54:03.810942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.550502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:54:03.811185Z","caller":"traceutil/trace.go:171","msg":"trace[426151581] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"1.299213592s","start":"2026-05-11T10:54:02.511943Z","end":"2026-05-11T10:54:03.811157Z","steps":["trace[426151581] 'process raft request' (duration: 585.337089ms)","trace[426151581] 'compare' (duration: 711.542676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:54:03.811323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.511916Z","time spent":"1.299348944s","remote":"127.0.0.1:51970","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-05-11T10:54:03.811823Z","caller":"traceutil/trace.go:171","msg":"trace[448818799] linearizableReadLoop","detail":"{readStateIndex:5455; appliedIndex:5453; }","duration":"1.256941416s","start":"2026-05-11T10:54:02.554868Z","end":"2026-05-11T10:54:03.811809Z","steps":["trace[448818799] 'read index received' (duration: 463.37293ms)","trace[448818799] 'applied index is now lower than readState.Index' (duration: 793.567586ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:54:03.811915Z","caller":"traceutil/trace.go:171","msg":"trace[148614165] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"1.222049337s","start":"2026-05-11T10:54:02.589856Z","end":"2026-05-11T10:54:03.811906Z","steps":["trace[148614165] 'process raft request' (duration: 1.221173083s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.811979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.589834Z","time spent":"1.222109137s","remote":"127.0.0.1:51970","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-05-11T10:54:03.812093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218250947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-11T10:54:03.812167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13973667s","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-05-11T10:54:03.812174Z","caller":"traceutil/trace.go:171","msg":"trace[72797320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5252; }","duration":"1.218364679s","start":"2026-05-11T10:54:02.593789Z","end":"2026-05-11T10:54:03.812154Z","steps":["trace[72797320] 'agreement among raft nodes before linearized reading' (duration: 1.218186636s)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:03.812201Z","caller":"traceutil/trace.go:171","msg":"trace[272326294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5252; }","duration":"1.139795561s","start":"2026-05-11T10:54:02.672397Z","end":"2026-05-11T10:54:03.812193Z","steps":["trace[272326294] 'agreement among raft nodes before linearized reading' (duration: 1.139674899s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.812215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.593777Z","time spent":"1.218429329s","remote":"127.0.0.1:51970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T10:54:03.812239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.672383Z","time spent":"1.139846382s","remote":"127.0.0.1:51970","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-05-11T10:54:03.812361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257496395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-05-11T10:54:03.812392Z","caller":"traceutil/trace.go:171","msg":"trace[800770763] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5252; }","duration":"1.257569026s","start":"2026-05-11T10:54:02.554815Z","end":"2026-05-11T10:54:03.812384Z","steps":["trace[800770763] 'agreement among raft nodes before linearized reading' (duration: 1.257481685s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.812425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.554794Z","time spent":"1.257625307s","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-11T10:54:03.812468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216862674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T10:54:03.812505Z","caller":"traceutil/trace.go:171","msg":"trace[1317649700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5252; }","duration":"1.216912575s","start":"2026-05-11T10:54:02.595582Z","end":"2026-05-11T10:54:03.812495Z","steps":["trace[1317649700] 'agreement among raft nodes before linearized reading' (duration: 1.216831714s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.812538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.595575Z","time spent":"1.216957005s","remote":"127.0.0.1:51970","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-05-11T10:54:03.813043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218965738s","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-05-11T10:54:03.813086Z","caller":"traceutil/trace.go:171","msg":"trace[964950497] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5252; }","duration":"1.219031479s","start":"2026-05-11T10:54:02.594045Z","end":"2026-05-11T10:54:03.813076Z","steps":["trace[964950497] 'agreement among raft nodes before linearized reading' (duration: 1.218925717s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.813125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.594035Z","time spent":"1.219073679s","remote":"127.0.0.1:51970","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-05-11T10:54:03.812395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20067672s","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-05-11T10:54:03.813199Z","caller":"traceutil/trace.go:171","msg":"trace[108531681] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5252; }","duration":"1.201506233s","start":"2026-05-11T10:54:02.611685Z","end":"2026-05-11T10:54:03.813191Z","steps":["trace[108531681] 'agreement among raft nodes before linearized reading' (duration: 1.200623849s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.81332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.926703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:54:03.813356Z","caller":"traceutil/trace.go:171","msg":"trace[162140971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5252; }","duration":"639.004894ms","start":"2026-05-11T10:54:03.17434Z","end":"2026-05-11T10:54:03.813345Z","steps":["trace[162140971] 'agreement among raft nodes before linearized reading' (duration: 638.945433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.813225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.611654Z","time spent":"1.201564004s","remote":"127.0.0.1:51862","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-05-11T10:54:03.81289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21779559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T10:54:03.813475Z","caller":"traceutil/trace.go:171","msg":"trace[1083688635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5252; }","duration":"1.218382969s","start":"2026-05-11T10:54:02.595083Z","end":"2026-05-11T10:54:03.813466Z","steps":["trace[1083688635] 'agreement among raft nodes before linearized reading' (duration: 1.217727979s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.813511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.595051Z","time spent":"1.218446219s","remote":"127.0.0.1:51970","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-05-11T10:54:03.813577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073470146s","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-05-11T10:54:03.813601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:03.174318Z","time spent":"639.275569ms","remote":"127.0.0.1:51716","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-05-11T10:54:03.813639Z","caller":"traceutil/trace.go:171","msg":"trace[2073084216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5252; }","duration":"1.073568397s","start":"2026-05-11T10:54:02.740055Z","end":"2026-05-11T10:54:03.813623Z","steps":["trace[2073084216] 'agreement among raft nodes before linearized reading' (duration: 1.072908587s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:03.814341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:02.740036Z","time spent":"1.073632748s","remote":"127.0.0.1:51970","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-05-11T10:54:04.029171Z","caller":"traceutil/trace.go:171","msg":"trace[87621566] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"209.067848ms","start":"2026-05-11T10:54:03.82008Z","end":"2026-05-11T10:54:04.029148Z","steps":["trace[87621566] 'process raft request' (duration: 208.998637ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:04.029243Z","caller":"traceutil/trace.go:171","msg":"trace[2098798973] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"209.768749ms","start":"2026-05-11T10:54:03.819458Z","end":"2026-05-11T10:54:04.029226Z","steps":["trace[2098798973] 'process raft request' (duration: 202.213171ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:04.029299Z","caller":"traceutil/trace.go:171","msg":"trace[1926237323] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"209.25284ms","start":"2026-05-11T10:54:03.820016Z","end":"2026-05-11T10:54:04.029269Z","steps":["trace[1926237323] 'process raft request' (duration: 209.013907ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:04.029555Z","caller":"traceutil/trace.go:171","msg":"trace[462178329] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"209.339463ms","start":"2026-05-11T10:54:03.820209Z","end":"2026-05-11T10:54:04.029548Z","steps":["trace[462178329] 'process raft request' (duration: 208.905835ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:04.029804Z","caller":"traceutil/trace.go:171","msg":"trace[318053926] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"209.214342ms","start":"2026-05-11T10:54:03.820579Z","end":"2026-05-11T10:54:04.029793Z","steps":["trace[318053926] 'process raft request' (duration: 208.898116ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:04.029909Z","caller":"traceutil/trace.go:171","msg":"trace[30763856] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"203.610603ms","start":"2026-05-11T10:54:03.826264Z","end":"2026-05-11T10:54:04.029875Z","steps":["trace[30763856] 'process raft request' (duration: 203.477931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:04.462353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.091476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:54:04.462704Z","caller":"traceutil/trace.go:171","msg":"trace[317052553] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"426.796757ms","start":"2026-05-11T10:54:04.035876Z","end":"2026-05-11T10:54:04.462673Z","steps":["trace[317052553] 'process raft request' (duration: 104.294429ms)","trace[317052553] 'compare' (duration: 320.779062ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:54:04.462755Z","caller":"traceutil/trace.go:171","msg":"trace[1115674009] linearizableReadLoop","detail":"{readStateIndex:5467; appliedIndex:5463; }","duration":"425.00218ms","start":"2026-05-11T10:54:04.037735Z","end":"2026-05-11T10:54:04.462737Z","steps":["trace[1115674009] 'read index received' (duration: 21.443118ms)","trace[1115674009] 'applied index is now lower than readState.Index' (duration: 403.558012ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:54:04.462859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:04.035847Z","time spent":"426.93495ms","remote":"127.0.0.1:51866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:54:04.463118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.31051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-05-11T10:54:04.463162Z","caller":"traceutil/trace.go:171","msg":"trace[96158728] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5263; }","duration":"328.39136ms","start":"2026-05-11T10:54:04.134759Z","end":"2026-05-11T10:54:04.46315Z","steps":["trace[96158728] 'agreement among raft nodes before linearized reading' (duration: 328.242648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:04.463194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:04.134723Z","time spent":"328.464062ms","remote":"127.0.0.1:37144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-11T10:54:04.463192Z","caller":"traceutil/trace.go:171","msg":"trace[1850404290] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"427.201554ms","start":"2026-05-11T10:54:04.035976Z","end":"2026-05-11T10:54:04.463178Z","steps":["trace[1850404290] 'process raft request' (duration: 426.476933ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:04.46324Z","caller":"traceutil/trace.go:171","msg":"trace[1701013818] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"427.250645ms","start":"2026-05-11T10:54:04.035981Z","end":"2026-05-11T10:54:04.463232Z","steps":["trace[1701013818] 'process raft request' (duration: 426.703786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:04.463942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:04.035973Z","time spent":"427.911986ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T10:54:04.464891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.394246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-54c9bd7b79-dz24l\" ","response":"range_response_count:1 size:11844"} {"level":"warn","ts":"2026-05-11T10:54:04.464899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:04.035967Z","time spent":"427.59257ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T10:54:04.464958Z","caller":"traceutil/trace.go:171","msg":"trace[1723699030] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-54c9bd7b79-dz24l; range_end:; response_count:1; response_revision:5263; }","duration":"427.227935ms","start":"2026-05-11T10:54:04.037714Z","end":"2026-05-11T10:54:04.464942Z","steps":["trace[1723699030] 'agreement among raft nodes before linearized reading' (duration: 425.279664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:04.465181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:54:04.037708Z","time spent":"427.276976ms","remote":"127.0.0.1:51884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11867,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-54c9bd7b79-dz24l\" "} {"level":"warn","ts":"2026-05-11T10:54:04.747696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.344778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:54:04.747907Z","caller":"traceutil/trace.go:171","msg":"trace[1686475177] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"195.975701ms","start":"2026-05-11T10:54:04.551907Z","end":"2026-05-11T10:54:04.747883Z","steps":["trace[1686475177] 'process raft request' (duration: 44.357668ms)","trace[1686475177] 'compare' (duration: 151.202886ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:54:04.748228Z","caller":"traceutil/trace.go:171","msg":"trace[863006818] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"192.594948ms","start":"2026-05-11T10:54:04.55561Z","end":"2026-05-11T10:54:04.748204Z","steps":["trace[863006818] 'process raft request' (duration: 192.196382ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:04.748471Z","caller":"traceutil/trace.go:171","msg":"trace[26029533] transaction","detail":"{read_only:false; response_revision:5267; number_of_response:1; }","duration":"192.657258ms","start":"2026-05-11T10:54:04.555791Z","end":"2026-05-11T10:54:04.748448Z","steps":["trace[26029533] 'process raft request' (duration: 192.301263ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:04.74851Z","caller":"traceutil/trace.go:171","msg":"trace[282120800] linearizableReadLoop","detail":"{readStateIndex:5471; appliedIndex:5468; }","duration":"192.667638ms","start":"2026-05-11T10:54:04.55582Z","end":"2026-05-11T10:54:04.748488Z","steps":["trace[282120800] 'read index received' (duration: 40.503927ms)","trace[282120800] 'applied index is now lower than readState.Index' (duration: 152.160901ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:54:04.748796Z","caller":"traceutil/trace.go:171","msg":"trace[506821925] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"189.236874ms","start":"2026-05-11T10:54:04.559542Z","end":"2026-05-11T10:54:04.748779Z","steps":["trace[506821925] 'process raft request' (duration: 188.766506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:04.752101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.910562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-05-11T10:54:04.752184Z","caller":"traceutil/trace.go:171","msg":"trace[155889711] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5268; }","duration":"196.381536ms","start":"2026-05-11T10:54:04.555786Z","end":"2026-05-11T10:54:04.752167Z","steps":["trace[155889711] 'agreement among raft nodes before linearized reading' (duration: 192.801481ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:06.672694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2844} {"level":"info","ts":"2026-05-11T10:54:06.774622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2844,"took":"100.424783ms","hash":2125745792,"current-db-size-bytes":52031488,"current-db-size":"52 MB","current-db-size-in-use-bytes":27152384,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-11T10:54:06.775162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2125745792,"revision":2844,"compact-revision":-1} {"level":"info","ts":"2026-05-11T10:54:30.326171Z","caller":"traceutil/trace.go:171","msg":"trace[1454195937] linearizableReadLoop","detail":"{readStateIndex:5751; appliedIndex:5750; }","duration":"127.251785ms","start":"2026-05-11T10:54:30.198895Z","end":"2026-05-11T10:54:30.326147Z","steps":["trace[1454195937] 'read index received' (duration: 123.620989ms)","trace[1454195937] 'applied index is now lower than readState.Index' (duration: 3.629726ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:54:30.326212Z","caller":"traceutil/trace.go:171","msg":"trace[2027610712] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"128.911479ms","start":"2026-05-11T10:54:30.197277Z","end":"2026-05-11T10:54:30.326188Z","steps":["trace[2027610712] 'process raft request' (duration: 125.290413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:54:30.326377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.467778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-11T10:54:30.326457Z","caller":"traceutil/trace.go:171","msg":"trace[1041378454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5539; }","duration":"127.59696ms","start":"2026-05-11T10:54:30.198842Z","end":"2026-05-11T10:54:30.326439Z","steps":["trace[1041378454] 'agreement among raft nodes before linearized reading' (duration: 127.374977ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:44.14372Z","caller":"traceutil/trace.go:171","msg":"trace[885674538] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"138.945425ms","start":"2026-05-11T10:54:44.004748Z","end":"2026-05-11T10:54:44.143694Z","steps":["trace[885674538] 'process raft request' (duration: 138.787933ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:44.161036Z","caller":"traceutil/trace.go:171","msg":"trace[470757175] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"147.368131ms","start":"2026-05-11T10:54:44.013643Z","end":"2026-05-11T10:54:44.161011Z","steps":["trace[470757175] 'process raft request' (duration: 146.790103ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:44.607813Z","caller":"traceutil/trace.go:171","msg":"trace[63968608] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"137.349181ms","start":"2026-05-11T10:54:44.470439Z","end":"2026-05-11T10:54:44.607789Z","steps":["trace[63968608] 'process raft request' (duration: 99.119434ms)","trace[63968608] 'compare' (duration: 38.069725ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:54:44.608186Z","caller":"traceutil/trace.go:171","msg":"trace[671230595] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"137.505284ms","start":"2026-05-11T10:54:44.470662Z","end":"2026-05-11T10:54:44.608168Z","steps":["trace[671230595] 'process raft request' (duration: 137.088228ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:44.609809Z","caller":"traceutil/trace.go:171","msg":"trace[1593170770] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"138.252175ms","start":"2026-05-11T10:54:44.470736Z","end":"2026-05-11T10:54:44.608988Z","steps":["trace[1593170770] 'process raft request' (duration: 137.24846ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:54:53.249194Z","caller":"traceutil/trace.go:171","msg":"trace[1673882153] transaction","detail":"{read_only:false; response_revision:5693; number_of_response:1; }","duration":"100.652935ms","start":"2026-05-11T10:54:53.148517Z","end":"2026-05-11T10:54:53.24917Z","steps":["trace[1673882153] 'process raft request' (duration: 100.505333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:57:33.200794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.931731ms","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-05-11T10:57:33.200902Z","caller":"traceutil/trace.go:171","msg":"trace[543096859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6815; }","duration":"190.115918ms","start":"2026-05-11T10:57:33.010763Z","end":"2026-05-11T10:57:33.200879Z","steps":["trace[543096859] 'range keys from in-memory index tree' (duration: 189.671721ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:58:01.900818Z","caller":"traceutil/trace.go:171","msg":"trace[507416379] transaction","detail":"{read_only:false; response_revision:7020; number_of_response:1; }","duration":"168.754477ms","start":"2026-05-11T10:58:01.732038Z","end":"2026-05-11T10:58:01.900793Z","steps":["trace[507416379] 'process raft request' (duration: 168.5738ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:58:01.912865Z","caller":"traceutil/trace.go:171","msg":"trace[758146300] linearizableReadLoop","detail":"{readStateIndex:7283; appliedIndex:7281; }","duration":"142.413577ms","start":"2026-05-11T10:58:01.770436Z","end":"2026-05-11T10:58:01.912849Z","steps":["trace[758146300] 'read index received' (duration: 130.193782ms)","trace[758146300] 'applied index is now lower than readState.Index' (duration: 12.218985ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:58:01.912947Z","caller":"traceutil/trace.go:171","msg":"trace[104253018] transaction","detail":"{read_only:false; response_revision:7021; number_of_response:1; }","duration":"180.598419ms","start":"2026-05-11T10:58:01.732341Z","end":"2026-05-11T10:58:01.912939Z","steps":["trace[104253018] 'process raft request' (duration: 180.394331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:58:01.913156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.653207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T10:58:01.913207Z","caller":"traceutil/trace.go:171","msg":"trace[2103139710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7021; }","duration":"125.757451ms","start":"2026-05-11T10:58:01.787436Z","end":"2026-05-11T10:58:01.913194Z","steps":["trace[2103139710] 'agreement among raft nodes before linearized reading' (duration: 125.601175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:58:01.913229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.798951ms","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-05-11T10:58:01.913256Z","caller":"traceutil/trace.go:171","msg":"trace[1938082146] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7021; }","duration":"142.868994ms","start":"2026-05-11T10:58:01.770378Z","end":"2026-05-11T10:58:01.913247Z","steps":["trace[1938082146] 'agreement among raft nodes before linearized reading' (duration: 142.780131ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:59:06.678838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5293} {"level":"info","ts":"2026-05-11T10:59:06.759383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5293,"took":"78.814683ms","hash":1318466537,"current-db-size-bytes":52031488,"current-db-size":"52 MB","current-db-size-in-use-bytes":18833408,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-05-11T10:59:06.759452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1318466537,"revision":5293,"compact-revision":2844}