{"level":"warn","ts":"2026-07-07T07:27:45.851607Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T07:27:45.851722Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.108: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.108:2380","--initial-cluster=instance=https://199.19.213.108:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.108:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.108: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-07-07T07:27:45.851787Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T07:27:45.851801Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-07-07T07:27:45.851819Z","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-07-07T07:27:45.852663Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"]} {"level":"info","ts":"2026-07-07T07:27:45.852762Z","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.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.108: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-07-07T07:27:45.857886Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.875389ms"} {"level":"info","ts":"2026-07-07T07:27:45.869576Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e9047e95cdba2056","cluster-id":"51f5e218724a48a2"} {"level":"info","ts":"2026-07-07T07:27:45.869727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T07:27:45.869754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 0"} {"level":"info","ts":"2026-07-07T07:27:45.869763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e9047e95cdba2056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T07:27:45.869774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became follower at term 1"} {"level":"info","ts":"2026-07-07T07:27:45.869835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"warn","ts":"2026-07-07T07:27:45.883164Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T07:27:45.884679Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T07:27:45.886887Z","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-07-07T07:27:45.890534Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e9047e95cdba2056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T07:27:45.893833Z","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-07-07T07:27:45.893988Z","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-07-07T07:27:45.894015Z","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-07-07T07:27:45.894314Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e9047e95cdba2056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T07:27:45.895007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 switched to configuration voters=(16790684492610805846)"} {"level":"info","ts":"2026-07-07T07:27:45.895139Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","added-peer-id":"e9047e95cdba2056","added-peer-peer-urls":["https://199.19.213.108:2380"]} {"level":"info","ts":"2026-07-07T07:27:45.898086Z","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-07-07T07:27:45.898244Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-07-07T07:27:45.898315Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.108:2380"} {"level":"info","ts":"2026-07-07T07:27:45.898605Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e9047e95cdba2056","initial-advertise-peer-urls":["https://199.19.213.108:2380"],"listen-peer-urls":["https://199.19.213.108:2380"],"advertise-client-urls":["https://199.19.213.108:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.108:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T07:27:45.898674Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T07:27:46.572259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T07:27:46.572332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T07:27:46.572359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgPreVoteResp from e9047e95cdba2056 at term 1"} {"level":"info","ts":"2026-07-07T07:27:46.572378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became candidate at term 2"} {"level":"info","ts":"2026-07-07T07:27:46.572388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 received MsgVoteResp from e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-07-07T07:27:46.572409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e9047e95cdba2056 became leader at term 2"} {"level":"info","ts":"2026-07-07T07:27:46.572423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e9047e95cdba2056 elected leader e9047e95cdba2056 at term 2"} {"level":"info","ts":"2026-07-07T07:27:46.574147Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e9047e95cdba2056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.108:2379]}","request-path":"/0/members/e9047e95cdba2056/attributes","cluster-id":"51f5e218724a48a2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T07:27:46.574476Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:27:46.575585Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T07:27:46.576071Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T07:27:46.576222Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"51f5e218724a48a2","local-member-id":"e9047e95cdba2056","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:27:46.576301Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:27:46.57636Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:27:46.576406Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T07:27:46.576427Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T07:27:46.578587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.108:2379"} {"level":"info","ts":"2026-07-07T07:27:46.578854Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T07:27:56.774843Z","caller":"traceutil/trace.go:171","msg":"trace[1838173460] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"201.610762ms","start":"2026-07-07T07:27:56.573197Z","end":"2026-07-07T07:27:56.774808Z","steps":["trace[1838173460] 'read index received' (duration: 117.725172ms)","trace[1838173460] 'applied index is now lower than readState.Index' (duration: 83.88389ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:56.775049Z","caller":"traceutil/trace.go:171","msg":"trace[2143092743] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"212.70539ms","start":"2026-07-07T07:27:56.562278Z","end":"2026-07-07T07:27:56.774984Z","steps":["trace[2143092743] 'process raft request' (duration: 128.610605ms)","trace[2143092743] 'compare' (duration: 83.702245ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:27:56.775366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.198134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-07-07T07:27:56.775343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.105124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-07T07:27:56.77543Z","caller":"traceutil/trace.go:171","msg":"trace[1572579407] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:306; }","duration":"153.316517ms","start":"2026-07-07T07:27:56.622098Z","end":"2026-07-07T07:27:56.775415Z","steps":["trace[1572579407] 'agreement among raft nodes before linearized reading' (duration: 153.171983ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:56.775464Z","caller":"traceutil/trace.go:171","msg":"trace[1997162321] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:306; }","duration":"202.283269ms","start":"2026-07-07T07:27:56.573147Z","end":"2026-07-07T07:27:56.77543Z","steps":["trace[1997162321] 'agreement among raft nodes before linearized reading' (duration: 201.868628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:56.775809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.339474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-07T07:27:56.775953Z","caller":"traceutil/trace.go:171","msg":"trace[120416523] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:306; }","duration":"102.505998ms","start":"2026-07-07T07:27:56.673418Z","end":"2026-07-07T07:27:56.775924Z","steps":["trace[120416523] 'agreement among raft nodes before linearized reading' (duration: 102.346104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:57.063662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.342797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-07-07T07:27:57.063886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.822414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-0zokyx\" ","response":"range_response_count:1 size:540"} {"level":"warn","ts":"2026-07-07T07:27:57.063882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.237759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-07-07T07:27:57.06398Z","caller":"traceutil/trace.go:171","msg":"trace[1323872376] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-0zokyx; range_end:; response_count:1; response_revision:307; }","duration":"126.915526ms","start":"2026-07-07T07:27:56.937024Z","end":"2026-07-07T07:27:57.06394Z","steps":["trace[1323872376] 'range keys from in-memory index tree' (duration: 126.746073ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:57.06402Z","caller":"traceutil/trace.go:171","msg":"trace[389752740] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:307; }","duration":"126.377113ms","start":"2026-07-07T07:27:56.937584Z","end":"2026-07-07T07:27:57.063961Z","steps":["trace[389752740] 'range keys from in-memory index tree' (duration: 126.113076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:57.064147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.118786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-07-07T07:27:57.064215Z","caller":"traceutil/trace.go:171","msg":"trace[759964205] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:307; }","duration":"126.281401ms","start":"2026-07-07T07:27:56.937914Z","end":"2026-07-07T07:27:57.064196Z","steps":["trace[759964205] 'range keys from in-memory index tree' (duration: 125.903621ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:57.063894Z","caller":"traceutil/trace.go:171","msg":"trace[716771607] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:307; }","duration":"125.622204ms","start":"2026-07-07T07:27:56.938246Z","end":"2026-07-07T07:27:57.063869Z","steps":["trace[716771607] 'range keys from in-memory index tree' (duration: 125.155322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:57.0642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.717694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-07T07:27:57.065064Z","caller":"traceutil/trace.go:171","msg":"trace[1115317211] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:307; }","duration":"141.631577ms","start":"2026-07-07T07:27:56.923408Z","end":"2026-07-07T07:27:57.06504Z","steps":["trace[1115317211] 'range keys from in-memory index tree' (duration: 140.58909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:57.064256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.791478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-07-07T07:27:57.06529Z","caller":"traceutil/trace.go:171","msg":"trace[232847625] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:307; }","duration":"126.817084ms","start":"2026-07-07T07:27:56.938421Z","end":"2026-07-07T07:27:57.065238Z","steps":["trace[232847625] 'range keys from in-memory index tree' (duration: 125.524041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:57.063788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.569918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-07-07T07:27:57.0681Z","caller":"traceutil/trace.go:171","msg":"trace[830775767] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:307; }","duration":"130.965798ms","start":"2026-07-07T07:27:56.937109Z","end":"2026-07-07T07:27:57.068075Z","steps":["trace[830775767] 'range keys from in-memory index tree' (duration: 126.313231ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:57.200221Z","caller":"traceutil/trace.go:171","msg":"trace[2022611877] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:316; }","duration":"126.476549ms","start":"2026-07-07T07:27:57.073717Z","end":"2026-07-07T07:27:57.200194Z","steps":["trace[2022611877] 'read index received' (duration: 56.172147ms)","trace[2022611877] 'applied index is now lower than readState.Index' (duration: 70.303432ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:57.200353Z","caller":"traceutil/trace.go:171","msg":"trace[1199801319] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"124.042208ms","start":"2026-07-07T07:27:57.076301Z","end":"2026-07-07T07:27:57.200343Z","steps":["trace[1199801319] 'process raft request' (duration: 123.849994ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:57.200349Z","caller":"traceutil/trace.go:171","msg":"trace[563644842] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"124.590201ms","start":"2026-07-07T07:27:57.075714Z","end":"2026-07-07T07:27:57.200304Z","steps":["trace[563644842] 'process raft request' (duration: 124.298384ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:57.200336Z","caller":"traceutil/trace.go:171","msg":"trace[265345529] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"126.665923ms","start":"2026-07-07T07:27:57.073638Z","end":"2026-07-07T07:27:57.200304Z","steps":["trace[265345529] 'process raft request' (duration: 101.735139ms)","trace[265345529] 'compare' (duration: 24.472953ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:27:57.200612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.875019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-07T07:27:57.200659Z","caller":"traceutil/trace.go:171","msg":"trace[1196984181] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:311; }","duration":"126.946651ms","start":"2026-07-07T07:27:57.073702Z","end":"2026-07-07T07:27:57.200648Z","steps":["trace[1196984181] 'agreement among raft nodes before linearized reading' (duration: 126.840028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:57.200829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.960951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-07-07T07:27:57.200891Z","caller":"traceutil/trace.go:171","msg":"trace[2144973936] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"127.049424ms","start":"2026-07-07T07:27:57.07382Z","end":"2026-07-07T07:27:57.200869Z","steps":["trace[2144973936] 'agreement among raft nodes before linearized reading' (duration: 126.91941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.050034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.511769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:27:58.050575Z","caller":"traceutil/trace.go:171","msg":"trace[1864854237] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:321; }","duration":"827.739445ms","start":"2026-07-07T07:27:57.222806Z","end":"2026-07-07T07:27:58.050545Z","steps":["trace[1864854237] 'read index received' (duration: 43.866749ms)","trace[1864854237] 'applied index is now lower than readState.Index' (duration: 783.868926ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:58.050636Z","caller":"traceutil/trace.go:171","msg":"trace[834295139] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"835.337525ms","start":"2026-07-07T07:27:57.215163Z","end":"2026-07-07T07:27:58.0505Z","steps":["trace[834295139] 'process raft request' (duration: 293.133024ms)","trace[834295139] 'compare' (duration: 533.37401ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:27:58.050828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.000471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-07T07:27:58.05086Z","caller":"traceutil/trace.go:171","msg":"trace[321537562] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:317; }","duration":"828.076514ms","start":"2026-07-07T07:27:57.222775Z","end":"2026-07-07T07:27:58.050852Z","steps":["trace[321537562] 'agreement among raft nodes before linearized reading' (duration: 827.91145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.050892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.222759Z","time spent":"828.124435ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-07T07:27:58.051046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.023872ms","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-07-07T07:27:58.051066Z","caller":"traceutil/trace.go:171","msg":"trace[1441022618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:319; }","duration":"106.071564ms","start":"2026-07-07T07:27:57.944987Z","end":"2026-07-07T07:27:58.051059Z","steps":["trace[1441022618] 'agreement among raft nodes before linearized reading' (duration: 106.018763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.051083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.319086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-07-07T07:27:58.051166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.215153Z","time spent":"835.767405ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:27:58.051186Z","caller":"traceutil/trace.go:171","msg":"trace[449958187] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:319; }","duration":"778.492011ms","start":"2026-07-07T07:27:57.272672Z","end":"2026-07-07T07:27:58.051164Z","steps":["trace[449958187] 'agreement among raft nodes before linearized reading' (duration: 778.250074ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.051216Z","caller":"traceutil/trace.go:171","msg":"trace[310120258] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"779.844655ms","start":"2026-07-07T07:27:57.271361Z","end":"2026-07-07T07:27:58.051205Z","steps":["trace[310120258] 'process raft request' (duration: 779.27555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.051232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.272644Z","time spent":"778.579553ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-07T07:27:58.051284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.271335Z","time spent":"779.906767ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T07:27:58.051483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.625224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-07-07T07:27:58.051572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.030737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-07T07:27:58.051577Z","caller":"traceutil/trace.go:171","msg":"trace[107270432] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:319; }","duration":"586.761497ms","start":"2026-07-07T07:27:57.464792Z","end":"2026-07-07T07:27:58.051553Z","steps":["trace[107270432] 'agreement among raft nodes before linearized reading' (duration: 586.560722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.051556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.334811ms","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-07-07T07:27:58.051623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.464772Z","time spent":"586.843819ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-07T07:27:58.051526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.502935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-07T07:27:58.05177Z","caller":"traceutil/trace.go:171","msg":"trace[1518560515] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:319; }","duration":"678.791952ms","start":"2026-07-07T07:27:57.372964Z","end":"2026-07-07T07:27:58.051756Z","steps":["trace[1518560515] 'agreement among raft nodes before linearized reading' (duration: 678.531966ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.051639Z","caller":"traceutil/trace.go:171","msg":"trace[1523278810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:319; }","duration":"415.449364ms","start":"2026-07-07T07:27:57.636181Z","end":"2026-07-07T07:27:58.05163Z","steps":["trace[1523278810] 'agreement among raft nodes before linearized reading' (duration: 415.318701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.05221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.636157Z","time spent":"415.952407ms","remote":"127.0.0.1:55204","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-07-07T07:27:58.051479Z","caller":"traceutil/trace.go:171","msg":"trace[1161132063] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"774.657724ms","start":"2026-07-07T07:27:57.276812Z","end":"2026-07-07T07:27:58.051469Z","steps":["trace[1161132063] 'process raft request' (duration: 774.012158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.052698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.276792Z","time spent":"775.613829ms","remote":"127.0.0.1:55200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:27:58.051611Z","caller":"traceutil/trace.go:171","msg":"trace[1888116476] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:319; }","duration":"728.087798ms","start":"2026-07-07T07:27:57.323515Z","end":"2026-07-07T07:27:58.051603Z","steps":["trace[1888116476] 'agreement among raft nodes before linearized reading' (duration: 728.024596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.052855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.323497Z","time spent":"729.35123ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-07T07:27:58.05218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:57.372926Z","time spent":"679.014088ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-07-07T07:27:58.344922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.247999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:27:58.34516Z","caller":"traceutil/trace.go:171","msg":"trace[1758868161] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"288.762428ms","start":"2026-07-07T07:27:58.056367Z","end":"2026-07-07T07:27:58.34513Z","steps":["trace[1758868161] 'process raft request' (duration: 86.200092ms)","trace[1758868161] 'compare' (duration: 202.056703ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:58.345315Z","caller":"traceutil/trace.go:171","msg":"trace[150927126] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"288.635964ms","start":"2026-07-07T07:27:58.056659Z","end":"2026-07-07T07:27:58.345295Z","steps":["trace[150927126] 'process raft request' (duration: 288.456149ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.345352Z","caller":"traceutil/trace.go:171","msg":"trace[2004972336] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"284.931692ms","start":"2026-07-07T07:27:58.060377Z","end":"2026-07-07T07:27:58.345309Z","steps":["trace[2004972336] 'process raft request' (duration: 284.772238ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.347221Z","caller":"traceutil/trace.go:171","msg":"trace[336558950] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"284.803389ms","start":"2026-07-07T07:27:58.060725Z","end":"2026-07-07T07:27:58.345529Z","steps":["trace[336558950] 'process raft request' (duration: 284.4693ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.345352Z","caller":"traceutil/trace.go:171","msg":"trace[1481663727] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"288.738637ms","start":"2026-07-07T07:27:58.056571Z","end":"2026-07-07T07:27:58.345309Z","steps":["trace[1481663727] 'process raft request' (duration: 288.487661ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.349695Z","caller":"traceutil/trace.go:171","msg":"trace[1540751027] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"287.779293ms","start":"2026-07-07T07:27:58.061883Z","end":"2026-07-07T07:27:58.349662Z","steps":["trace[1540751027] 'process raft request' (duration: 283.354742ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.350711Z","caller":"traceutil/trace.go:171","msg":"trace[209963852] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"288.507421ms","start":"2026-07-07T07:27:58.062175Z","end":"2026-07-07T07:27:58.350682Z","steps":["trace[209963852] 'process raft request' (duration: 286.011059ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.351715Z","caller":"traceutil/trace.go:171","msg":"trace[1390416569] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"289.490445ms","start":"2026-07-07T07:27:58.062189Z","end":"2026-07-07T07:27:58.35168Z","steps":["trace[1390416569] 'process raft request' (duration: 288.015788ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.352158Z","caller":"traceutil/trace.go:171","msg":"trace[369423478] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:328; }","duration":"281.399674ms","start":"2026-07-07T07:27:58.070669Z","end":"2026-07-07T07:27:58.352069Z","steps":["trace[369423478] 'read index received' (duration: 71.849873ms)","trace[369423478] 'applied index is now lower than readState.Index' (duration: 209.289174ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:58.352503Z","caller":"traceutil/trace.go:171","msg":"trace[1408135101] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"289.27322ms","start":"2026-07-07T07:27:58.063198Z","end":"2026-07-07T07:27:58.352471Z","steps":["trace[1408135101] 'process raft request' (duration: 288.523961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.354593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.210803ms","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-07-07T07:27:58.354673Z","caller":"traceutil/trace.go:171","msg":"trace[1891218541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:329; }","duration":"169.336436ms","start":"2026-07-07T07:27:58.185309Z","end":"2026-07-07T07:27:58.354646Z","steps":["trace[1891218541] 'agreement among raft nodes before linearized reading' (duration: 168.952107ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.357123Z","caller":"traceutil/trace.go:171","msg":"trace[2059782956] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"294.868559ms","start":"2026-07-07T07:27:58.062235Z","end":"2026-07-07T07:27:58.357103Z","steps":["trace[2059782956] 'process raft request' (duration: 289.219799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.357466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.793128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-07T07:27:58.357501Z","caller":"traceutil/trace.go:171","msg":"trace[1809369485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:329; }","duration":"286.87436ms","start":"2026-07-07T07:27:58.070616Z","end":"2026-07-07T07:27:58.357491Z","steps":["trace[1809369485] 'agreement among raft nodes before linearized reading' (duration: 283.927357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.680516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.311281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:27:58.68064Z","caller":"traceutil/trace.go:171","msg":"trace[1405795908] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"323.206428ms","start":"2026-07-07T07:27:58.357412Z","end":"2026-07-07T07:27:58.680619Z","steps":["trace[1405795908] 'process raft request' (duration: 152.707892ms)","trace[1405795908] 'compare' (duration: 170.164838ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:27:58.680849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.357393Z","time spent":"323.407513ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:27:58.71186Z","caller":"traceutil/trace.go:171","msg":"trace[1428864571] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"354.421687ms","start":"2026-07-07T07:27:58.357411Z","end":"2026-07-07T07:27:58.711833Z","steps":["trace[1428864571] 'process raft request' (duration: 354.249653ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.711923Z","caller":"traceutil/trace.go:171","msg":"trace[696021269] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"354.341215ms","start":"2026-07-07T07:27:58.357549Z","end":"2026-07-07T07:27:58.71189Z","steps":["trace[696021269] 'process raft request' (duration: 354.230532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.712004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.357365Z","time spent":"354.571731ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T07:27:58.712155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.310484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-07-07T07:27:58.712214Z","caller":"traceutil/trace.go:171","msg":"trace[1955846373] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:335; }","duration":"354.395916ms","start":"2026-07-07T07:27:58.357804Z","end":"2026-07-07T07:27:58.7122Z","steps":["trace[1955846373] 'agreement among raft nodes before linearized reading' (duration: 354.241672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.712248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.357761Z","time spent":"354.479168ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-07-07T07:27:58.712219Z","caller":"traceutil/trace.go:171","msg":"trace[716561284] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"350.96629ms","start":"2026-07-07T07:27:58.361196Z","end":"2026-07-07T07:27:58.712162Z","steps":["trace[716561284] 'process raft request' (duration: 350.741605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.712245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.357538Z","time spent":"354.498079ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:27:58.71234Z","caller":"traceutil/trace.go:171","msg":"trace[1151309126] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"325.7134ms","start":"2026-07-07T07:27:58.386616Z","end":"2026-07-07T07:27:58.71233Z","steps":["trace[1151309126] 'process raft request' (duration: 325.356802ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:58.712304Z","caller":"traceutil/trace.go:171","msg":"trace[1414758679] transaction","detail":"{read_only:false; number_of_response:1; response_revision:332; }","duration":"351.530555ms","start":"2026-07-07T07:27:58.360642Z","end":"2026-07-07T07:27:58.712173Z","steps":["trace[1414758679] 'process raft request' (duration: 351.183106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.712409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.386581Z","time spent":"325.789613ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:27:58.71202Z","caller":"traceutil/trace.go:171","msg":"trace[836361486] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:338; }","duration":"354.196151ms","start":"2026-07-07T07:27:58.357809Z","end":"2026-07-07T07:27:58.712005Z","steps":["trace[836361486] 'read index received' (duration: 152.414895ms)","trace[836361486] 'applied index is now lower than readState.Index' (duration: 201.780336ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:27:58.712525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.360634Z","time spent":"351.836382ms","remote":"127.0.0.1:55272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31,"response count":0,"response size":2239,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:27:58.712555Z","caller":"traceutil/trace.go:171","msg":"trace[260821120] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"351.655598ms","start":"2026-07-07T07:27:58.360859Z","end":"2026-07-07T07:27:58.712515Z","steps":["trace[260821120] 'process raft request' (duration: 351.028052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.712784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.399817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:913"} {"level":"warn","ts":"2026-07-07T07:27:58.712809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.435332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-07T07:27:58.71282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.360852Z","time spent":"351.863323ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:27:58.712853Z","caller":"traceutil/trace.go:171","msg":"trace[1936675197] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:335; }","duration":"352.499109ms","start":"2026-07-07T07:27:58.360334Z","end":"2026-07-07T07:27:58.712833Z","steps":["trace[1936675197] 'agreement among raft nodes before linearized reading' (duration: 352.351475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.712903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.360322Z","time spent":"352.571901ms","remote":"127.0.0.1:55272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":936,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-07-07T07:27:58.712475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.361189Z","time spent":"351.157425ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:27:58.712899Z","caller":"traceutil/trace.go:171","msg":"trace[1116733805] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:335; }","duration":"353.523864ms","start":"2026-07-07T07:27:58.359325Z","end":"2026-07-07T07:27:58.712849Z","steps":["trace[1116733805] 'agreement among raft nodes before linearized reading' (duration: 353.35491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:58.71307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:27:58.359292Z","time spent":"353.72229ms","remote":"127.0.0.1:55130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-07-07T07:27:58.955474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.483562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:27:58.95567Z","caller":"traceutil/trace.go:171","msg":"trace[1340219035] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"236.867953ms","start":"2026-07-07T07:27:58.718771Z","end":"2026-07-07T07:27:58.955639Z","steps":["trace[1340219035] 'process raft request' (duration: 121.089873ms)","trace[1340219035] 'compare' (duration: 115.234176ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:59.00812Z","caller":"traceutil/trace.go:171","msg":"trace[5021058] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"288.003559ms","start":"2026-07-07T07:27:58.720092Z","end":"2026-07-07T07:27:59.008095Z","steps":["trace[5021058] 'process raft request' (duration: 287.65255ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.0081Z","caller":"traceutil/trace.go:171","msg":"trace[1841676135] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"288.186563ms","start":"2026-07-07T07:27:58.719879Z","end":"2026-07-07T07:27:59.008065Z","steps":["trace[1841676135] 'process raft request' (duration: 287.636839ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.009104Z","caller":"traceutil/trace.go:171","msg":"trace[1538045018] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"288.49547ms","start":"2026-07-07T07:27:58.720544Z","end":"2026-07-07T07:27:59.00904Z","steps":["trace[1538045018] 'process raft request' (duration: 288.215213ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.00948Z","caller":"traceutil/trace.go:171","msg":"trace[2037339887] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"286.424059ms","start":"2026-07-07T07:27:58.72304Z","end":"2026-07-07T07:27:59.009464Z","steps":["trace[2037339887] 'process raft request' (duration: 286.369887ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.009692Z","caller":"traceutil/trace.go:171","msg":"trace[1959674951] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"287.634279ms","start":"2026-07-07T07:27:58.722046Z","end":"2026-07-07T07:27:59.00968Z","steps":["trace[1959674951] 'process raft request' (duration: 287.26398ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.009737Z","caller":"traceutil/trace.go:171","msg":"trace[768999365] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"287.451394ms","start":"2026-07-07T07:27:58.72227Z","end":"2026-07-07T07:27:59.009722Z","steps":["trace[768999365] 'process raft request' (duration: 287.101625ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.010113Z","caller":"traceutil/trace.go:171","msg":"trace[1939078809] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"288.976852ms","start":"2026-07-07T07:27:58.721113Z","end":"2026-07-07T07:27:59.01009Z","steps":["trace[1939078809] 'process raft request' (duration: 287.814473ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.117607Z","caller":"traceutil/trace.go:171","msg":"trace[1341910672] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"102.087429ms","start":"2026-07-07T07:27:59.015496Z","end":"2026-07-07T07:27:59.117584Z","steps":["trace[1341910672] 'read index received' (duration: 87.078715ms)","trace[1341910672] 'applied index is now lower than readState.Index' (duration: 15.007334ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:59.117698Z","caller":"traceutil/trace.go:171","msg":"trace[760512801] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"156.441724ms","start":"2026-07-07T07:27:58.961199Z","end":"2026-07-07T07:27:59.11764Z","steps":["trace[760512801] 'process raft request' (duration: 141.450911ms)","trace[760512801] 'compare' (duration: 14.790488ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:27:59.117804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.281744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-2w8dt\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-07-07T07:27:59.11784Z","caller":"traceutil/trace.go:171","msg":"trace[1512463414] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-2w8dt; range_end:; response_count:1; response_revision:344; }","duration":"102.381926ms","start":"2026-07-07T07:27:59.015448Z","end":"2026-07-07T07:27:59.11783Z","steps":["trace[1512463414] 'agreement among raft nodes before linearized reading' (duration: 102.251743ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.292385Z","caller":"traceutil/trace.go:171","msg":"trace[1916784944] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"276.233489ms","start":"2026-07-07T07:27:59.016077Z","end":"2026-07-07T07:27:59.292311Z","steps":["trace[1916784944] 'process raft request' (duration: 204.328631ms)","trace[1916784944] 'compare' (duration: 71.560509ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:59.292539Z","caller":"traceutil/trace.go:171","msg":"trace[1302542515] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"265.78741ms","start":"2026-07-07T07:27:59.026736Z","end":"2026-07-07T07:27:59.292523Z","steps":["trace[1302542515] 'process raft request' (duration: 265.684028ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.292517Z","caller":"traceutil/trace.go:171","msg":"trace[402137658] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"274.525567ms","start":"2026-07-07T07:27:59.017936Z","end":"2026-07-07T07:27:59.292461Z","steps":["trace[402137658] 'process raft request' (duration: 274.315941ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.292557Z","caller":"traceutil/trace.go:171","msg":"trace[1905115223] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"274.341022ms","start":"2026-07-07T07:27:59.018175Z","end":"2026-07-07T07:27:59.292516Z","steps":["trace[1905115223] 'process raft request' (duration: 274.180538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:59.292659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.759559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-07-07T07:27:59.292822Z","caller":"traceutil/trace.go:171","msg":"trace[846481003] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:348; }","duration":"187.920423ms","start":"2026-07-07T07:27:59.104888Z","end":"2026-07-07T07:27:59.292808Z","steps":["trace[846481003] 'agreement among raft nodes before linearized reading' (duration: 187.722928ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.2925Z","caller":"traceutil/trace.go:171","msg":"trace[1356101248] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:354; }","duration":"174.799027ms","start":"2026-07-07T07:27:59.11767Z","end":"2026-07-07T07:27:59.292469Z","steps":["trace[1356101248] 'read index received' (duration: 102.752255ms)","trace[1356101248] 'applied index is now lower than readState.Index' (duration: 72.044992ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:27:59.292659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.109202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-07-07T07:27:59.292942Z","caller":"traceutil/trace.go:171","msg":"trace[537529180] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:348; }","duration":"275.421839ms","start":"2026-07-07T07:27:59.017504Z","end":"2026-07-07T07:27:59.292925Z","steps":["trace[537529180] 'agreement among raft nodes before linearized reading' (duration: 275.06021ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.333958Z","caller":"traceutil/trace.go:171","msg":"trace[928842117] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"207.740617ms","start":"2026-07-07T07:27:59.126178Z","end":"2026-07-07T07:27:59.333918Z","steps":["trace[928842117] 'process raft request' (duration: 207.588123ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.333978Z","caller":"traceutil/trace.go:171","msg":"trace[1489612299] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"211.026268ms","start":"2026-07-07T07:27:59.122912Z","end":"2026-07-07T07:27:59.333939Z","steps":["trace[1489612299] 'process raft request' (duration: 210.563497ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.480424Z","caller":"traceutil/trace.go:171","msg":"trace[221011586] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:360; }","duration":"146.546974ms","start":"2026-07-07T07:27:59.33386Z","end":"2026-07-07T07:27:59.480407Z","steps":["trace[221011586] 'read index received' (duration: 109.917813ms)","trace[221011586] 'applied index is now lower than readState.Index' (duration: 36.628531ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:59.480476Z","caller":"traceutil/trace.go:171","msg":"trace[1749919661] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"179.373651ms","start":"2026-07-07T07:27:59.301061Z","end":"2026-07-07T07:27:59.480435Z","steps":["trace[1749919661] 'process raft request' (duration: 179.267468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:59.480546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.293268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-07-07T07:27:59.480568Z","caller":"traceutil/trace.go:171","msg":"trace[664240933] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:353; }","duration":"183.38101ms","start":"2026-07-07T07:27:59.29718Z","end":"2026-07-07T07:27:59.480561Z","steps":["trace[664240933] 'agreement among raft nodes before linearized reading' (duration: 183.305188ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.480621Z","caller":"traceutil/trace.go:171","msg":"trace[1853029087] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"178.262762ms","start":"2026-07-07T07:27:59.302323Z","end":"2026-07-07T07:27:59.480585Z","steps":["trace[1853029087] 'process raft request' (duration: 178.055217ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:59.480795Z","caller":"traceutil/trace.go:171","msg":"trace[52710794] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"182.99503ms","start":"2026-07-07T07:27:59.297792Z","end":"2026-07-07T07:27:59.480787Z","steps":["trace[52710794] 'process raft request' (duration: 145.97109ms)","trace[52710794] 'compare' (duration: 36.443826ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:59.526578Z","caller":"traceutil/trace.go:171","msg":"trace[2055175562] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"185.993305ms","start":"2026-07-07T07:27:59.340545Z","end":"2026-07-07T07:27:59.526538Z","steps":["trace[2055175562] 'process raft request' (duration: 185.839132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:59.753667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.928982ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-07T07:27:59.754072Z","caller":"traceutil/trace.go:171","msg":"trace[1326747874] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"170.120191ms","start":"2026-07-07T07:27:59.583933Z","end":"2026-07-07T07:27:59.754053Z","steps":["trace[1326747874] 'process raft request' (duration: 169.842724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:04.892978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224935006163293,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T07:28:05.030502Z","caller":"traceutil/trace.go:171","msg":"trace[171483604] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"638.268159ms","start":"2026-07-07T07:28:04.392201Z","end":"2026-07-07T07:28:05.030469Z","steps":["trace[171483604] 'read index received' (duration: 637.953831ms)","trace[171483604] 'applied index is now lower than readState.Index' (duration: 312.438µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:28:05.030609Z","caller":"traceutil/trace.go:171","msg":"trace[1656626118] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"644.410999ms","start":"2026-07-07T07:28:04.38613Z","end":"2026-07-07T07:28:05.030541Z","steps":["trace[1656626118] 'process raft request' (duration: 644.027169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:05.03079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.546045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-07T07:28:05.030859Z","caller":"traceutil/trace.go:171","msg":"trace[244786524] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:376; }","duration":"638.691189ms","start":"2026-07-07T07:28:04.392147Z","end":"2026-07-07T07:28:05.030839Z","steps":["trace[244786524] 'agreement among raft nodes before linearized reading' (duration: 638.467503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:05.030929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:28:04.392104Z","time spent":"638.810162ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-07-07T07:28:05.031139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:28:04.386105Z","time spent":"644.793687ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:28:05.087015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.900333ms","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-07-07T07:28:05.08714Z","caller":"traceutil/trace.go:171","msg":"trace[362649360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"685.068798ms","start":"2026-07-07T07:28:04.402034Z","end":"2026-07-07T07:28:05.087102Z","steps":["trace[362649360] 'agreement among raft nodes before linearized reading' (duration: 684.7702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:05.087198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:28:04.40201Z","time spent":"685.177281ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T07:28:05.087206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.274782ms","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-07-07T07:28:05.087286Z","caller":"traceutil/trace.go:171","msg":"trace[1079272293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"336.352893ms","start":"2026-07-07T07:28:04.750902Z","end":"2026-07-07T07:28:05.087255Z","steps":["trace[1079272293] 'agreement among raft nodes before linearized reading' (duration: 336.271021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:05.087331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:28:04.750891Z","time spent":"336.432286ms","remote":"127.0.0.1:55204","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-07-07T07:28:05.087557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.166495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T07:28:05.087108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.586074ms","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-07-07T07:28:05.087587Z","caller":"traceutil/trace.go:171","msg":"trace[1915469464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"524.238356ms","start":"2026-07-07T07:28:04.563338Z","end":"2026-07-07T07:28:05.087577Z","steps":["trace[1915469464] 'agreement among raft nodes before linearized reading' (duration: 524.195385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:05.087608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:28:04.563313Z","time spent":"524.291598ms","remote":"127.0.0.1:54926","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-07-07T07:28:05.087622Z","caller":"traceutil/trace.go:171","msg":"trace[1342417851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"338.177109ms","start":"2026-07-07T07:28:04.749423Z","end":"2026-07-07T07:28:05.0876Z","steps":["trace[1342417851] 'agreement among raft nodes before linearized reading' (duration: 337.41088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:05.087669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:28:04.749396Z","time spent":"338.26146ms","remote":"127.0.0.1:55204","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-07-07T07:28:05.087851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.314391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:28:05.087888Z","caller":"traceutil/trace.go:171","msg":"trace[1371643241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"347.410194ms","start":"2026-07-07T07:28:04.740469Z","end":"2026-07-07T07:28:05.087879Z","steps":["trace[1371643241] 'agreement among raft nodes before linearized reading' (duration: 347.346182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:05.087922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:28:04.74043Z","time spent":"347.475265ms","remote":"127.0.0.1:54910","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-07-07T07:28:05.28273Z","caller":"traceutil/trace.go:171","msg":"trace[2065101704] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"189.18996ms","start":"2026-07-07T07:28:05.093512Z","end":"2026-07-07T07:28:05.282702Z","steps":["trace[2065101704] 'process raft request' (duration: 161.225748ms)","trace[2065101704] 'compare' (duration: 27.757127ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:28:05.283218Z","caller":"traceutil/trace.go:171","msg":"trace[1123226897] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"189.465767ms","start":"2026-07-07T07:28:05.093734Z","end":"2026-07-07T07:28:05.2832Z","steps":["trace[1123226897] 'process raft request' (duration: 188.926664ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:05.329222Z","caller":"traceutil/trace.go:171","msg":"trace[1447694208] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"192.357788ms","start":"2026-07-07T07:28:05.136829Z","end":"2026-07-07T07:28:05.329186Z","steps":["trace[1447694208] 'process raft request' (duration: 192.227714ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.326157Z","caller":"traceutil/trace.go:171","msg":"trace[2018033373] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"265.761471ms","start":"2026-07-07T07:28:32.060357Z","end":"2026-07-07T07:28:32.326118Z","steps":["trace[2018033373] 'process raft request' (duration: 265.535366ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.341917Z","caller":"traceutil/trace.go:171","msg":"trace[1961718475] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"186.426896ms","start":"2026-07-07T07:28:32.155432Z","end":"2026-07-07T07:28:32.341859Z","steps":["trace[1961718475] 'process raft request' (duration: 186.14426ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.342635Z","caller":"traceutil/trace.go:171","msg":"trace[993137193] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"171.553884ms","start":"2026-07-07T07:28:32.171038Z","end":"2026-07-07T07:28:32.342592Z","steps":["trace[993137193] 'process raft request' (duration: 170.817827ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.342687Z","caller":"traceutil/trace.go:171","msg":"trace[1622338773] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"182.641569ms","start":"2026-07-07T07:28:32.159988Z","end":"2026-07-07T07:28:32.34263Z","steps":["trace[1622338773] 'process raft request' (duration: 181.7925ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.343044Z","caller":"traceutil/trace.go:171","msg":"trace[1057932410] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"184.172374ms","start":"2026-07-07T07:28:32.158664Z","end":"2026-07-07T07:28:32.342836Z","steps":["trace[1057932410] 'process raft request' (duration: 183.084539ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.34307Z","caller":"traceutil/trace.go:171","msg":"trace[620220427] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"169.125348ms","start":"2026-07-07T07:28:32.173729Z","end":"2026-07-07T07:28:32.342855Z","steps":["trace[620220427] 'process raft request' (duration: 168.161436ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.343227Z","caller":"traceutil/trace.go:171","msg":"trace[665978034] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"171.858721ms","start":"2026-07-07T07:28:32.170947Z","end":"2026-07-07T07:28:32.342806Z","steps":["trace[665978034] 'process raft request' (duration: 170.865808ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.343351Z","caller":"traceutil/trace.go:171","msg":"trace[173916862] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"167.733946ms","start":"2026-07-07T07:28:32.175424Z","end":"2026-07-07T07:28:32.343157Z","steps":["trace[173916862] 'process raft request' (duration: 166.549029ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.343717Z","caller":"traceutil/trace.go:171","msg":"trace[1151231537] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"169.58332ms","start":"2026-07-07T07:28:32.173937Z","end":"2026-07-07T07:28:32.34352Z","steps":["trace[1151231537] 'process raft request' (duration: 167.991603ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.34306Z","caller":"traceutil/trace.go:171","msg":"trace[1101403339] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"149.395415ms","start":"2026-07-07T07:28:32.193478Z","end":"2026-07-07T07:28:32.342874Z","steps":["trace[1101403339] 'process raft request' (duration: 148.532885ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.344737Z","caller":"traceutil/trace.go:171","msg":"trace[1603674056] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"115.964346ms","start":"2026-07-07T07:28:32.228746Z","end":"2026-07-07T07:28:32.34471Z","steps":["trace[1603674056] 'process raft request' (duration: 115.867734ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:32.344856Z","caller":"traceutil/trace.go:171","msg":"trace[634149769] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"135.417514ms","start":"2026-07-07T07:28:32.209427Z","end":"2026-07-07T07:28:32.344844Z","steps":["trace[634149769] 'process raft request' (duration: 133.232184ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:35.736743Z","caller":"traceutil/trace.go:171","msg":"trace[2118633459] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"118.743709ms","start":"2026-07-07T07:28:35.617963Z","end":"2026-07-07T07:28:35.736706Z","steps":["trace[2118633459] 'process raft request' (duration: 118.275158ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:38.980512Z","caller":"traceutil/trace.go:171","msg":"trace[1900829634] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"120.384277ms","start":"2026-07-07T07:28:38.860093Z","end":"2026-07-07T07:28:38.980477Z","steps":["trace[1900829634] 'process raft request' (duration: 119.62054ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:39.589034Z","caller":"traceutil/trace.go:171","msg":"trace[458187482] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"229.747497ms","start":"2026-07-07T07:28:39.359237Z","end":"2026-07-07T07:28:39.588985Z","steps":["trace[458187482] 'process raft request' (duration: 208.592624ms)","trace[458187482] 'compare' (duration: 20.873736ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:28:39.610876Z","caller":"traceutil/trace.go:171","msg":"trace[1780265753] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"242.541228ms","start":"2026-07-07T07:28:39.368299Z","end":"2026-07-07T07:28:39.61084Z","steps":["trace[1780265753] 'process raft request' (duration: 242.259611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:39.796302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.979955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:28:39.796461Z","caller":"traceutil/trace.go:171","msg":"trace[1828576068] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"131.422125ms","start":"2026-07-07T07:28:39.665004Z","end":"2026-07-07T07:28:39.796426Z","steps":["trace[1828576068] 'process raft request' (duration: 25.245925ms)","trace[1828576068] 'compare' (duration: 105.843432ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:28:39.79656Z","caller":"traceutil/trace.go:171","msg":"trace[25183042] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"114.727024ms","start":"2026-07-07T07:28:39.681806Z","end":"2026-07-07T07:28:39.796533Z","steps":["trace[25183042] 'read index received' (duration: 8.422222ms)","trace[25183042] 'applied index is now lower than readState.Index' (duration: 106.301332ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:28:39.796668Z","caller":"traceutil/trace.go:171","msg":"trace[600203349] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"113.276741ms","start":"2026-07-07T07:28:39.683372Z","end":"2026-07-07T07:28:39.796649Z","steps":["trace[600203349] 'process raft request' (duration: 113.038596ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:39.796571Z","caller":"traceutil/trace.go:171","msg":"trace[1215589680] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"112.485354ms","start":"2026-07-07T07:28:39.684071Z","end":"2026-07-07T07:28:39.796557Z","steps":["trace[1215589680] 'process raft request' (duration: 112.429963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:39.796741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.919679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-07T07:28:39.796778Z","caller":"traceutil/trace.go:171","msg":"trace[1347969744] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:622; }","duration":"115.006601ms","start":"2026-07-07T07:28:39.68176Z","end":"2026-07-07T07:28:39.796767Z","steps":["trace[1347969744] 'agreement among raft nodes before linearized reading' (duration: 114.925239ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:41.485648Z","caller":"traceutil/trace.go:171","msg":"trace[1744102257] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"122.00441ms","start":"2026-07-07T07:28:41.363619Z","end":"2026-07-07T07:28:41.485623Z","steps":["trace[1744102257] 'process raft request' (duration: 121.834086ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:58.203205Z","caller":"traceutil/trace.go:171","msg":"trace[3495825] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"240.870992ms","start":"2026-07-07T07:28:57.96231Z","end":"2026-07-07T07:28:58.203181Z","steps":["trace[3495825] 'read index received' (duration: 240.673518ms)","trace[3495825] 'applied index is now lower than readState.Index' (duration: 196.134µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:28:58.203249Z","caller":"traceutil/trace.go:171","msg":"trace[1058988271] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"258.615529ms","start":"2026-07-07T07:28:57.944606Z","end":"2026-07-07T07:28:58.203222Z","steps":["trace[1058988271] 'process raft request' (duration: 258.428765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:58.203455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.064816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:28:58.203525Z","caller":"traceutil/trace.go:171","msg":"trace[2068804190] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:790; }","duration":"241.22584ms","start":"2026-07-07T07:28:57.962281Z","end":"2026-07-07T07:28:58.203507Z","steps":["trace[2068804190] 'agreement among raft nodes before linearized reading' (duration: 241.074357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:58.20362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.646246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T07:28:58.203722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.82306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:28:58.203795Z","caller":"traceutil/trace.go:171","msg":"trace[1928934951] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:790; }","duration":"222.82974ms","start":"2026-07-07T07:28:57.980884Z","end":"2026-07-07T07:28:58.203714Z","steps":["trace[1928934951] 'agreement among raft nodes before linearized reading' (duration: 222.671597ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:58.203806Z","caller":"traceutil/trace.go:171","msg":"trace[322646928] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:790; }","duration":"222.933051ms","start":"2026-07-07T07:28:57.980847Z","end":"2026-07-07T07:28:58.20378Z","steps":["trace[322646928] 'agreement among raft nodes before linearized reading' (duration: 222.794209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:58.203647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.386161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:28:58.203881Z","caller":"traceutil/trace.go:171","msg":"trace[1604722813] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:790; }","duration":"222.900171ms","start":"2026-07-07T07:28:57.980966Z","end":"2026-07-07T07:28:58.203866Z","steps":["trace[1604722813] 'agreement among raft nodes before linearized reading' (duration: 222.399931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:58.203819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.12271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:28:58.204047Z","caller":"traceutil/trace.go:171","msg":"trace[1612001434] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:790; }","duration":"116.498237ms","start":"2026-07-07T07:28:58.087531Z","end":"2026-07-07T07:28:58.204029Z","steps":["trace[1612001434] 'agreement among raft nodes before linearized reading' (duration: 116.11251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:58.203489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.137388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:28:58.204363Z","caller":"traceutil/trace.go:171","msg":"trace[1256116679] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:790; }","duration":"242.095048ms","start":"2026-07-07T07:28:57.962249Z","end":"2026-07-07T07:28:58.204344Z","steps":["trace[1256116679] 'agreement among raft nodes before linearized reading' (duration: 241.092358ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:58.581858Z","caller":"traceutil/trace.go:171","msg":"trace[1066416596] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"120.044229ms","start":"2026-07-07T07:28:58.461791Z","end":"2026-07-07T07:28:58.581835Z","steps":["trace[1066416596] 'process raft request' (duration: 119.906706ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:29:30.514346Z","caller":"traceutil/trace.go:171","msg":"trace[683143682] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"134.941503ms","start":"2026-07-07T07:29:30.379372Z","end":"2026-07-07T07:29:30.514314Z","steps":["trace[683143682] 'process raft request' (duration: 134.740529ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:29:31.207204Z","caller":"traceutil/trace.go:171","msg":"trace[1378802487] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"468.322329ms","start":"2026-07-07T07:29:30.738863Z","end":"2026-07-07T07:29:31.207185Z","steps":["trace[1378802487] 'read index received' (duration: 468.078474ms)","trace[1378802487] 'applied index is now lower than readState.Index' (duration: 242.865µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:29:31.207379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.503292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:29:31.207421Z","caller":"traceutil/trace.go:171","msg":"trace[109088018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"468.613306ms","start":"2026-07-07T07:29:30.738795Z","end":"2026-07-07T07:29:31.207409Z","steps":["trace[109088018] 'agreement among raft nodes before linearized reading' (duration: 468.513164ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:29:31.207379Z","caller":"traceutil/trace.go:171","msg":"trace[83473346] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"520.190437ms","start":"2026-07-07T07:29:30.687084Z","end":"2026-07-07T07:29:31.207274Z","steps":["trace[83473346] 'process raft request' (duration: 519.959352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:29:31.2075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.648859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:29:31.207525Z","caller":"traceutil/trace.go:171","msg":"trace[1401405143] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1199; }","duration":"342.711ms","start":"2026-07-07T07:29:30.864807Z","end":"2026-07-07T07:29:31.207518Z","steps":["trace[1401405143] 'agreement among raft nodes before linearized reading' (duration: 342.670599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:29:31.207551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:29:30.864787Z","time spent":"342.756871ms","remote":"127.0.0.1:55236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:29:31.207606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:29:30.68705Z","time spent":"520.443912ms","remote":"127.0.0.1:55204","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-07-07T07:29:31.207455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:29:30.738767Z","time spent":"468.680846ms","remote":"127.0.0.1:54926","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-07-07T07:29:40.153593Z","caller":"traceutil/trace.go:171","msg":"trace[1744262284] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"151.184432ms","start":"2026-07-07T07:29:40.002386Z","end":"2026-07-07T07:29:40.15357Z","steps":["trace[1744262284] 'process raft request' (duration: 150.991498ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:29:40.95845Z","caller":"traceutil/trace.go:171","msg":"trace[1766783046] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"106.917076ms","start":"2026-07-07T07:29:40.851514Z","end":"2026-07-07T07:29:40.958432Z","steps":["trace[1766783046] 'process raft request' (duration: 106.806044ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:29:41.051496Z","caller":"traceutil/trace.go:171","msg":"trace[1179054450] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"191.912613ms","start":"2026-07-07T07:29:40.859556Z","end":"2026-07-07T07:29:41.051469Z","steps":["trace[1179054450] 'process raft request' (duration: 168.750958ms)","trace[1179054450] 'compare' (duration: 23.040412ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:29:53.828226Z","caller":"traceutil/trace.go:171","msg":"trace[624533562] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"104.504471ms","start":"2026-07-07T07:29:53.723694Z","end":"2026-07-07T07:29:53.828199Z","steps":["trace[624533562] 'process raft request' (duration: 103.233464ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:13.786714Z","caller":"traceutil/trace.go:171","msg":"trace[297389219] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"104.796326ms","start":"2026-07-07T07:30:13.681877Z","end":"2026-07-07T07:30:13.786673Z","steps":["trace[297389219] 'process raft request' (duration: 58.466432ms)","trace[297389219] 'compare' (duration: 46.12389ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:30:25.744658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.832899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87412902-c6da-4f4e-864d-974961a53df2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-07T07:30:25.744758Z","caller":"traceutil/trace.go:171","msg":"trace[1372416849] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87412902-c6da-4f4e-864d-974961a53df2; range_end:; response_count:1; response_revision:1892; }","duration":"454.96353ms","start":"2026-07-07T07:30:25.289758Z","end":"2026-07-07T07:30:25.744721Z","steps":["trace[1372416849] 'range keys from in-memory index tree' (duration: 454.684495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:25.74477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.413498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"warn","ts":"2026-07-07T07:30:25.744799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:30:25.289742Z","time spent":"455.045173ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87412902-c6da-4f4e-864d-974961a53df2\" "} {"level":"info","ts":"2026-07-07T07:30:25.744837Z","caller":"traceutil/trace.go:171","msg":"trace[1158114107] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1892; }","duration":"476.53808ms","start":"2026-07-07T07:30:25.268281Z","end":"2026-07-07T07:30:25.744819Z","steps":["trace[1158114107] 'range keys from in-memory index tree' (duration: 476.241894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:25.744869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:30:25.268259Z","time spent":"476.602202ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-07T07:30:25.978509Z","caller":"traceutil/trace.go:171","msg":"trace[358177979] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"230.154207ms","start":"2026-07-07T07:30:25.748324Z","end":"2026-07-07T07:30:25.978478Z","steps":["trace[358177979] 'process raft request' (duration: 229.990084ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:26.006987Z","caller":"traceutil/trace.go:171","msg":"trace[2120517682] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1970; }","duration":"161.646667ms","start":"2026-07-07T07:30:25.845317Z","end":"2026-07-07T07:30:26.006964Z","steps":["trace[2120517682] 'read index received' (duration: 133.002086ms)","trace[2120517682] 'applied index is now lower than readState.Index' (duration: 28.643581ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:30:26.007135Z","caller":"traceutil/trace.go:171","msg":"trace[666528493] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"174.608263ms","start":"2026-07-07T07:30:25.832422Z","end":"2026-07-07T07:30:26.00703Z","steps":["trace[666528493] 'process raft request' (duration: 174.418649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.007173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.841881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87412902-c6da-4f4e-864d-974961a53df2\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-07T07:30:26.007291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.909252ms","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-07-07T07:30:26.007306Z","caller":"traceutil/trace.go:171","msg":"trace[898209182] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87412902-c6da-4f4e-864d-974961a53df2; range_end:; response_count:1; response_revision:1894; }","duration":"161.994404ms","start":"2026-07-07T07:30:25.845278Z","end":"2026-07-07T07:30:26.007272Z","steps":["trace[898209182] 'agreement among raft nodes before linearized reading' (duration: 161.79226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.007329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.413734ms","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-07-07T07:30:26.007319Z","caller":"traceutil/trace.go:171","msg":"trace[1079541284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1894; }","duration":"138.973093ms","start":"2026-07-07T07:30:25.868338Z","end":"2026-07-07T07:30:26.007311Z","steps":["trace[1079541284] 'agreement among raft nodes before linearized reading' (duration: 138.856141ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:26.007394Z","caller":"traceutil/trace.go:171","msg":"trace[346811907] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1894; }","duration":"140.532777ms","start":"2026-07-07T07:30:25.866841Z","end":"2026-07-07T07:30:26.007374Z","steps":["trace[346811907] 'agreement among raft nodes before linearized reading' (duration: 140.290001ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:26.189343Z","caller":"traceutil/trace.go:171","msg":"trace[1960662563] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"173.347357ms","start":"2026-07-07T07:30:26.015968Z","end":"2026-07-07T07:30:26.189315Z","steps":["trace[1960662563] 'process raft request' (duration: 172.965029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.559764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.322589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:30:26.560158Z","caller":"traceutil/trace.go:171","msg":"trace[2146623692] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"304.712589ms","start":"2026-07-07T07:30:26.255382Z","end":"2026-07-07T07:30:26.560094Z","steps":["trace[2146623692] 'process raft request' (duration: 30.987711ms)","trace[2146623692] 'compare' (duration: 272.945351ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:30:26.560185Z","caller":"traceutil/trace.go:171","msg":"trace[1141587385] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1974; }","duration":"140.731222ms","start":"2026-07-07T07:30:26.419437Z","end":"2026-07-07T07:30:26.560168Z","steps":["trace[1141587385] 'read index received' (duration: 53.302µs)","trace[1141587385] 'applied index is now lower than readState.Index' (duration: 140.67595ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:30:26.560235Z","caller":"traceutil/trace.go:171","msg":"trace[70224680] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"304.770519ms","start":"2026-07-07T07:30:26.255449Z","end":"2026-07-07T07:30:26.56022Z","steps":["trace[70224680] 'process raft request' (duration: 304.403342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.560448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:30:26.25544Z","time spent":"304.967334ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:30:26.560577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:30:26.255365Z","time spent":"304.915713ms","remote":"127.0.0.1:55204","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-07-07T07:30:26.560742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.311784ms","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-07-07T07:30:26.56078Z","caller":"traceutil/trace.go:171","msg":"trace[607640603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1898; }","duration":"141.379536ms","start":"2026-07-07T07:30:26.41939Z","end":"2026-07-07T07:30:26.56077Z","steps":["trace[607640603] 'agreement among raft nodes before linearized reading' (duration: 140.836305ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:26.914007Z","caller":"traceutil/trace.go:171","msg":"trace[956031554] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"351.123017ms","start":"2026-07-07T07:30:26.562856Z","end":"2026-07-07T07:30:26.913979Z","steps":["trace[956031554] 'process raft request' (duration: 350.722938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.914169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:30:26.562829Z","time spent":"351.25604ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:30:26.918886Z","caller":"traceutil/trace.go:171","msg":"trace[317321841] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"354.446788ms","start":"2026-07-07T07:30:26.564419Z","end":"2026-07-07T07:30:26.918866Z","steps":["trace[317321841] 'process raft request' (duration: 354.246154ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:26.919136Z","caller":"traceutil/trace.go:171","msg":"trace[2084834922] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1976; }","duration":"265.113682ms","start":"2026-07-07T07:30:26.653976Z","end":"2026-07-07T07:30:26.91909Z","steps":["trace[2084834922] 'read index received' (duration: 259.702086ms)","trace[2084834922] 'applied index is now lower than readState.Index' (duration: 5.408486ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:30:26.919247Z","caller":"traceutil/trace.go:171","msg":"trace[981221656] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"233.041219ms","start":"2026-07-07T07:30:26.686189Z","end":"2026-07-07T07:30:26.91923Z","steps":["trace[981221656] 'process raft request' (duration: 232.64337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.919314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.319667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:30:26.919356Z","caller":"traceutil/trace.go:171","msg":"trace[507155904] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1901; }","duration":"265.419549ms","start":"2026-07-07T07:30:26.653925Z","end":"2026-07-07T07:30:26.919344Z","steps":["trace[507155904] 'agreement among raft nodes before linearized reading' (duration: 265.322337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.919579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.985176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:30:26.919613Z","caller":"traceutil/trace.go:171","msg":"trace[1483102069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1901; }","duration":"179.055598ms","start":"2026-07-07T07:30:26.740549Z","end":"2026-07-07T07:30:26.919604Z","steps":["trace[1483102069] 'agreement among raft nodes before linearized reading' (duration: 178.927245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.919576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:30:26.5644Z","time spent":"354.653432ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:30:26.91982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.935974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-87412902-c6da-4f4e-864d-974961a53df2\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-07-07T07:30:26.919836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.438604ms","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-07-07T07:30:26.919856Z","caller":"traceutil/trace.go:171","msg":"trace[986431708] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-87412902-c6da-4f4e-864d-974961a53df2; range_end:; response_count:1; response_revision:1901; }","duration":"170.968075ms","start":"2026-07-07T07:30:26.748878Z","end":"2026-07-07T07:30:26.919846Z","steps":["trace[986431708] 'agreement among raft nodes before linearized reading' (duration: 170.897563ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:26.919873Z","caller":"traceutil/trace.go:171","msg":"trace[606347832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1901; }","duration":"109.503024ms","start":"2026-07-07T07:30:26.810358Z","end":"2026-07-07T07:30:26.919861Z","steps":["trace[606347832] 'agreement among raft nodes before linearized reading' (duration: 109.380182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:26.91975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.148786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-07T07:30:26.920066Z","caller":"traceutil/trace.go:171","msg":"trace[1232027192] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1901; }","duration":"131.501744ms","start":"2026-07-07T07:30:26.788557Z","end":"2026-07-07T07:30:26.920058Z","steps":["trace[1232027192] 'agreement among raft nodes before linearized reading' (duration: 131.111645ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:42.364961Z","caller":"traceutil/trace.go:171","msg":"trace[1871416931] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"308.183838ms","start":"2026-07-07T07:30:42.056753Z","end":"2026-07-07T07:30:42.364937Z","steps":["trace[1871416931] 'process raft request' (duration: 307.940052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:42.365197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:30:42.056726Z","time spent":"308.323471ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:30:42.365836Z","caller":"traceutil/trace.go:171","msg":"trace[14594820] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2109; }","duration":"265.360861ms","start":"2026-07-07T07:30:42.10045Z","end":"2026-07-07T07:30:42.365811Z","steps":["trace[14594820] 'read index received' (duration: 264.3502ms)","trace[14594820] 'applied index is now lower than readState.Index' (duration: 1.009461ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:30:42.365941Z","caller":"traceutil/trace.go:171","msg":"trace[140875818] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"301.315821ms","start":"2026-07-07T07:30:42.064615Z","end":"2026-07-07T07:30:42.365931Z","steps":["trace[140875818] 'process raft request' (duration: 301.105147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:42.366084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:30:42.064598Z","time spent":"301.373703ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:30:42.36614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.246179ms","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-07-07T07:30:42.366287Z","caller":"traceutil/trace.go:171","msg":"trace[471542229] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2030; }","duration":"251.435453ms","start":"2026-07-07T07:30:42.114832Z","end":"2026-07-07T07:30:42.366267Z","steps":["trace[471542229] 'agreement among raft nodes before linearized reading' (duration: 251.146387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:42.366257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.805411ms","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-07-07T07:30:42.366446Z","caller":"traceutil/trace.go:171","msg":"trace[1866701186] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2030; }","duration":"266.022036ms","start":"2026-07-07T07:30:42.100406Z","end":"2026-07-07T07:30:42.366428Z","steps":["trace[1866701186] 'agreement among raft nodes before linearized reading' (duration: 265.75303ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:54.573845Z","caller":"traceutil/trace.go:171","msg":"trace[774576287] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"146.448024ms","start":"2026-07-07T07:30:54.427365Z","end":"2026-07-07T07:30:54.573813Z","steps":["trace[774576287] 'read index received' (duration: 146.101027ms)","trace[774576287] 'applied index is now lower than readState.Index' (duration: 345.387µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:30:54.573944Z","caller":"traceutil/trace.go:171","msg":"trace[1263207519] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"157.031981ms","start":"2026-07-07T07:30:54.416865Z","end":"2026-07-07T07:30:54.573897Z","steps":["trace[1263207519] 'process raft request' (duration: 156.752665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:54.574228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.556844ms","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-07-07T07:30:54.574278Z","caller":"traceutil/trace.go:171","msg":"trace[1282357802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2105; }","duration":"131.600086ms","start":"2026-07-07T07:30:54.442663Z","end":"2026-07-07T07:30:54.574263Z","steps":["trace[1282357802] 'agreement among raft nodes before linearized reading' (duration: 131.519094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:54.57415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.7583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-07T07:30:54.574466Z","caller":"traceutil/trace.go:171","msg":"trace[701041087] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2105; }","duration":"147.108978ms","start":"2026-07-07T07:30:54.427298Z","end":"2026-07-07T07:30:54.574407Z","steps":["trace[701041087] 'agreement among raft nodes before linearized reading' (duration: 146.682449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:30:54.57467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.020778ms","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-07-07T07:30:54.574712Z","caller":"traceutil/trace.go:171","msg":"trace[1374206123] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2105; }","duration":"121.136591ms","start":"2026-07-07T07:30:54.453564Z","end":"2026-07-07T07:30:54.574701Z","steps":["trace[1374206123] 'agreement among raft nodes before linearized reading' (duration: 120.940597ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:30:59.728674Z","caller":"traceutil/trace.go:171","msg":"trace[186416839] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"113.524599ms","start":"2026-07-07T07:30:59.615121Z","end":"2026-07-07T07:30:59.728646Z","steps":["trace[186416839] 'read index received' (duration: 113.335225ms)","trace[186416839] 'applied index is now lower than readState.Index' (duration: 187.694µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:30:59.729206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.04006ms","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-07-07T07:30:59.729417Z","caller":"traceutil/trace.go:171","msg":"trace[2033137802] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2135; }","duration":"114.313976ms","start":"2026-07-07T07:30:59.615072Z","end":"2026-07-07T07:30:59.729386Z","steps":["trace[2033137802] 'agreement among raft nodes before linearized reading' (duration: 113.823796ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:31:00.808352Z","caller":"traceutil/trace.go:171","msg":"trace[147858877] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"139.847155ms","start":"2026-07-07T07:31:00.668448Z","end":"2026-07-07T07:31:00.808295Z","steps":["trace[147858877] 'process raft request' (duration: 127.186143ms)","trace[147858877] 'compare' (duration: 12.220513ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:31:01.521392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.219583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:31:01.521523Z","caller":"traceutil/trace.go:171","msg":"trace[433662773] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"394.324696ms","start":"2026-07-07T07:31:01.127176Z","end":"2026-07-07T07:31:01.5215Z","steps":["trace[433662773] 'read index received' (duration: 187.588262ms)","trace[433662773] 'applied index is now lower than readState.Index' (duration: 206.734374ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:31:01.522012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.357436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-07T07:31:01.522204Z","caller":"traceutil/trace.go:171","msg":"trace[952313030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2142; }","duration":"393.646491ms","start":"2026-07-07T07:31:01.12852Z","end":"2026-07-07T07:31:01.522166Z","steps":["trace[952313030] 'agreement among raft nodes before linearized reading' (duration: 393.359495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:31:01.522269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:31:01.128487Z","time spent":"393.762654ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T07:31:01.522264Z","caller":"traceutil/trace.go:171","msg":"trace[1422630169] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"464.797981ms","start":"2026-07-07T07:31:01.057434Z","end":"2026-07-07T07:31:01.522232Z","steps":["trace[1422630169] 'process raft request' (duration: 257.410563ms)","trace[1422630169] 'compare' (duration: 205.942477ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:31:01.522269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.555969ms","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-07-07T07:31:01.522346Z","caller":"traceutil/trace.go:171","msg":"trace[760773080] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2142; }","duration":"393.645992ms","start":"2026-07-07T07:31:01.128678Z","end":"2026-07-07T07:31:01.522324Z","steps":["trace[760773080] 'agreement among raft nodes before linearized reading' (duration: 393.243393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:31:01.522395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:31:01.128669Z","time spent":"393.713673ms","remote":"127.0.0.1:55204","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-07-07T07:31:01.522632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:31:01.057413Z","time spent":"464.905674ms","remote":"127.0.0.1:55204","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-07-07T07:31:01.522924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.749796ms","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-07-07T07:31:01.52298Z","caller":"traceutil/trace.go:171","msg":"trace[1550048692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2142; }","duration":"395.835419ms","start":"2026-07-07T07:31:01.127133Z","end":"2026-07-07T07:31:01.522968Z","steps":["trace[1550048692] 'agreement among raft nodes before linearized reading' (duration: 394.755345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:31:01.523014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:31:01.127117Z","time spent":"395.886989ms","remote":"127.0.0.1:55204","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":"info","ts":"2026-07-07T07:31:01.695401Z","caller":"traceutil/trace.go:171","msg":"trace[771217229] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"169.123575ms","start":"2026-07-07T07:31:01.526246Z","end":"2026-07-07T07:31:01.69537Z","steps":["trace[771217229] 'process raft request' (duration: 134.215405ms)","trace[771217229] 'compare' (duration: 34.765657ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:31:02.094896Z","caller":"traceutil/trace.go:171","msg":"trace[1403323438] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2229; }","duration":"276.80877ms","start":"2026-07-07T07:31:01.818065Z","end":"2026-07-07T07:31:02.094874Z","steps":["trace[1403323438] 'read index received' (duration: 267.192813ms)","trace[1403323438] 'applied index is now lower than readState.Index' (duration: 9.614617ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:31:02.095092Z","caller":"traceutil/trace.go:171","msg":"trace[795872842] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"567.464688ms","start":"2026-07-07T07:31:01.527599Z","end":"2026-07-07T07:31:02.095064Z","steps":["trace[795872842] 'process raft request' (duration: 557.718249ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:31:02.095195Z","caller":"traceutil/trace.go:171","msg":"trace[541685208] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"567.443198ms","start":"2026-07-07T07:31:01.527739Z","end":"2026-07-07T07:31:02.095183Z","steps":["trace[541685208] 'process raft request' (duration: 567.039939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:31:02.0952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:31:01.527582Z","time spent":"567.56141ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:31:02.095284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:31:01.527721Z","time spent":"567.505139ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:31:02.095454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.387533ms","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-07-07T07:31:02.095582Z","caller":"traceutil/trace.go:171","msg":"trace[1990135398] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2145; }","duration":"277.516546ms","start":"2026-07-07T07:31:01.818044Z","end":"2026-07-07T07:31:02.095561Z","steps":["trace[1990135398] 'agreement among raft nodes before linearized reading' (duration: 277.013405ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:31:56.223323Z","caller":"traceutil/trace.go:171","msg":"trace[809632888] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"113.452463ms","start":"2026-07-07T07:31:56.109849Z","end":"2026-07-07T07:31:56.223301Z","steps":["trace[809632888] 'process raft request' (duration: 113.31904ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:31:57.789319Z","caller":"traceutil/trace.go:171","msg":"trace[422587622] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"482.054384ms","start":"2026-07-07T07:31:57.307244Z","end":"2026-07-07T07:31:57.789298Z","steps":["trace[422587622] 'process raft request' (duration: 481.917631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:31:57.78948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:31:57.307228Z","time spent":"482.179837ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8038,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:32:08.023006Z","caller":"traceutil/trace.go:171","msg":"trace[2011378728] linearizableReadLoop","detail":"{readStateIndex:2699; appliedIndex:2698; }","duration":"102.443487ms","start":"2026-07-07T07:32:07.920538Z","end":"2026-07-07T07:32:08.022982Z","steps":["trace[2011378728] 'read index received' (duration: 102.244683ms)","trace[2011378728] 'applied index is now lower than readState.Index' (duration: 197.494µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:08.023119Z","caller":"traceutil/trace.go:171","msg":"trace[2048197356] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"145.99337ms","start":"2026-07-07T07:32:07.877092Z","end":"2026-07-07T07:32:08.023085Z","steps":["trace[2048197356] 'process raft request' (duration: 145.751074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:08.023204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.659532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-07T07:32:08.02324Z","caller":"traceutil/trace.go:171","msg":"trace[1977394305] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2598; }","duration":"102.742664ms","start":"2026-07-07T07:32:07.920486Z","end":"2026-07-07T07:32:08.023229Z","steps":["trace[1977394305] 'agreement among raft nodes before linearized reading' (duration: 102.596081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:08.854004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224935006168039,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T07:32:08.959376Z","caller":"traceutil/trace.go:171","msg":"trace[256875114] linearizableReadLoop","detail":"{readStateIndex:2700; appliedIndex:2699; }","duration":"606.215959ms","start":"2026-07-07T07:32:08.353135Z","end":"2026-07-07T07:32:08.959351Z","steps":["trace[256875114] 'read index received' (duration: 606.028085ms)","trace[256875114] 'applied index is now lower than readState.Index' (duration: 186.614µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:08.959502Z","caller":"traceutil/trace.go:171","msg":"trace[629819217] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"630.608237ms","start":"2026-07-07T07:32:08.328881Z","end":"2026-07-07T07:32:08.959489Z","steps":["trace[629819217] 'process raft request' (duration: 630.348091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:08.959605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:08.328849Z","time spent":"630.680949ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:32:08.959707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.028807ms","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-07-07T07:32:08.959784Z","caller":"traceutil/trace.go:171","msg":"trace[1904957070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2599; }","duration":"436.14731ms","start":"2026-07-07T07:32:08.523616Z","end":"2026-07-07T07:32:08.959763Z","steps":["trace[1904957070] 'agreement among raft nodes before linearized reading' (duration: 435.936165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:08.959823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:08.5236Z","time spent":"436.215041ms","remote":"127.0.0.1:55204","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-07-07T07:32:08.960114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.408332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:32:08.960212Z","caller":"traceutil/trace.go:171","msg":"trace[1582674514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2599; }","duration":"221.532524ms","start":"2026-07-07T07:32:08.738662Z","end":"2026-07-07T07:32:08.960194Z","steps":["trace[1582674514] 'agreement among raft nodes before linearized reading' (duration: 221.411842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:08.99904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.969857ms","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-07-07T07:32:08.999117Z","caller":"traceutil/trace.go:171","msg":"trace[2031518893] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2599; }","duration":"449.1379ms","start":"2026-07-07T07:32:08.549958Z","end":"2026-07-07T07:32:08.999096Z","steps":["trace[2031518893] 'agreement among raft nodes before linearized reading' (duration: 410.456573ms)","trace[2031518893] 'count revisions from in-memory index tree' (duration: 38.548225ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:32:08.999153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:08.549918Z","time spent":"449.227262ms","remote":"127.0.0.1:38582","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-07-07T07:32:08.999302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.173523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:32:08.999377Z","caller":"traceutil/trace.go:171","msg":"trace[595180893] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2599; }","duration":"237.284476ms","start":"2026-07-07T07:32:08.762068Z","end":"2026-07-07T07:32:08.999353Z","steps":["trace[595180893] 'agreement among raft nodes before linearized reading' (duration: 198.361613ms)","trace[595180893] 'count revisions from in-memory index tree' (duration: 38.82673ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:32:08.999457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.876868ms","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-07-07T07:32:08.999489Z","caller":"traceutil/trace.go:171","msg":"trace[1023280885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2599; }","duration":"389.9282ms","start":"2026-07-07T07:32:08.609549Z","end":"2026-07-07T07:32:08.999477Z","steps":["trace[1023280885] 'agreement among raft nodes before linearized reading' (duration: 350.849524ms)","trace[1023280885] 'range keys from in-memory index tree' (duration: 38.983554ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:32:08.999538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:08.609534Z","time spent":"389.996951ms","remote":"127.0.0.1:55204","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-07-07T07:32:08.999622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.487132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-07T07:32:08.99966Z","caller":"traceutil/trace.go:171","msg":"trace[487497225] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2599; }","duration":"646.552962ms","start":"2026-07-07T07:32:08.353095Z","end":"2026-07-07T07:32:08.999648Z","steps":["trace[487497225] 'agreement among raft nodes before linearized reading' (duration: 607.347004ms)","trace[487497225] 'range keys from in-memory index tree' (duration: 39.086456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:32:08.999696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:08.353076Z","time spent":"646.611864ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-07T07:32:09.517696Z","caller":"traceutil/trace.go:171","msg":"trace[1873449206] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"170.209824ms","start":"2026-07-07T07:32:09.347461Z","end":"2026-07-07T07:32:09.517671Z","steps":["trace[1873449206] 'process raft request' (duration: 170.075131ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:09.534715Z","caller":"traceutil/trace.go:171","msg":"trace[934643941] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"184.556035ms","start":"2026-07-07T07:32:09.350136Z","end":"2026-07-07T07:32:09.534692Z","steps":["trace[934643941] 'process raft request' (duration: 184.487203ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:09.535128Z","caller":"traceutil/trace.go:171","msg":"trace[616307425] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"186.859445ms","start":"2026-07-07T07:32:09.348245Z","end":"2026-07-07T07:32:09.535104Z","steps":["trace[616307425] 'process raft request' (duration: 186.226881ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:33.464368Z","caller":"traceutil/trace.go:171","msg":"trace[1827515638] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"199.466022ms","start":"2026-07-07T07:32:33.264882Z","end":"2026-07-07T07:32:33.464348Z","steps":["trace[1827515638] 'process raft request' (duration: 199.35738ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:33.77772Z","caller":"traceutil/trace.go:171","msg":"trace[215373208] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"108.621974ms","start":"2026-07-07T07:32:33.669075Z","end":"2026-07-07T07:32:33.777697Z","steps":["trace[215373208] 'process raft request' (duration: 102.704396ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:33.777919Z","caller":"traceutil/trace.go:171","msg":"trace[965552544] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"101.708394ms","start":"2026-07-07T07:32:33.676178Z","end":"2026-07-07T07:32:33.777886Z","steps":["trace[965552544] 'process raft request' (duration: 101.487849ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:39.155286Z","caller":"traceutil/trace.go:171","msg":"trace[1159602240] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"170.476385ms","start":"2026-07-07T07:32:38.984791Z","end":"2026-07-07T07:32:39.155267Z","steps":["trace[1159602240] 'process raft request' (duration: 170.355553ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:39.38201Z","caller":"traceutil/trace.go:171","msg":"trace[1705543637] linearizableReadLoop","detail":"{readStateIndex:2874; appliedIndex:2872; }","duration":"242.640139ms","start":"2026-07-07T07:32:39.139349Z","end":"2026-07-07T07:32:39.381989Z","steps":["trace[1705543637] 'read index received' (duration: 15.838724ms)","trace[1705543637] 'applied index is now lower than readState.Index' (duration: 226.798455ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:39.382087Z","caller":"traceutil/trace.go:171","msg":"trace[678179451] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"252.335009ms","start":"2026-07-07T07:32:39.129746Z","end":"2026-07-07T07:32:39.382081Z","steps":["trace[678179451] 'process raft request' (duration: 245.787177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:39.382325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.974816ms","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-07-07T07:32:39.382349Z","caller":"traceutil/trace.go:171","msg":"trace[347654685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2767; }","duration":"243.050068ms","start":"2026-07-07T07:32:39.139293Z","end":"2026-07-07T07:32:39.382343Z","steps":["trace[347654685] 'agreement among raft nodes before linearized reading' (duration: 242.957926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:39.38253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.512908ms","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-07-07T07:32:39.382558Z","caller":"traceutil/trace.go:171","msg":"trace[1184389151] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2767; }","duration":"207.583799ms","start":"2026-07-07T07:32:39.174967Z","end":"2026-07-07T07:32:39.382551Z","steps":["trace[1184389151] 'agreement among raft nodes before linearized reading' (duration: 207.517588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:39.382711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.963312ms","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-07-07T07:32:39.382749Z","caller":"traceutil/trace.go:171","msg":"trace[1016039889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2767; }","duration":"211.038094ms","start":"2026-07-07T07:32:39.171701Z","end":"2026-07-07T07:32:39.382739Z","steps":["trace[1016039889] 'agreement among raft nodes before linearized reading' (duration: 210.460892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:44.922132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.385782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:32:44.922214Z","caller":"traceutil/trace.go:171","msg":"trace[517276747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2797; }","duration":"181.522515ms","start":"2026-07-07T07:32:44.740673Z","end":"2026-07-07T07:32:44.922196Z","steps":["trace[517276747] 'range keys from in-memory index tree' (duration: 181.28113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:44.940184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.782158ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-07T07:34:35.768891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.940895ms","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-07-07T07:34:35.769007Z","caller":"traceutil/trace.go:171","msg":"trace[1566496267] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3369; }","duration":"896.128292ms","start":"2026-07-07T07:34:34.872863Z","end":"2026-07-07T07:34:35.768991Z","steps":["trace[1566496267] 'range keys from in-memory index tree' (duration: 895.859693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:34:35.769066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:34:34.872824Z","time spent":"896.232745ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-07T07:34:56.956333Z","caller":"traceutil/trace.go:171","msg":"trace[2124543305] transaction","detail":"{read_only:false; response_revision:3511; number_of_response:1; }","duration":"104.720889ms","start":"2026-07-07T07:34:56.851593Z","end":"2026-07-07T07:34:56.956313Z","steps":["trace[2124543305] 'process raft request' (duration: 54.803446ms)","trace[2124543305] 'compare' (duration: 49.82852ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:34:56.959987Z","caller":"traceutil/trace.go:171","msg":"trace[976072697] linearizableReadLoop","detail":"{readStateIndex:3653; appliedIndex:3650; }","duration":"101.098915ms","start":"2026-07-07T07:34:56.858866Z","end":"2026-07-07T07:34:56.959965Z","steps":["trace[976072697] 'read index received' (duration: 47.575889ms)","trace[976072697] 'applied index is now lower than readState.Index' (duration: 53.521986ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:34:56.960199Z","caller":"traceutil/trace.go:171","msg":"trace[1074974279] transaction","detail":"{read_only:false; response_revision:3512; number_of_response:1; }","duration":"108.139847ms","start":"2026-07-07T07:34:56.852051Z","end":"2026-07-07T07:34:56.960191Z","steps":["trace[1074974279] 'process raft request' (duration: 107.770306ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:34:56.96032Z","caller":"traceutil/trace.go:171","msg":"trace[318730270] transaction","detail":"{read_only:false; response_revision:3513; number_of_response:1; }","duration":"107.465926ms","start":"2026-07-07T07:34:56.852849Z","end":"2026-07-07T07:34:56.960315Z","steps":["trace[318730270] 'process raft request' (duration: 107.092794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:34:56.960453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.57863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-07T07:34:56.960485Z","caller":"traceutil/trace.go:171","msg":"trace[1374961184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3513; }","duration":"101.635762ms","start":"2026-07-07T07:34:56.858841Z","end":"2026-07-07T07:34:56.960477Z","steps":["trace[1374961184] 'agreement among raft nodes before linearized reading' (duration: 101.544109ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:25.254388Z","caller":"traceutil/trace.go:171","msg":"trace[1106199103] transaction","detail":"{read_only:false; response_revision:3723; number_of_response:1; }","duration":"100.824444ms","start":"2026-07-07T07:35:25.153549Z","end":"2026-07-07T07:35:25.254374Z","steps":["trace[1106199103] 'process raft request' (duration: 100.653589ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:25.254305Z","caller":"traceutil/trace.go:171","msg":"trace[1775525312] transaction","detail":"{read_only:false; response_revision:3722; number_of_response:1; }","duration":"101.775582ms","start":"2026-07-07T07:35:25.15247Z","end":"2026-07-07T07:35:25.254245Z","steps":["trace[1775525312] 'process raft request' (duration: 54.34541ms)","trace[1775525312] 'compare' (duration: 47.267537ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:35:59.506765Z","caller":"traceutil/trace.go:171","msg":"trace[2095878117] transaction","detail":"{read_only:false; response_revision:3910; number_of_response:1; }","duration":"109.796891ms","start":"2026-07-07T07:35:59.396943Z","end":"2026-07-07T07:35:59.50674Z","steps":["trace[2095878117] 'process raft request' (duration: 67.070119ms)","trace[2095878117] 'compare' (duration: 42.591668ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:35:59.516398Z","caller":"traceutil/trace.go:171","msg":"trace[713759358] transaction","detail":"{read_only:false; response_revision:3911; number_of_response:1; }","duration":"116.832132ms","start":"2026-07-07T07:35:59.399535Z","end":"2026-07-07T07:35:59.516367Z","steps":["trace[713759358] 'process raft request' (duration: 116.584275ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:35:59.51679Z","caller":"traceutil/trace.go:171","msg":"trace[1940581473] transaction","detail":"{read_only:false; response_revision:3912; number_of_response:1; }","duration":"116.279136ms","start":"2026-07-07T07:35:59.400488Z","end":"2026-07-07T07:35:59.516767Z","steps":["trace[1940581473] 'process raft request' (duration: 115.823673ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:36:21.799518Z","caller":"traceutil/trace.go:171","msg":"trace[1061705903] transaction","detail":"{read_only:false; response_revision:4093; number_of_response:1; }","duration":"105.042599ms","start":"2026-07-07T07:36:21.694454Z","end":"2026-07-07T07:36:21.799496Z","steps":["trace[1061705903] 'process raft request' (duration: 104.881815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:57.842988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.40495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:36:57.843074Z","caller":"traceutil/trace.go:171","msg":"trace[828545650] linearizableReadLoop","detail":"{readStateIndex:4909; appliedIndex:4908; }","duration":"305.831531ms","start":"2026-07-07T07:36:57.537228Z","end":"2026-07-07T07:36:57.84306Z","steps":["trace[828545650] 'read index received' (duration: 105.155353ms)","trace[828545650] 'applied index is now lower than readState.Index' (duration: 200.675138ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:36:57.843318Z","caller":"traceutil/trace.go:171","msg":"trace[1236526264] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"366.846875ms","start":"2026-07-07T07:36:57.476437Z","end":"2026-07-07T07:36:57.843283Z","steps":["trace[1236526264] 'process raft request' (duration: 166.089975ms)","trace[1236526264] 'compare' (duration: 200.306348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:36:57.843497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:57.476414Z","time spent":"366.997699ms","remote":"127.0.0.1:55204","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-07-07T07:36:57.843486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.233462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-rmh7m\" ","response":"range_response_count:1 size:4810"} {"level":"info","ts":"2026-07-07T07:36:57.843559Z","caller":"traceutil/trace.go:171","msg":"trace[458827170] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-rmh7m; range_end:; response_count:1; response_revision:4723; }","duration":"306.350795ms","start":"2026-07-07T07:36:57.537189Z","end":"2026-07-07T07:36:57.843539Z","steps":["trace[458827170] 'agreement among raft nodes before linearized reading' (duration: 306.139719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:57.843599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:57.53717Z","time spent":"306.418917ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":4833,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-rmh7m\" "} {"level":"warn","ts":"2026-07-07T07:36:58.435066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.86981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:36:58.435195Z","caller":"traceutil/trace.go:171","msg":"trace[52996272] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"588.048004ms","start":"2026-07-07T07:36:57.84712Z","end":"2026-07-07T07:36:58.435168Z","steps":["trace[52996272] 'process raft request' (duration: 120.957519ms)","trace[52996272] 'compare' (duration: 466.692634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:36:58.435281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:57.847102Z","time spent":"588.134907ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:36:58.473533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224935006173552,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-07T07:36:58.762784Z","caller":"traceutil/trace.go:171","msg":"trace[1776945996] linearizableReadLoop","detail":"{readStateIndex:4911; appliedIndex:4910; }","duration":"789.741241ms","start":"2026-07-07T07:36:57.97301Z","end":"2026-07-07T07:36:58.762751Z","steps":["trace[1776945996] 'read index received' (duration: 768.678134ms)","trace[1776945996] 'applied index is now lower than readState.Index' (duration: 21.061897ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:36:58.762932Z","caller":"traceutil/trace.go:171","msg":"trace[1634527170] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"912.974923ms","start":"2026-07-07T07:36:57.849936Z","end":"2026-07-07T07:36:58.762911Z","steps":["trace[1634527170] 'process raft request' (duration: 891.739961ms)","trace[1634527170] 'compare' (duration: 20.907043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:36:58.763148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.128883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ae462815-38d8-4f18-87f0-b13f074b7757\" ","response":"range_response_count:1 size:4067"} {"level":"info","ts":"2026-07-07T07:36:58.763212Z","caller":"traceutil/trace.go:171","msg":"trace[1176425935] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ae462815-38d8-4f18-87f0-b13f074b7757; range_end:; response_count:1; response_revision:4725; }","duration":"790.227485ms","start":"2026-07-07T07:36:57.972967Z","end":"2026-07-07T07:36:58.763195Z","steps":["trace[1176425935] 'agreement among raft nodes before linearized reading' (duration: 790.057871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.763251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:57.97295Z","time spent":"790.291587ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4090,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ae462815-38d8-4f18-87f0-b13f074b7757\" "} {"level":"warn","ts":"2026-07-07T07:36:58.763469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:57.84992Z","time spent":"913.047344ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5123,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:36:58.832923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.65574ms","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-07-07T07:36:58.832998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.753838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-07T07:36:58.833045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.30248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T07:36:58.833046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.159597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-07-07T07:36:58.833074Z","caller":"traceutil/trace.go:171","msg":"trace[354839890] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4726; }","duration":"529.357562ms","start":"2026-07-07T07:36:58.303706Z","end":"2026-07-07T07:36:58.833063Z","steps":["trace[354839890] 'agreement among raft nodes before linearized reading' (duration: 529.31507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.833078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.151166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:36:58.833089Z","caller":"traceutil/trace.go:171","msg":"trace[595886355] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4726; }","duration":"230.199908ms","start":"2026-07-07T07:36:58.602876Z","end":"2026-07-07T07:36:58.833076Z","steps":["trace[595886355] 'agreement among raft nodes before linearized reading' (duration: 230.092375ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:36:58.833112Z","caller":"traceutil/trace.go:171","msg":"trace[391131390] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4726; }","duration":"279.184957ms","start":"2026-07-07T07:36:58.553918Z","end":"2026-07-07T07:36:58.833102Z","steps":["trace[391131390] 'agreement among raft nodes before linearized reading' (duration: 279.110825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.833189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.553028ms","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-07-07T07:36:58.833222Z","caller":"traceutil/trace.go:171","msg":"trace[1849144629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4726; }","duration":"639.62716ms","start":"2026-07-07T07:36:58.193587Z","end":"2026-07-07T07:36:58.833214Z","steps":["trace[1849144629] 'agreement among raft nodes before linearized reading' (duration: 639.547258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.833248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.193558Z","time spent":"639.683311ms","remote":"127.0.0.1:55204","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":"info","ts":"2026-07-07T07:36:58.833228Z","caller":"traceutil/trace.go:171","msg":"trace[1424023741] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"290.948274ms","start":"2026-07-07T07:36:58.54227Z","end":"2026-07-07T07:36:58.833218Z","steps":["trace[1424023741] 'process raft request' (duration: 290.346898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.833115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.30369Z","time spent":"529.414763ms","remote":"127.0.0.1:59252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:36:58.833437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.955668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-07T07:36:58.833041Z","caller":"traceutil/trace.go:171","msg":"trace[755593239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4726; }","duration":"771.83848ms","start":"2026-07-07T07:36:58.061192Z","end":"2026-07-07T07:36:58.833031Z","steps":["trace[755593239] 'agreement among raft nodes before linearized reading' (duration: 771.742397ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:36:58.833474Z","caller":"traceutil/trace.go:171","msg":"trace[1025457112] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4726; }","duration":"640.02077ms","start":"2026-07-07T07:36:58.193444Z","end":"2026-07-07T07:36:58.833465Z","steps":["trace[1025457112] 'agreement among raft nodes before linearized reading' (duration: 639.931338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.833492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.061141Z","time spent":"772.339923ms","remote":"127.0.0.1:55204","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-07-07T07:36:58.832923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.935356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-07T07:36:58.83365Z","caller":"traceutil/trace.go:171","msg":"trace[709255015] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4726; }","duration":"826.717377ms","start":"2026-07-07T07:36:58.006921Z","end":"2026-07-07T07:36:58.833639Z","steps":["trace[709255015] 'agreement among raft nodes before linearized reading' (duration: 825.817753ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:36:58.832998Z","caller":"traceutil/trace.go:171","msg":"trace[349160760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4726; }","duration":"649.770573ms","start":"2026-07-07T07:36:58.183207Z","end":"2026-07-07T07:36:58.832978Z","steps":["trace[349160760] 'agreement among raft nodes before linearized reading' (duration: 649.616469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.833693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.006899Z","time spent":"826.780948ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-07T07:36:58.833714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.183189Z","time spent":"650.517503ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T07:36:58.833591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.129406ms","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":"warn","ts":"2026-07-07T07:36:58.832924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.511844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66f282df-42b5-458b-bca4-5d32343daff0\" ","response":"range_response_count:1 size:4053"} {"level":"warn","ts":"2026-07-07T07:36:58.83376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.773644ms","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-07-07T07:36:58.833826Z","caller":"traceutil/trace.go:171","msg":"trace[949163273] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66f282df-42b5-458b-bca4-5d32343daff0; range_end:; response_count:1; response_revision:4726; }","duration":"433.45115ms","start":"2026-07-07T07:36:58.400358Z","end":"2026-07-07T07:36:58.83381Z","steps":["trace[949163273] 'agreement among raft nodes before linearized reading' (duration: 432.410472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.833869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.400339Z","time spent":"433.518542ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4076,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66f282df-42b5-458b-bca4-5d32343daff0\" "} {"level":"warn","ts":"2026-07-07T07:36:58.833503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.193429Z","time spent":"640.066272ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-07T07:36:58.83384Z","caller":"traceutil/trace.go:171","msg":"trace[1931408265] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4726; }","duration":"662.496405ms","start":"2026-07-07T07:36:58.171296Z","end":"2026-07-07T07:36:58.833792Z","steps":["trace[1931408265] 'agreement among raft nodes before linearized reading' (duration: 661.834537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.833447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.691417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-07T07:36:58.834165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.171279Z","time spent":"662.812484ms","remote":"127.0.0.1:55026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":47,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:36:58.833004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.955191ms","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-07-07T07:36:58.834309Z","caller":"traceutil/trace.go:171","msg":"trace[904028032] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4726; }","duration":"280.294397ms","start":"2026-07-07T07:36:58.554001Z","end":"2026-07-07T07:36:58.834296Z","steps":["trace[904028032] 'agreement among raft nodes before linearized reading' (duration: 278.971411ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:36:58.833911Z","caller":"traceutil/trace.go:171","msg":"trace[799806547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4726; }","duration":"743.917307ms","start":"2026-07-07T07:36:58.089956Z","end":"2026-07-07T07:36:58.833874Z","steps":["trace[799806547] 'agreement among raft nodes before linearized reading' (duration: 743.548898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:36:58.834555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:36:58.089906Z","time spent":"744.635557ms","remote":"127.0.0.1:55204","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-07-07T07:36:58.834704Z","caller":"traceutil/trace.go:171","msg":"trace[1453699387] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4726; }","duration":"226.56223ms","start":"2026-07-07T07:36:58.607566Z","end":"2026-07-07T07:36:58.834128Z","steps":["trace[1453699387] 'agreement among raft nodes before linearized reading' (duration: 225.393339ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:11.821436Z","caller":"traceutil/trace.go:171","msg":"trace[1100667670] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"135.564792ms","start":"2026-07-07T07:37:11.685854Z","end":"2026-07-07T07:37:11.821418Z","steps":["trace[1100667670] 'process raft request' (duration: 135.503901ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:11.821667Z","caller":"traceutil/trace.go:171","msg":"trace[1261321704] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"141.86706ms","start":"2026-07-07T07:37:11.679769Z","end":"2026-07-07T07:37:11.821636Z","steps":["trace[1261321704] 'process raft request' (duration: 141.548272ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:11.821857Z","caller":"traceutil/trace.go:171","msg":"trace[1010311894] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"142.656811ms","start":"2026-07-07T07:37:11.679191Z","end":"2026-07-07T07:37:11.821848Z","steps":["trace[1010311894] 'process raft request' (duration: 142.008374ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:12.1951Z","caller":"traceutil/trace.go:171","msg":"trace[1562033761] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"257.890918ms","start":"2026-07-07T07:37:11.937181Z","end":"2026-07-07T07:37:12.195072Z","steps":["trace[1562033761] 'process raft request' (duration: 257.656472ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:12.318557Z","caller":"traceutil/trace.go:171","msg":"trace[880844341] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"226.8426ms","start":"2026-07-07T07:37:12.09168Z","end":"2026-07-07T07:37:12.318523Z","steps":["trace[880844341] 'process raft request' (duration: 226.708367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:12.31872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.608327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:37:12.318791Z","caller":"traceutil/trace.go:171","msg":"trace[1274218253] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4916; }","duration":"154.74964ms","start":"2026-07-07T07:37:12.164029Z","end":"2026-07-07T07:37:12.318779Z","steps":["trace[1274218253] 'agreement among raft nodes before linearized reading' (duration: 154.586346ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:12.318569Z","caller":"traceutil/trace.go:171","msg":"trace[1369165279] linearizableReadLoop","detail":"{readStateIndex:5109; appliedIndex:5108; }","duration":"154.454232ms","start":"2026-07-07T07:37:12.164093Z","end":"2026-07-07T07:37:12.318547Z","steps":["trace[1369165279] 'read index received' (duration: 31.679123ms)","trace[1369165279] 'applied index is now lower than readState.Index' (duration: 122.734368ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:13.169994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.077302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:37:13.170072Z","caller":"traceutil/trace.go:171","msg":"trace[214220979] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:4926; }","duration":"112.213295ms","start":"2026-07-07T07:37:13.057837Z","end":"2026-07-07T07:37:13.17005Z","steps":["trace[214220979] 'count revisions from in-memory index tree' (duration: 111.965829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:13.170144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.267131ms","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-07-07T07:37:13.170277Z","caller":"traceutil/trace.go:171","msg":"trace[1525033028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4926; }","duration":"238.440416ms","start":"2026-07-07T07:37:12.931806Z","end":"2026-07-07T07:37:13.170246Z","steps":["trace[1525033028] 'range keys from in-memory index tree' (duration: 238.087956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:13.170773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.341849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-07T07:37:13.170823Z","caller":"traceutil/trace.go:171","msg":"trace[1563931592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4926; }","duration":"242.424902ms","start":"2026-07-07T07:37:12.928386Z","end":"2026-07-07T07:37:13.170811Z","steps":["trace[1563931592] 'range keys from in-memory index tree' (duration: 242.209376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:13.171136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.002017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-07T07:37:13.171183Z","caller":"traceutil/trace.go:171","msg":"trace[408723638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4926; }","duration":"240.065829ms","start":"2026-07-07T07:37:12.931106Z","end":"2026-07-07T07:37:13.171172Z","steps":["trace[408723638] 'range keys from in-memory index tree' (duration: 239.855254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:13.171251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.335706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-07T07:37:13.171272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.450489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-07T07:37:13.171392Z","caller":"traceutil/trace.go:171","msg":"trace[1340545579] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4926; }","duration":"249.604853ms","start":"2026-07-07T07:37:12.921773Z","end":"2026-07-07T07:37:13.171378Z","steps":["trace[1340545579] 'range keys from in-memory index tree' (duration: 248.782421ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:13.171398Z","caller":"traceutil/trace.go:171","msg":"trace[1883879956] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4926; }","duration":"249.434798ms","start":"2026-07-07T07:37:12.921888Z","end":"2026-07-07T07:37:13.171323Z","steps":["trace[1883879956] 'range keys from in-memory index tree' (duration: 249.255634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:13.171645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.760557ms","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-07-07T07:37:13.171676Z","caller":"traceutil/trace.go:171","msg":"trace[1033835325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4926; }","duration":"252.858699ms","start":"2026-07-07T07:37:12.918809Z","end":"2026-07-07T07:37:13.171667Z","steps":["trace[1033835325] 'range keys from in-memory index tree' (duration: 251.809651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:24.694713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.420922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-07T07:37:24.694822Z","caller":"traceutil/trace.go:171","msg":"trace[1940892710] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5134; }","duration":"344.548285ms","start":"2026-07-07T07:37:24.350247Z","end":"2026-07-07T07:37:24.694796Z","steps":["trace[1940892710] 'range keys from in-memory index tree' (duration: 344.089002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:24.694829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.180083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-07-07T07:37:24.694864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:24.35016Z","time spent":"344.692729ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-07T07:37:24.694907Z","caller":"traceutil/trace.go:171","msg":"trace[454086405] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5134; }","duration":"153.276086ms","start":"2026-07-07T07:37:24.541607Z","end":"2026-07-07T07:37:24.694883Z","steps":["trace[454086405] 'range keys from in-memory index tree' (duration: 152.996418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:24.694983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.156441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-07T07:37:24.695056Z","caller":"traceutil/trace.go:171","msg":"trace[1656410554] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5134; }","duration":"220.290964ms","start":"2026-07-07T07:37:24.474748Z","end":"2026-07-07T07:37:24.695039Z","steps":["trace[1656410554] 'range keys from in-memory index tree' (duration: 219.957226ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:29.213117Z","caller":"traceutil/trace.go:171","msg":"trace[177701012] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5357; }","duration":"345.637235ms","start":"2026-07-07T07:37:28.867444Z","end":"2026-07-07T07:37:29.213081Z","steps":["trace[177701012] 'read index received' (duration: 345.625515ms)","trace[177701012] 'applied index is now lower than readState.Index' (duration: 9.42µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:29.213582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.091277ms","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-07-07T07:37:29.213645Z","caller":"traceutil/trace.go:171","msg":"trace[1375283014] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5158; }","duration":"346.2218ms","start":"2026-07-07T07:37:28.867409Z","end":"2026-07-07T07:37:29.213631Z","steps":["trace[1375283014] 'agreement among raft nodes before linearized reading' (duration: 345.82623ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:29.213131Z","caller":"traceutil/trace.go:171","msg":"trace[425666160] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"406.036285ms","start":"2026-07-07T07:37:28.807069Z","end":"2026-07-07T07:37:29.213105Z","steps":["trace[425666160] 'process raft request' (duration: 405.85987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:29.214033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:28.80702Z","time spent":"406.792225ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:37:29.213696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:28.867396Z","time spent":"346.289932ms","remote":"127.0.0.1:55096","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":"info","ts":"2026-07-07T07:37:29.346904Z","caller":"traceutil/trace.go:171","msg":"trace[183086304] linearizableReadLoop","detail":"{readStateIndex:5358; appliedIndex:5357; }","duration":"133.656917ms","start":"2026-07-07T07:37:29.213208Z","end":"2026-07-07T07:37:29.346865Z","steps":["trace[183086304] 'read index received' (duration: 107.295763ms)","trace[183086304] 'applied index is now lower than readState.Index' (duration: 26.359774ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:29.347055Z","caller":"traceutil/trace.go:171","msg":"trace[387329364] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"176.75902ms","start":"2026-07-07T07:37:29.170237Z","end":"2026-07-07T07:37:29.346996Z","steps":["trace[387329364] 'process raft request' (duration: 150.263233ms)","trace[387329364] 'compare' (duration: 26.210339ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:29.34714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.812675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:37:29.347194Z","caller":"traceutil/trace.go:171","msg":"trace[95430746] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:5159; }","duration":"351.949959ms","start":"2026-07-07T07:37:28.995229Z","end":"2026-07-07T07:37:29.347179Z","steps":["trace[95430746] 'agreement among raft nodes before linearized reading' (duration: 351.809665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:29.347263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:28.995196Z","time spent":"352.036231ms","remote":"127.0.0.1:36450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:37:29.35187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.127306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-525e8a63-6ff2-44a1-8dd0-c99ad275fc15\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-07T07:37:29.351982Z","caller":"traceutil/trace.go:171","msg":"trace[2023421242] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"129.517827ms","start":"2026-07-07T07:37:29.222433Z","end":"2026-07-07T07:37:29.351951Z","steps":["trace[2023421242] 'process raft request' (duration: 129.129507ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:29.352043Z","caller":"traceutil/trace.go:171","msg":"trace[1282358808] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"128.684765ms","start":"2026-07-07T07:37:29.223341Z","end":"2026-07-07T07:37:29.352025Z","steps":["trace[1282358808] 'process raft request' (duration: 128.301225ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:29.352015Z","caller":"traceutil/trace.go:171","msg":"trace[857033288] range","detail":"{range_begin:/registry/persistentvolumes/pvc-525e8a63-6ff2-44a1-8dd0-c99ad275fc15; range_end:; response_count:1; response_revision:5163; }","duration":"132.32465ms","start":"2026-07-07T07:37:29.219673Z","end":"2026-07-07T07:37:29.351997Z","steps":["trace[857033288] 'agreement among raft nodes before linearized reading' (duration: 132.009942ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:29.352274Z","caller":"traceutil/trace.go:171","msg":"trace[942272007] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"129.162528ms","start":"2026-07-07T07:37:29.223071Z","end":"2026-07-07T07:37:29.352234Z","steps":["trace[942272007] 'process raft request' (duration: 128.538491ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:29.352338Z","caller":"traceutil/trace.go:171","msg":"trace[502633432] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"130.077802ms","start":"2026-07-07T07:37:29.222248Z","end":"2026-07-07T07:37:29.352325Z","steps":["trace[502633432] 'process raft request' (duration: 129.166918ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:29.549579Z","caller":"traceutil/trace.go:171","msg":"trace[1353480480] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"153.087627ms","start":"2026-07-07T07:37:29.396366Z","end":"2026-07-07T07:37:29.549453Z","steps":["trace[1353480480] 'process raft request' (duration: 78.423973ms)","trace[1353480480] 'compare' (duration: 74.171791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:29.833184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.991162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:37:29.833477Z","caller":"traceutil/trace.go:171","msg":"trace[625722758] linearizableReadLoop","detail":"{readStateIndex:5368; appliedIndex:5366; }","duration":"221.60984ms","start":"2026-07-07T07:37:29.611847Z","end":"2026-07-07T07:37:29.833457Z","steps":["trace[625722758] 'read index received' (duration: 116.250839ms)","trace[625722758] 'applied index is now lower than readState.Index' (duration: 105.357021ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:29.83347Z","caller":"traceutil/trace.go:171","msg":"trace[830753462] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"433.504374ms","start":"2026-07-07T07:37:29.399941Z","end":"2026-07-07T07:37:29.833445Z","steps":["trace[830753462] 'process raft request' (duration: 433.37397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:29.83371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.863237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.108\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-07T07:37:29.833744Z","caller":"traceutil/trace.go:171","msg":"trace[1310432673] range","detail":"{range_begin:/registry/masterleases/199.19.213.108; range_end:; response_count:1; response_revision:5170; }","duration":"221.954859ms","start":"2026-07-07T07:37:29.61178Z","end":"2026-07-07T07:37:29.833735Z","steps":["trace[1310432673] 'agreement among raft nodes before linearized reading' (duration: 221.756513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:29.833912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.49602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T07:37:29.834049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:29.399926Z","time spent":"433.790941ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:37:29.833474Z","caller":"traceutil/trace.go:171","msg":"trace[1117141602] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"435.799114ms","start":"2026-07-07T07:37:29.397652Z","end":"2026-07-07T07:37:29.833451Z","steps":["trace[1117141602] 'process raft request' (duration: 330.432392ms)","trace[1117141602] 'compare' (duration: 104.798137ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:29.83397Z","caller":"traceutil/trace.go:171","msg":"trace[1886518987] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:5170; }","duration":"101.607343ms","start":"2026-07-07T07:37:29.732349Z","end":"2026-07-07T07:37:29.833956Z","steps":["trace[1886518987] 'agreement among raft nodes before linearized reading' (duration: 101.455659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:29.834226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.20407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-07-07T07:37:29.834268Z","caller":"traceutil/trace.go:171","msg":"trace[413592919] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5170; }","duration":"126.276542ms","start":"2026-07-07T07:37:29.707978Z","end":"2026-07-07T07:37:29.834255Z","steps":["trace[413592919] 'agreement among raft nodes before linearized reading' (duration: 125.627195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:29.834152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:37:29.397604Z","time spent":"436.509483ms","remote":"127.0.0.1:55204","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-07-07T07:37:29.833657Z","caller":"traceutil/trace.go:171","msg":"trace[344866471] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"155.874541ms","start":"2026-07-07T07:37:29.677765Z","end":"2026-07-07T07:37:29.833639Z","steps":["trace[344866471] 'process raft request' (duration: 155.660125ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:30.868654Z","caller":"traceutil/trace.go:171","msg":"trace[1121942969] linearizableReadLoop","detail":"{readStateIndex:5377; appliedIndex:5376; }","duration":"161.989548ms","start":"2026-07-07T07:37:30.706639Z","end":"2026-07-07T07:37:30.868628Z","steps":["trace[1121942969] 'read index received' (duration: 161.69783ms)","trace[1121942969] 'applied index is now lower than readState.Index' (duration: 290.348µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:30.869029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.375669ms","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-07-07T07:37:30.869094Z","caller":"traceutil/trace.go:171","msg":"trace[724900461] 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:5177; }","duration":"162.484622ms","start":"2026-07-07T07:37:30.706591Z","end":"2026-07-07T07:37:30.869075Z","steps":["trace[724900461] 'agreement among raft nodes before linearized reading' (duration: 162.307757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:30.869113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.101307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:37:30.869148Z","caller":"traceutil/trace.go:171","msg":"trace[2139838191] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5177; }","duration":"131.197509ms","start":"2026-07-07T07:37:30.737944Z","end":"2026-07-07T07:37:30.869141Z","steps":["trace[2139838191] 'agreement among raft nodes before linearized reading' (duration: 131.112546ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:30.86903Z","caller":"traceutil/trace.go:171","msg":"trace[2043443105] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"208.207514ms","start":"2026-07-07T07:37:30.660779Z","end":"2026-07-07T07:37:30.868986Z","steps":["trace[2043443105] 'process raft request' (duration: 207.69023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:30.869383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.619227ms","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-07-07T07:37:30.869448Z","caller":"traceutil/trace.go:171","msg":"trace[980996022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5177; }","duration":"140.7304ms","start":"2026-07-07T07:37:30.72871Z","end":"2026-07-07T07:37:30.869441Z","steps":["trace[980996022] 'agreement among raft nodes before linearized reading' (duration: 140.212286ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:30.993826Z","caller":"traceutil/trace.go:171","msg":"trace[857837957] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"120.006835ms","start":"2026-07-07T07:37:30.873788Z","end":"2026-07-07T07:37:30.993795Z","steps":["trace[857837957] 'process raft request' (duration: 77.166428ms)","trace[857837957] 'compare' (duration: 42.57071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:30.994078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.455264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17209"} {"level":"info","ts":"2026-07-07T07:37:30.994164Z","caller":"traceutil/trace.go:171","msg":"trace[1710354142] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5178; }","duration":"118.585708ms","start":"2026-07-07T07:37:30.875553Z","end":"2026-07-07T07:37:30.994139Z","steps":["trace[1710354142] 'agreement among raft nodes before linearized reading' (duration: 118.318231ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:30.993826Z","caller":"traceutil/trace.go:171","msg":"trace[350155213] linearizableReadLoop","detail":"{readStateIndex:5378; appliedIndex:5377; }","duration":"118.187748ms","start":"2026-07-07T07:37:30.8756Z","end":"2026-07-07T07:37:30.993788Z","steps":["trace[350155213] 'read index received' (duration: 75.206208ms)","trace[350155213] 'applied index is now lower than readState.Index' (duration: 42.97983ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:40.032491Z","caller":"traceutil/trace.go:171","msg":"trace[1475601015] linearizableReadLoop","detail":"{readStateIndex:5442; appliedIndex:5441; }","duration":"121.69311ms","start":"2026-07-07T07:37:39.910769Z","end":"2026-07-07T07:37:40.032462Z","steps":["trace[1475601015] 'read index received' (duration: 38.292051ms)","trace[1475601015] 'applied index is now lower than readState.Index' (duration: 83.399479ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:37:40.032543Z","caller":"traceutil/trace.go:171","msg":"trace[591707819] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"142.638538ms","start":"2026-07-07T07:37:39.88986Z","end":"2026-07-07T07:37:40.032498Z","steps":["trace[591707819] 'process raft request' (duration: 59.356832ms)","trace[591707819] 'compare' (duration: 82.926057ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:37:40.033614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.8107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-07T07:37:40.033756Z","caller":"traceutil/trace.go:171","msg":"trace[793521466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5240; }","duration":"122.995925ms","start":"2026-07-07T07:37:39.910716Z","end":"2026-07-07T07:37:40.033712Z","steps":["trace[793521466] 'agreement among raft nodes before linearized reading' (duration: 121.914916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:37:40.286277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.724593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-07T07:37:40.286661Z","caller":"traceutil/trace.go:171","msg":"trace[1805962559] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5242; }","duration":"152.174345ms","start":"2026-07-07T07:37:40.134462Z","end":"2026-07-07T07:37:40.286637Z","steps":["trace[1805962559] 'range keys from in-memory index tree' (duration: 151.261651ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:41.003503Z","caller":"traceutil/trace.go:171","msg":"trace[758229172] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"213.310502ms","start":"2026-07-07T07:37:40.79016Z","end":"2026-07-07T07:37:41.003471Z","steps":["trace[758229172] 'process raft request' (duration: 213.106876ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:37:46.60502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2808} {"level":"info","ts":"2026-07-07T07:37:46.687591Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2808,"took":"80.240878ms","hash":2713980219,"current-db-size-bytes":51695616,"current-db-size":"52 MB","current-db-size-in-use-bytes":28028928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-07T07:37:46.687696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2713980219,"revision":2808,"compact-revision":-1} {"level":"info","ts":"2026-07-07T07:38:19.897535Z","caller":"traceutil/trace.go:171","msg":"trace[271546120] linearizableReadLoop","detail":"{readStateIndex:5768; appliedIndex:5767; }","duration":"182.503759ms","start":"2026-07-07T07:38:19.715006Z","end":"2026-07-07T07:38:19.897509Z","steps":["trace[271546120] 'read index received' (duration: 182.286283ms)","trace[271546120] 'applied index is now lower than readState.Index' (duration: 215.936µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:38:19.897711Z","caller":"traceutil/trace.go:171","msg":"trace[1320148663] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"182.835218ms","start":"2026-07-07T07:38:19.714827Z","end":"2026-07-07T07:38:19.897662Z","steps":["trace[1320148663] 'process raft request' (duration: 182.501419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:19.898518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.480864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-07T07:38:19.898586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.442462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-07T07:38:19.898603Z","caller":"traceutil/trace.go:171","msg":"trace[1121774835] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5555; }","duration":"183.602207ms","start":"2026-07-07T07:38:19.714978Z","end":"2026-07-07T07:38:19.898581Z","steps":["trace[1121774835] 'agreement among raft nodes before linearized reading' (duration: 182.684304ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:19.898647Z","caller":"traceutil/trace.go:171","msg":"trace[342532788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5555; }","duration":"148.022286ms","start":"2026-07-07T07:38:19.750609Z","end":"2026-07-07T07:38:19.898631Z","steps":["trace[342532788] 'agreement among raft nodes before linearized reading' (duration: 147.301558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:19.897903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.923184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:38:19.900239Z","caller":"traceutil/trace.go:171","msg":"trace[1510307986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5555; }","duration":"159.411738ms","start":"2026-07-07T07:38:19.740796Z","end":"2026-07-07T07:38:19.900207Z","steps":["trace[1510307986] 'agreement among raft nodes before linearized reading' (duration: 156.901624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:38:28.197174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.638178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-07T07:38:28.197432Z","caller":"traceutil/trace.go:171","msg":"trace[853133395] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5623; }","duration":"128.925245ms","start":"2026-07-07T07:38:28.068452Z","end":"2026-07-07T07:38:28.197377Z","steps":["trace[853133395] 'range keys from in-memory index tree' (duration: 128.421253ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:38:30.733652Z","caller":"traceutil/trace.go:171","msg":"trace[1884282043] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"195.209909ms","start":"2026-07-07T07:38:30.538421Z","end":"2026-07-07T07:38:30.733631Z","steps":["trace[1884282043] 'process raft request' (duration: 194.948192ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:39:36.533665Z","caller":"traceutil/trace.go:171","msg":"trace[565686550] linearizableReadLoop","detail":"{readStateIndex:6310; appliedIndex:6309; }","duration":"119.053494ms","start":"2026-07-07T07:39:36.414584Z","end":"2026-07-07T07:39:36.533637Z","steps":["trace[565686550] 'read index received' (duration: 49.030481ms)","trace[565686550] 'applied index is now lower than readState.Index' (duration: 70.021883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:39:36.533844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.243358ms","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-07-07T07:39:36.533886Z","caller":"traceutil/trace.go:171","msg":"trace[1814239328] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:6081; }","duration":"119.356911ms","start":"2026-07-07T07:39:36.414518Z","end":"2026-07-07T07:39:36.533875Z","steps":["trace[1814239328] 'agreement among raft nodes before linearized reading' (duration: 119.230258ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:39:36.533883Z","caller":"traceutil/trace.go:171","msg":"trace[2033369262] transaction","detail":"{read_only:false; response_revision:6081; number_of_response:1; }","duration":"163.615533ms","start":"2026-07-07T07:39:36.37024Z","end":"2026-07-07T07:39:36.533855Z","steps":["trace[2033369262] 'process raft request' (duration: 93.441066ms)","trace[2033369262] 'compare' (duration: 69.816498ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:39:36.775056Z","caller":"traceutil/trace.go:171","msg":"trace[706054443] transaction","detail":"{read_only:false; response_revision:6083; number_of_response:1; }","duration":"141.175265ms","start":"2026-07-07T07:39:36.63384Z","end":"2026-07-07T07:39:36.775016Z","steps":["trace[706054443] 'process raft request' (duration: 113.534287ms)","trace[706054443] 'compare' (duration: 27.249418ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:39:49.765783Z","caller":"traceutil/trace.go:171","msg":"trace[1505916551] transaction","detail":"{read_only:false; response_revision:6149; number_of_response:1; }","duration":"124.387706ms","start":"2026-07-07T07:39:49.641368Z","end":"2026-07-07T07:39:49.765756Z","steps":["trace[1505916551] 'process raft request' (duration: 43.981311ms)","trace[1505916551] 'compare' (duration: 80.17903ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:40:38.283839Z","caller":"traceutil/trace.go:171","msg":"trace[1036683953] linearizableReadLoop","detail":"{readStateIndex:6674; appliedIndex:6673; }","duration":"146.717726ms","start":"2026-07-07T07:40:38.137093Z","end":"2026-07-07T07:40:38.283811Z","steps":["trace[1036683953] 'read index received' (duration: 146.590773ms)","trace[1036683953] 'applied index is now lower than readState.Index' (duration: 125.763µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:40:38.284385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.255779ms","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-07-07T07:40:38.284479Z","caller":"traceutil/trace.go:171","msg":"trace[771536742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6432; }","duration":"147.371192ms","start":"2026-07-07T07:40:38.137085Z","end":"2026-07-07T07:40:38.284457Z","steps":["trace[771536742] 'agreement among raft nodes before linearized reading' (duration: 147.005813ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:40:38.284787Z","caller":"traceutil/trace.go:171","msg":"trace[750287475] transaction","detail":"{read_only:false; response_revision:6432; number_of_response:1; }","duration":"168.598913ms","start":"2026-07-07T07:40:38.116175Z","end":"2026-07-07T07:40:38.284774Z","steps":["trace[750287475] 'process raft request' (duration: 167.473346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:50.654385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224935006178217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T07:40:53.508468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2330224935006178217,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T07:40:53.510091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.734269631s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T07:40:53.510118Z","caller":"traceutil/trace.go:171","msg":"trace[1359744411] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"4.734336532s","start":"2026-07-07T07:40:48.775772Z","end":"2026-07-07T07:40:53.510108Z","steps":["trace[1359744411] 'agreement among raft nodes before linearized reading' (duration: 4.734299022s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:53.510143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:48.775753Z","time spent":"4.734383774s","remote":"127.0.0.1:54910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T07:40:53.930632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.070302195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-07T07:40:53.930713Z","caller":"traceutil/trace.go:171","msg":"trace[736324306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.070439929s","start":"2026-07-07T07:40:48.860254Z","end":"2026-07-07T07:40:53.930694Z","steps":["trace[736324306] 'agreement among raft nodes before linearized reading' (duration: 5.070340136s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:53.930759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:48.860237Z","time spent":"5.07051109s","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T07:40:53.939624Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.165744587s","expected-duration":"1s"} {"level":"info","ts":"2026-07-07T07:40:53.94376Z","caller":"traceutil/trace.go:171","msg":"trace[164072008] transaction","detail":"{read_only:false; response_revision:6521; number_of_response:1; }","duration":"5.169926479s","start":"2026-07-07T07:40:48.773811Z","end":"2026-07-07T07:40:53.943738Z","steps":["trace[164072008] 'process raft request' (duration: 5.169785345s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:53.943901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:48.773799Z","time spent":"5.170026661s","remote":"127.0.0.1:55204","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-07-07T07:40:53.944305Z","caller":"traceutil/trace.go:171","msg":"trace[1131939358] linearizableReadLoop","detail":"{readStateIndex:6768; appliedIndex:6768; }","duration":"5.168489244s","start":"2026-07-07T07:40:48.775807Z","end":"2026-07-07T07:40:53.944296Z","steps":["trace[1131939358] 'read index received' (duration: 5.168485633s)","trace[1131939358] 'applied index is now lower than readState.Index' (duration: 2.961µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:40:54.363779Z","caller":"traceutil/trace.go:171","msg":"trace[7694174] linearizableReadLoop","detail":"{readStateIndex:6772; appliedIndex:6768; }","duration":"419.425659ms","start":"2026-07-07T07:40:53.944335Z","end":"2026-07-07T07:40:54.363761Z","steps":["trace[7694174] 'read index received' (duration: 414.209181ms)","trace[7694174] 'applied index is now lower than readState.Index' (duration: 5.215928ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:40:54.363829Z","caller":"traceutil/trace.go:171","msg":"trace[1211011238] transaction","detail":"{read_only:false; response_revision:6522; number_of_response:1; }","duration":"841.576776ms","start":"2026-07-07T07:40:53.522221Z","end":"2026-07-07T07:40:54.363798Z","steps":["trace[1211011238] 'process raft request' (duration: 836.388769ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:40:54.363824Z","caller":"traceutil/trace.go:171","msg":"trace[2141691277] transaction","detail":"{read_only:false; response_revision:6523; number_of_response:1; }","duration":"840.419107ms","start":"2026-07-07T07:40:53.523354Z","end":"2026-07-07T07:40:54.363773Z","steps":["trace[2141691277] 'process raft request' (duration: 840.289004ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:40:54.363883Z","caller":"traceutil/trace.go:171","msg":"trace[1118445016] transaction","detail":"{read_only:false; response_revision:6524; number_of_response:1; }","duration":"695.810947ms","start":"2026-07-07T07:40:53.668049Z","end":"2026-07-07T07:40:54.36386Z","steps":["trace[1118445016] 'process raft request' (duration: 695.638152ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:40:54.363925Z","caller":"traceutil/trace.go:171","msg":"trace[113236436] transaction","detail":"{read_only:false; response_revision:6525; number_of_response:1; }","duration":"695.695794ms","start":"2026-07-07T07:40:53.668221Z","end":"2026-07-07T07:40:54.363917Z","steps":["trace[113236436] 'process raft request' (duration: 695.504799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.363964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.522199Z","time spent":"841.689979ms","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T07:40:54.364011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.668218Z","time spent":"695.726055ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:40:54.364029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.668032Z","time spent":"695.91092ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:40:54.364022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.523348Z","time spent":"840.563891ms","remote":"127.0.0.1:55204","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-07-07T07:40:54.364328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.150225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:40:54.364358Z","caller":"traceutil/trace.go:171","msg":"trace[1175727778] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:6525; }","duration":"696.203297ms","start":"2026-07-07T07:40:53.668147Z","end":"2026-07-07T07:40:54.36435Z","steps":["trace[1175727778] 'agreement among raft nodes before linearized reading' (duration: 696.016922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.364353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.861765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T07:40:54.364379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.668139Z","time spent":"696.234327ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":38,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-07T07:40:54.364384Z","caller":"traceutil/trace.go:171","msg":"trace[1713044046] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:6525; }","duration":"837.936367ms","start":"2026-07-07T07:40:53.526436Z","end":"2026-07-07T07:40:54.364372Z","steps":["trace[1713044046] 'agreement among raft nodes before linearized reading' (duration: 837.890786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.364387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.174585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-07T07:40:54.364407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.526419Z","time spent":"837.981768ms","remote":"127.0.0.1:43084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-07-07T07:40:54.364426Z","caller":"traceutil/trace.go:171","msg":"trace[1835277065] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6525; }","duration":"696.221877ms","start":"2026-07-07T07:40:53.668194Z","end":"2026-07-07T07:40:54.364416Z","steps":["trace[1835277065] 'agreement among raft nodes before linearized reading' (duration: 696.033223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.36445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.668192Z","time spent":"696.252958ms","remote":"127.0.0.1:55124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-07T07:40:54.364494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.045599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:40:54.364526Z","caller":"traceutil/trace.go:171","msg":"trace[201101852] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:6525; }","duration":"840.10145ms","start":"2026-07-07T07:40:53.524417Z","end":"2026-07-07T07:40:54.364518Z","steps":["trace[201101852] 'agreement among raft nodes before linearized reading' (duration: 839.399923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.364559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.524412Z","time spent":"840.141401ms","remote":"127.0.0.1:55244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:40:54.364827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.488864ms","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-07-07T07:40:54.364864Z","caller":"traceutil/trace.go:171","msg":"trace[418866622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6525; }","duration":"841.539185ms","start":"2026-07-07T07:40:53.523317Z","end":"2026-07-07T07:40:54.364856Z","steps":["trace[418866622] 'agreement among raft nodes before linearized reading' (duration: 840.544781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.364889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.523315Z","time spent":"841.564196ms","remote":"127.0.0.1:55204","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-07-07T07:40:54.364901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.583106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:40:54.364934Z","caller":"traceutil/trace.go:171","msg":"trace[562579661] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:6525; }","duration":"841.626938ms","start":"2026-07-07T07:40:53.523298Z","end":"2026-07-07T07:40:54.364925Z","steps":["trace[562579661] 'agreement among raft nodes before linearized reading' (duration: 840.569332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.364966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.523292Z","time spent":"841.666679ms","remote":"127.0.0.1:38792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:40:54.364978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.675113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:40:54.365014Z","caller":"traceutil/trace.go:171","msg":"trace[1951493685] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:6525; }","duration":"842.736545ms","start":"2026-07-07T07:40:53.522271Z","end":"2026-07-07T07:40:54.365008Z","steps":["trace[1951493685] 'agreement among raft nodes before linearized reading' (duration: 841.603097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.365045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.522266Z","time spent":"842.763185ms","remote":"127.0.0.1:55108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:40:54.365147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.744289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:40:54.36517Z","caller":"traceutil/trace.go:171","msg":"trace[1513401634] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:6525; }","duration":"856.77287ms","start":"2026-07-07T07:40:53.50839Z","end":"2026-07-07T07:40:54.365163Z","steps":["trace[1513401634] 'agreement among raft nodes before linearized reading' (duration: 855.490288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.365188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.508385Z","time spent":"856.79763ms","remote":"127.0.0.1:49968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:40:54.365267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.925923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.108\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-07T07:40:54.365295Z","caller":"traceutil/trace.go:171","msg":"trace[1468397192] range","detail":"{range_begin:/registry/masterleases/199.19.213.108; range_end:; response_count:1; response_revision:6525; }","duration":"856.960874ms","start":"2026-07-07T07:40:53.508327Z","end":"2026-07-07T07:40:54.365288Z","steps":["trace[1468397192] 'agreement among raft nodes before linearized reading' (duration: 855.559329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.365319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.508324Z","time spent":"856.989494ms","remote":"127.0.0.1:54958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.108\" "} {"level":"warn","ts":"2026-07-07T07:40:54.365427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.115918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T07:40:54.365451Z","caller":"traceutil/trace.go:171","msg":"trace[1940367119] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:6525; }","duration":"857.16747ms","start":"2026-07-07T07:40:53.508277Z","end":"2026-07-07T07:40:54.365445Z","steps":["trace[1940367119] 'agreement among raft nodes before linearized reading' (duration: 855.615201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.365472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.508264Z","time spent":"857.20196ms","remote":"127.0.0.1:55276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":72,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:40:54.365539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.343835ms","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-07-07T07:40:54.365565Z","caller":"traceutil/trace.go:171","msg":"trace[1947652198] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:6525; }","duration":"697.379525ms","start":"2026-07-07T07:40:53.668179Z","end":"2026-07-07T07:40:54.365558Z","steps":["trace[1947652198] 'agreement among raft nodes before linearized reading' (duration: 695.725675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.365588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.668176Z","time spent":"697.404006ms","remote":"127.0.0.1:38774","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-07-07T07:40:54.365068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.676949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-07T07:40:54.365698Z","caller":"traceutil/trace.go:171","msg":"trace[1270935727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6525; }","duration":"842.315395ms","start":"2026-07-07T07:40:53.523374Z","end":"2026-07-07T07:40:54.365689Z","steps":["trace[1270935727] 'agreement among raft nodes before linearized reading' (duration: 840.474839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.365721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:53.523372Z","time spent":"842.343215ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T07:40:54.373896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.701513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-07T07:40:54.37401Z","caller":"traceutil/trace.go:171","msg":"trace[1588837733] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6526; }","duration":"357.831497ms","start":"2026-07-07T07:40:54.016163Z","end":"2026-07-07T07:40:54.373994Z","steps":["trace[1588837733] 'agreement among raft nodes before linearized reading' (duration: 357.566841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.374042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:54.016157Z","time spent":"357.877868ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-07T07:40:54.374098Z","caller":"traceutil/trace.go:171","msg":"trace[998266401] transaction","detail":"{read_only:false; response_revision:6526; number_of_response:1; }","duration":"357.839677ms","start":"2026-07-07T07:40:54.016227Z","end":"2026-07-07T07:40:54.374066Z","steps":["trace[998266401] 'process raft request' (duration: 357.274953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.374198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.018154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:40:54.374218Z","caller":"traceutil/trace.go:171","msg":"trace[762988894] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:6526; }","duration":"295.076176ms","start":"2026-07-07T07:40:54.079136Z","end":"2026-07-07T07:40:54.374213Z","steps":["trace[762988894] 'agreement among raft nodes before linearized reading' (duration: 295.034895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.374266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:54.016214Z","time spent":"357.95347ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:40:54.374345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.145615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-07T07:40:54.374366Z","caller":"traceutil/trace.go:171","msg":"trace[201758183] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6526; }","duration":"358.174435ms","start":"2026-07-07T07:40:54.016183Z","end":"2026-07-07T07:40:54.374358Z","steps":["trace[201758183] 'agreement among raft nodes before linearized reading' (duration: 358.123244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.37438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:54.016174Z","time spent":"358.203426ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-07T07:40:54.374691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.521774ms","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-07-07T07:40:54.374708Z","caller":"traceutil/trace.go:171","msg":"trace[839144009] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6526; }","duration":"358.587626ms","start":"2026-07-07T07:40:54.016115Z","end":"2026-07-07T07:40:54.374703Z","steps":["trace[839144009] 'agreement among raft nodes before linearized reading' (duration: 358.542385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.374724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:54.016097Z","time spent":"358.623046ms","remote":"127.0.0.1:38582","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-07-07T07:40:54.374727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.602086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-07T07:40:54.374761Z","caller":"traceutil/trace.go:171","msg":"trace[387515224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6526; }","duration":"358.647077ms","start":"2026-07-07T07:40:54.016105Z","end":"2026-07-07T07:40:54.374752Z","steps":["trace[387515224] 'agreement among raft nodes before linearized reading' (duration: 358.507464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.374783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:54.0161Z","time spent":"358.676868ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-07T07:40:54.374959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.864282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-07T07:40:54.374985Z","caller":"traceutil/trace.go:171","msg":"trace[1580745783] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6526; }","duration":"358.923164ms","start":"2026-07-07T07:40:54.016053Z","end":"2026-07-07T07:40:54.374976Z","steps":["trace[1580745783] 'agreement among raft nodes before linearized reading' (duration: 358.862122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.375001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:54.016031Z","time spent":"358.966095ms","remote":"127.0.0.1:55204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-07T07:40:54.375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.951005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:40:54.37503Z","caller":"traceutil/trace.go:171","msg":"trace[171101537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6526; }","duration":"359.018197ms","start":"2026-07-07T07:40:54.016005Z","end":"2026-07-07T07:40:54.375023Z","steps":["trace[171101537] 'agreement among raft nodes before linearized reading' (duration: 358.960985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:40:54.375049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:40:54.015989Z","time spent":"359.055537ms","remote":"127.0.0.1:54926","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-07-07T07:41:54.850303Z","caller":"traceutil/trace.go:171","msg":"trace[800261851] transaction","detail":"{read_only:false; response_revision:7020; number_of_response:1; }","duration":"163.788412ms","start":"2026-07-07T07:41:54.686485Z","end":"2026-07-07T07:41:54.850273Z","steps":["trace[800261851] 'process raft request' (duration: 163.634608ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:41:54.939046Z","caller":"traceutil/trace.go:171","msg":"trace[1917367622] linearizableReadLoop","detail":"{readStateIndex:7286; appliedIndex:7284; }","duration":"138.707447ms","start":"2026-07-07T07:41:54.800312Z","end":"2026-07-07T07:41:54.93902Z","steps":["trace[1917367622] 'read index received' (duration: 49.924323ms)","trace[1917367622] 'applied index is now lower than readState.Index' (duration: 88.782044ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:41:54.939133Z","caller":"traceutil/trace.go:171","msg":"trace[1118959352] transaction","detail":"{read_only:false; response_revision:7021; number_of_response:1; }","duration":"196.547015ms","start":"2026-07-07T07:41:54.742568Z","end":"2026-07-07T07:41:54.939115Z","steps":["trace[1118959352] 'process raft request' (duration: 148.113828ms)","trace[1118959352] 'compare' (duration: 48.142088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:41:54.939317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.968125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-07T07:41:54.939321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.019966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-07T07:41:54.939359Z","caller":"traceutil/trace.go:171","msg":"trace[876042951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7021; }","duration":"139.077878ms","start":"2026-07-07T07:41:54.800271Z","end":"2026-07-07T07:41:54.939348Z","steps":["trace[876042951] 'agreement among raft nodes before linearized reading' (duration: 138.916643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:41:54.939583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.501059ms","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-07-07T07:41:54.939617Z","caller":"traceutil/trace.go:171","msg":"trace[7195906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7021; }","duration":"115.53582ms","start":"2026-07-07T07:41:54.824074Z","end":"2026-07-07T07:41:54.93961Z","steps":["trace[7195906] 'agreement among raft nodes before linearized reading' (duration: 115.465228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:41:54.93961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.610237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-07T07:41:54.939683Z","caller":"traceutil/trace.go:171","msg":"trace[997425581] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7021; }","duration":"108.71521ms","start":"2026-07-07T07:41:54.830951Z","end":"2026-07-07T07:41:54.939666Z","steps":["trace[997425581] 'agreement among raft nodes before linearized reading' (duration: 108.544325ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:41:54.939371Z","caller":"traceutil/trace.go:171","msg":"trace[393622478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7021; }","duration":"116.102719ms","start":"2026-07-07T07:41:54.823256Z","end":"2026-07-07T07:41:54.939358Z","steps":["trace[393622478] 'agreement among raft nodes before linearized reading' (duration: 115.999035ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:41:55.24909Z","caller":"traceutil/trace.go:171","msg":"trace[1132518213] transaction","detail":"{read_only:false; response_revision:7028; number_of_response:1; }","duration":"220.729997ms","start":"2026-07-07T07:41:55.028342Z","end":"2026-07-07T07:41:55.249072Z","steps":["trace[1132518213] 'process raft request' (duration: 137.665072ms)","trace[1132518213] 'compare' (duration: 82.615961ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:42:46.627674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5278} {"level":"info","ts":"2026-07-07T07:42:46.740403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5278,"took":"111.280256ms","hash":920449180,"current-db-size-bytes":51695616,"current-db-size":"52 MB","current-db-size-in-use-bytes":18644992,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-07T07:42:46.740462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":920449180,"revision":5278,"compact-revision":2808} {"level":"info","ts":"2026-07-07T07:44:49.379634Z","caller":"traceutil/trace.go:171","msg":"trace[1797566680] linearizableReadLoop","detail":"{readStateIndex:8236; appliedIndex:8235; }","duration":"118.625673ms","start":"2026-07-07T07:44:49.260976Z","end":"2026-07-07T07:44:49.379601Z","steps":["trace[1797566680] 'read index received' (duration: 89.614002ms)","trace[1797566680] 'applied index is now lower than readState.Index' (duration: 29.010691ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:44:49.379715Z","caller":"traceutil/trace.go:171","msg":"trace[1673562343] transaction","detail":"{read_only:false; response_revision:7934; number_of_response:1; }","duration":"131.110872ms","start":"2026-07-07T07:44:49.248561Z","end":"2026-07-07T07:44:49.379672Z","steps":["trace[1673562343] 'process raft request' (duration: 102.071061ms)","trace[1673562343] 'compare' (duration: 28.824475ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:44:49.379973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.037816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-prometheus-tls\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-07-07T07:44:49.380119Z","caller":"traceutil/trace.go:171","msg":"trace[108396676] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-prometheus-tls; range_end:; response_count:1; response_revision:7934; }","duration":"152.257382ms","start":"2026-07-07T07:44:49.227835Z","end":"2026-07-07T07:44:49.380093Z","steps":["trace[108396676] 'agreement among raft nodes before linearized reading' (duration: 151.919613ms)"],"step_count":1}