{"level":"warn","ts":"2026-06-02T23:46:38.11214Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T23:46:38.112266Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-06-02T23:46:38.112347Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T23:46:38.112369Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-02T23:46:38.112395Z","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-06-02T23:46:38.113194Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-06-02T23:46:38.113287Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-06-02T23:46:38.120072Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.496601ms"} {"level":"info","ts":"2026-06-02T23:46:38.128847Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-06-02T23:46:38.128981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T23:46:38.129043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-06-02T23:46:38.129068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T23:46:38.129099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-06-02T23:46:38.129175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-06-02T23:46:38.135843Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T23:46:38.137166Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T23:46:38.138294Z","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-06-02T23:46:38.140367Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T23:46:38.142628Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T23:46:38.143713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-06-02T23:46:38.143906Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-02T23:46:38.144029Z","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-06-02T23:46:38.144103Z","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-06-02T23:46:38.144117Z","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-06-02T23:46:38.146138Z","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-06-02T23:46:38.146289Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-02T23:46:38.146348Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-02T23:46:38.146577Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T23:46:38.146735Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T23:46:38.829625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T23:46:38.829681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T23:46:38.829723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-06-02T23:46:38.829737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-06-02T23:46:38.829745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-02T23:46:38.829756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-06-02T23:46:38.829765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-02T23:46:38.830804Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T23:46:38.830802Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:46:38.830848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T23:46:38.830907Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T23:46:38.831949Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:46:38.832058Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:46:38.832122Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T23:46:38.832892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-06-02T23:46:38.833513Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T23:46:38.851325Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T23:46:38.851425Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T23:46:43.496183Z","caller":"traceutil/trace.go:171","msg":"trace[613971921] linearizableReadLoop","detail":"{readStateIndex:249; appliedIndex:248; }","duration":"110.362973ms","start":"2026-06-02T23:46:43.385786Z","end":"2026-06-02T23:46:43.496148Z","steps":["trace[613971921] 'read index received' (duration: 73.893525ms)","trace[613971921] 'applied index is now lower than readState.Index' (duration: 36.468198ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:46:43.496268Z","caller":"traceutil/trace.go:171","msg":"trace[724471277] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"159.171475ms","start":"2026-06-02T23:46:43.337041Z","end":"2026-06-02T23:46:43.496212Z","steps":["trace[724471277] 'process raft request' (duration: 122.644157ms)","trace[724471277] 'compare' (duration: 36.276846ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:46:43.496715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.863357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:46:43.496797Z","caller":"traceutil/trace.go:171","msg":"trace[1252160679] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:243; }","duration":"111.037519ms","start":"2026-06-02T23:46:43.385739Z","end":"2026-06-02T23:46:43.496776Z","steps":["trace[1252160679] 'agreement among raft nodes before linearized reading' (duration: 110.561845ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:44.12537Z","caller":"traceutil/trace.go:171","msg":"trace[638298704] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"189.180178ms","start":"2026-06-02T23:46:43.936163Z","end":"2026-06-02T23:46:44.125343Z","steps":["trace[638298704] 'read index received' (duration: 161.598414ms)","trace[638298704] 'applied index is now lower than readState.Index' (duration: 27.580624ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:46:44.125408Z","caller":"traceutil/trace.go:171","msg":"trace[20994567] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"197.099091ms","start":"2026-06-02T23:46:43.928276Z","end":"2026-06-02T23:46:44.125375Z","steps":["trace[20994567] 'process raft request' (duration: 169.490907ms)","trace[20994567] 'compare' (duration: 27.282701ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:46:44.125551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.04896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-02T23:46:44.12559Z","caller":"traceutil/trace.go:171","msg":"trace[1019320808] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:250; }","duration":"198.10918ms","start":"2026-06-02T23:46:43.92747Z","end":"2026-06-02T23:46:44.12558Z","steps":["trace[1019320808] 'agreement among raft nodes before linearized reading' (duration: 197.976969ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:44.201323Z","caller":"traceutil/trace.go:171","msg":"trace[1281416788] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"262.356453ms","start":"2026-06-02T23:46:43.938948Z","end":"2026-06-02T23:46:44.201305Z","steps":["trace[1281416788] 'process raft request' (duration: 262.152831ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:44.20144Z","caller":"traceutil/trace.go:171","msg":"trace[2033084989] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"152.025943ms","start":"2026-06-02T23:46:44.049401Z","end":"2026-06-02T23:46:44.201427Z","steps":["trace[2033084989] 'process raft request' (duration: 151.951852ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:44.201458Z","caller":"traceutil/trace.go:171","msg":"trace[657411694] transaction","detail":"{read_only:false; number_of_response:0; response_revision:251; }","duration":"171.666874ms","start":"2026-06-02T23:46:44.029744Z","end":"2026-06-02T23:46:44.201411Z","steps":["trace[657411694] 'process raft request' (duration: 171.471982ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:44.201504Z","caller":"traceutil/trace.go:171","msg":"trace[904400921] transaction","detail":"{read_only:false; number_of_response:0; response_revision:251; }","duration":"154.110252ms","start":"2026-06-02T23:46:44.047371Z","end":"2026-06-02T23:46:44.201481Z","steps":["trace[904400921] 'process raft request' (duration: 153.87955ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:44.201525Z","caller":"traceutil/trace.go:171","msg":"trace[681403109] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"152.80449ms","start":"2026-06-02T23:46:44.048701Z","end":"2026-06-02T23:46:44.201505Z","steps":["trace[681403109] 'process raft request' (duration: 152.572818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:46:44.201582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.139141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-06-02T23:46:44.20163Z","caller":"traceutil/trace.go:171","msg":"trace[1235258339] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"152.82953ms","start":"2026-06-02T23:46:44.04879Z","end":"2026-06-02T23:46:44.20162Z","steps":["trace[1235258339] 'process raft request' (duration: 152.543327ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:44.201643Z","caller":"traceutil/trace.go:171","msg":"trace[857250256] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:252; }","duration":"262.242532ms","start":"2026-06-02T23:46:43.939389Z","end":"2026-06-02T23:46:44.201631Z","steps":["trace[857250256] 'agreement among raft nodes before linearized reading' (duration: 262.116051ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:46:44.201685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.456191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-02T23:46:44.201749Z","caller":"traceutil/trace.go:171","msg":"trace[321062632] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:252; }","duration":"265.546522ms","start":"2026-06-02T23:46:43.936185Z","end":"2026-06-02T23:46:44.201731Z","steps":["trace[321062632] 'agreement among raft nodes before linearized reading' (duration: 265.436601ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:44.201454Z","caller":"traceutil/trace.go:171","msg":"trace[1665857053] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"151.890141ms","start":"2026-06-02T23:46:44.049541Z","end":"2026-06-02T23:46:44.201432Z","steps":["trace[1665857053] 'process raft request' (duration: 151.828651ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:46.47599Z","caller":"traceutil/trace.go:171","msg":"trace[2110154476] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"189.645753ms","start":"2026-06-02T23:46:46.286314Z","end":"2026-06-02T23:46:46.475959Z","steps":["trace[2110154476] 'read index received' (duration: 172.864689ms)","trace[2110154476] 'applied index is now lower than readState.Index' (duration: 16.779844ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:46:46.476111Z","caller":"traceutil/trace.go:171","msg":"trace[1299735064] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"218.434637ms","start":"2026-06-02T23:46:46.257623Z","end":"2026-06-02T23:46:46.476058Z","steps":["trace[1299735064] 'process raft request' (duration: 201.587162ms)","trace[1299735064] 'compare' (duration: 16.618402ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:46:46.476235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.888936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-06-02T23:46:46.476195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.355956ms","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-06-02T23:46:46.476356Z","caller":"traceutil/trace.go:171","msg":"trace[1457855620] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:297; }","duration":"190.056467ms","start":"2026-06-02T23:46:46.28627Z","end":"2026-06-02T23:46:46.476327Z","steps":["trace[1457855620] 'agreement among raft nodes before linearized reading' (duration: 189.837175ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:46.476473Z","caller":"traceutil/trace.go:171","msg":"trace[1847888219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:297; }","duration":"125.670089ms","start":"2026-06-02T23:46:46.350754Z","end":"2026-06-02T23:46:46.476424Z","steps":["trace[1847888219] 'agreement among raft nodes before linearized reading' (duration: 125.372926ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:47.451974Z","caller":"traceutil/trace.go:171","msg":"trace[1792913333] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"106.004154ms","start":"2026-06-02T23:46:47.345946Z","end":"2026-06-02T23:46:47.45195Z","steps":["trace[1792913333] 'process raft request' (duration: 105.792232ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:47.452176Z","caller":"traceutil/trace.go:171","msg":"trace[1667916985] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"106.182176ms","start":"2026-06-02T23:46:47.345965Z","end":"2026-06-02T23:46:47.452148Z","steps":["trace[1667916985] 'process raft request' (duration: 105.941114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:46:47.73097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.12901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:46:47.731102Z","caller":"traceutil/trace.go:171","msg":"trace[1636027451] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:326; }","duration":"343.577869ms","start":"2026-06-02T23:46:47.387512Z","end":"2026-06-02T23:46:47.73109Z","steps":["trace[1636027451] 'read index received' (duration: 64.289475ms)","trace[1636027451] 'applied index is now lower than readState.Index' (duration: 279.287254ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:46:47.731184Z","caller":"traceutil/trace.go:171","msg":"trace[1384912831] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"384.812185ms","start":"2026-06-02T23:46:47.346364Z","end":"2026-06-02T23:46:47.731176Z","steps":["trace[1384912831] 'process raft request' (duration: 203.015288ms)","trace[1384912831] 'compare' (duration: 181.010799ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:46:47.731249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:46:47.346355Z","time spent":"384.871885ms","remote":"127.0.0.1:38678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:46:47.731397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.876651ms","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-06-02T23:46:47.731458Z","caller":"traceutil/trace.go:171","msg":"trace[1156411214] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:314; }","duration":"343.976772ms","start":"2026-06-02T23:46:47.387465Z","end":"2026-06-02T23:46:47.731442Z","steps":["trace[1156411214] 'agreement among raft nodes before linearized reading' (duration: 343.867491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:46:47.731502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:46:47.387452Z","time spent":"344.041902ms","remote":"127.0.0.1:38516","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-06-02T23:46:47.731578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.26064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-02T23:46:47.731697Z","caller":"traceutil/trace.go:171","msg":"trace[754646362] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:314; }","duration":"294.496792ms","start":"2026-06-02T23:46:47.437174Z","end":"2026-06-02T23:46:47.731671Z","steps":["trace[754646362] 'agreement among raft nodes before linearized reading' (duration: 294.195429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:46:47.731719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.706808ms","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-06-02T23:46:47.731781Z","caller":"traceutil/trace.go:171","msg":"trace[1500801750] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:314; }","duration":"143.767549ms","start":"2026-06-02T23:46:47.588006Z","end":"2026-06-02T23:46:47.731773Z","steps":["trace[1500801750] 'agreement among raft nodes before linearized reading' (duration: 143.654198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:46:47.731846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.388046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-06-02T23:46:47.731636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.912996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-06-02T23:46:47.731893Z","caller":"traceutil/trace.go:171","msg":"trace[1701194628] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:314; }","duration":"245.463086ms","start":"2026-06-02T23:46:47.486419Z","end":"2026-06-02T23:46:47.731882Z","steps":["trace[1701194628] 'agreement among raft nodes before linearized reading' (duration: 245.393825ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:47.731943Z","caller":"traceutil/trace.go:171","msg":"trace[2138920114] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:314; }","duration":"195.251929ms","start":"2026-06-02T23:46:47.536659Z","end":"2026-06-02T23:46:47.731911Z","steps":["trace[2138920114] 'agreement among raft nodes before linearized reading' (duration: 194.943646ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:48.002438Z","caller":"traceutil/trace.go:171","msg":"trace[1580918479] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"263.105836ms","start":"2026-06-02T23:46:47.739302Z","end":"2026-06-02T23:46:48.002408Z","steps":["trace[1580918479] 'process raft request' (duration: 227.837935ms)","trace[1580918479] 'compare' (duration: 34.907087ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:46:48.002726Z","caller":"traceutil/trace.go:171","msg":"trace[370580737] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"262.044725ms","start":"2026-06-02T23:46:47.740638Z","end":"2026-06-02T23:46:48.002683Z","steps":["trace[370580737] 'process raft request' (duration: 261.698222ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:48.003041Z","caller":"traceutil/trace.go:171","msg":"trace[704298598] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"260.35004ms","start":"2026-06-02T23:46:47.742677Z","end":"2026-06-02T23:46:48.003027Z","steps":["trace[704298598] 'process raft request' (duration: 260.28479ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:48.003096Z","caller":"traceutil/trace.go:171","msg":"trace[1865577399] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"262.058226ms","start":"2026-06-02T23:46:47.741027Z","end":"2026-06-02T23:46:48.003085Z","steps":["trace[1865577399] 'process raft request' (duration: 261.782953ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:48.003223Z","caller":"traceutil/trace.go:171","msg":"trace[2122195907] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"261.949115ms","start":"2026-06-02T23:46:47.741243Z","end":"2026-06-02T23:46:48.003192Z","steps":["trace[2122195907] 'process raft request' (duration: 261.629842ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:46:48.002994Z","caller":"traceutil/trace.go:171","msg":"trace[2141606820] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"262.228858ms","start":"2026-06-02T23:46:47.740726Z","end":"2026-06-02T23:46:48.002955Z","steps":["trace[2141606820] 'process raft request' (duration: 261.834294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:46:48.628788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.963961ms","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-06-02T23:46:48.628889Z","caller":"traceutil/trace.go:171","msg":"trace[401905940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:345; }","duration":"148.113382ms","start":"2026-06-02T23:46:48.480752Z","end":"2026-06-02T23:46:48.628865Z","steps":["trace[401905940] 'range keys from in-memory index tree' (duration: 147.762019ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:05.459192Z","caller":"traceutil/trace.go:171","msg":"trace[495621477] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"197.240268ms","start":"2026-06-02T23:47:05.261929Z","end":"2026-06-02T23:47:05.459169Z","steps":["trace[495621477] 'read index received' (duration: 197.130763ms)","trace[495621477] 'applied index is now lower than readState.Index' (duration: 108.345µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:05.459327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.378724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:05.459353Z","caller":"traceutil/trace.go:171","msg":"trace[134133979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"197.456678ms","start":"2026-06-02T23:47:05.261887Z","end":"2026-06-02T23:47:05.459344Z","steps":["trace[134133979] 'agreement among raft nodes before linearized reading' (duration: 197.380385ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:05.459323Z","caller":"traceutil/trace.go:171","msg":"trace[696564440] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"393.528519ms","start":"2026-06-02T23:47:05.065753Z","end":"2026-06-02T23:47:05.459282Z","steps":["trace[696564440] 'process raft request' (duration: 393.287038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:05.459486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:05.065729Z","time spent":"393.687167ms","remote":"127.0.0.1:38800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:05.606972Z","caller":"traceutil/trace.go:171","msg":"trace[802986995] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"141.93121ms","start":"2026-06-02T23:47:05.46501Z","end":"2026-06-02T23:47:05.606941Z","steps":["trace[802986995] 'process raft request' (duration: 134.406391ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:05.607647Z","caller":"traceutil/trace.go:171","msg":"trace[1512170437] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"139.029178ms","start":"2026-06-02T23:47:05.468604Z","end":"2026-06-02T23:47:05.607633Z","steps":["trace[1512170437] 'process raft request' (duration: 138.983506ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:05.607686Z","caller":"traceutil/trace.go:171","msg":"trace[697651387] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"141.590233ms","start":"2026-06-02T23:47:05.466066Z","end":"2026-06-02T23:47:05.607656Z","steps":["trace[697651387] 'process raft request' (duration: 141.459096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:10.73492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.691309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:47:10.73504Z","caller":"traceutil/trace.go:171","msg":"trace[686035933] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"186.941602ms","start":"2026-06-02T23:47:10.54808Z","end":"2026-06-02T23:47:10.735022Z","steps":["trace[686035933] 'process raft request' (duration: 21.048294ms)","trace[686035933] 'compare' (duration: 165.577364ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:47:11.571671Z","caller":"traceutil/trace.go:171","msg":"trace[2045176848] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"735.801465ms","start":"2026-06-02T23:47:10.835842Z","end":"2026-06-02T23:47:11.571643Z","steps":["trace[2045176848] 'process raft request' (duration: 735.493421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:11.571908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:10.835816Z","time spent":"735.938341ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:47:12.076758Z","caller":"traceutil/trace.go:171","msg":"trace[1402115316] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:460; }","duration":"814.61005ms","start":"2026-06-02T23:47:11.26211Z","end":"2026-06-02T23:47:12.07672Z","steps":["trace[1402115316] 'read index received' (duration: 309.506917ms)","trace[1402115316] 'applied index is now lower than readState.Index' (duration: 505.101392ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:12.076969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.823489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:12.077073Z","caller":"traceutil/trace.go:171","msg":"trace[899261009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:441; }","duration":"814.940444ms","start":"2026-06-02T23:47:11.262105Z","end":"2026-06-02T23:47:12.077045Z","steps":["trace[899261009] 'agreement among raft nodes before linearized reading' (duration: 814.723734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:12.077139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:11.262062Z","time spent":"815.06207ms","remote":"127.0.0.1:38312","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-06-02T23:47:12.076816Z","caller":"traceutil/trace.go:171","msg":"trace[2016853380] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"1.178763421s","start":"2026-06-02T23:47:10.898Z","end":"2026-06-02T23:47:12.076764Z","steps":["trace[2016853380] 'process raft request' (duration: 1.12549158s)","trace[2016853380] 'compare' (duration: 52.972608ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:12.077375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.657684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-02T23:47:12.077473Z","caller":"traceutil/trace.go:171","msg":"trace[856760707] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:441; }","duration":"246.890765ms","start":"2026-06-02T23:47:11.830565Z","end":"2026-06-02T23:47:12.077455Z","steps":["trace[856760707] 'agreement among raft nodes before linearized reading' (duration: 246.521448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:12.077455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:10.897973Z","time spent":"1.179348398s","remote":"127.0.0.1:38584","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-06-02T23:47:13.712475Z","caller":"traceutil/trace.go:171","msg":"trace[1875840505] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"131.494257ms","start":"2026-06-02T23:47:13.580946Z","end":"2026-06-02T23:47:13.71244Z","steps":["trace[1875840505] 'process raft request' (duration: 131.311269ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:19.937607Z","caller":"traceutil/trace.go:171","msg":"trace[1224315759] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"331.129262ms","start":"2026-06-02T23:47:19.606444Z","end":"2026-06-02T23:47:19.937573Z","steps":["trace[1224315759] 'process raft request' (duration: 330.995956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:19.937786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.606422Z","time spent":"331.295199ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:19.948813Z","caller":"traceutil/trace.go:171","msg":"trace[625530498] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"328.658017ms","start":"2026-06-02T23:47:19.62013Z","end":"2026-06-02T23:47:19.948788Z","steps":["trace[625530498] 'process raft request' (duration: 328.369865ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:19.94887Z","caller":"traceutil/trace.go:171","msg":"trace[787389674] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"325.592719ms","start":"2026-06-02T23:47:19.623244Z","end":"2026-06-02T23:47:19.948836Z","steps":["trace[787389674] 'process raft request' (duration: 325.348349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:19.948979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.620113Z","time spent":"328.790873ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:19.949143Z","caller":"traceutil/trace.go:171","msg":"trace[508143951] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"325.957745ms","start":"2026-06-02T23:47:19.623172Z","end":"2026-06-02T23:47:19.94913Z","steps":["trace[508143951] 'process raft request' (duration: 325.389521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:19.949207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.623162Z","time spent":"326.008266ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:19.949246Z","caller":"traceutil/trace.go:171","msg":"trace[1811744608] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:516; }","duration":"207.844949ms","start":"2026-06-02T23:47:19.741376Z","end":"2026-06-02T23:47:19.949221Z","steps":["trace[1811744608] 'read index received' (duration: 196.191511ms)","trace[1811744608] 'applied index is now lower than readState.Index' (duration: 11.652808ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:47:19.949351Z","caller":"traceutil/trace.go:171","msg":"trace[1839553146] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"338.172426ms","start":"2026-06-02T23:47:19.611146Z","end":"2026-06-02T23:47:19.949318Z","steps":["trace[1839553146] 'process raft request' (duration: 337.163994ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:19.949369Z","caller":"traceutil/trace.go:171","msg":"trace[2004219536] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"325.931613ms","start":"2026-06-02T23:47:19.62343Z","end":"2026-06-02T23:47:19.949361Z","steps":["trace[2004219536] 'process raft request' (duration: 325.192592ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:19.94946Z","caller":"traceutil/trace.go:171","msg":"trace[779669729] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"320.078997ms","start":"2026-06-02T23:47:19.629359Z","end":"2026-06-02T23:47:19.949438Z","steps":["trace[779669729] 'process raft request' (duration: 319.328816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:19.949462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.611132Z","time spent":"338.268251ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:47:19.949508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.629347Z","time spent":"320.13396ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:47:19.949026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.623238Z","time spent":"325.708644ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:19.949537Z","caller":"traceutil/trace.go:171","msg":"trace[1805120274] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"337.425906ms","start":"2026-06-02T23:47:19.61209Z","end":"2026-06-02T23:47:19.949516Z","steps":["trace[1805120274] 'process raft request' (duration: 336.33174ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:19.949505Z","caller":"traceutil/trace.go:171","msg":"trace[306194446] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"295.950285ms","start":"2026-06-02T23:47:19.653488Z","end":"2026-06-02T23:47:19.949438Z","steps":["trace[306194446] 'process raft request' (duration: 295.35434ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:19.949075Z","caller":"traceutil/trace.go:171","msg":"trace[1467809490] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"328.870217ms","start":"2026-06-02T23:47:19.620194Z","end":"2026-06-02T23:47:19.949065Z","steps":["trace[1467809490] 'process raft request' (duration: 328.334254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:19.949582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.612082Z","time spent":"337.479748ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:19.94961Z","caller":"traceutil/trace.go:171","msg":"trace[611026615] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"181.604599ms","start":"2026-06-02T23:47:19.767994Z","end":"2026-06-02T23:47:19.949599Z","steps":["trace[611026615] 'process raft request' (duration: 181.109438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:19.94963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.620189Z","time spent":"329.411919ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:19.949681Z","caller":"traceutil/trace.go:171","msg":"trace[1436349311] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"317.828314ms","start":"2026-06-02T23:47:19.631827Z","end":"2026-06-02T23:47:19.949656Z","steps":["trace[1436349311] 'process raft request' (duration: 316.942456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:19.949734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.34555ms","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-06-02T23:47:19.949773Z","caller":"traceutil/trace.go:171","msg":"trace[1503146647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"208.408603ms","start":"2026-06-02T23:47:19.741353Z","end":"2026-06-02T23:47:19.949761Z","steps":["trace[1503146647] 'agreement among raft nodes before linearized reading' (duration: 208.245046ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:19.949775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.623423Z","time spent":"325.972135ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:47:19.950565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:19.631818Z","time spent":"317.928487ms","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T23:47:19.958776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.94499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:19.958898Z","caller":"traceutil/trace.go:171","msg":"trace[1508883317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"211.043943ms","start":"2026-06-02T23:47:19.747798Z","end":"2026-06-02T23:47:19.958842Z","steps":["trace[1508883317] 'agreement among raft nodes before linearized reading' (duration: 210.841155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:26.914537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.134922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:47:26.914646Z","caller":"traceutil/trace.go:171","msg":"trace[1299104703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"279.321319ms","start":"2026-06-02T23:47:26.635303Z","end":"2026-06-02T23:47:26.914624Z","steps":["trace[1299104703] 'range keys from in-memory index tree' (duration: 278.957675ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:27.033933Z","caller":"traceutil/trace.go:171","msg":"trace[630786023] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"374.31873ms","start":"2026-06-02T23:47:26.659541Z","end":"2026-06-02T23:47:27.03386Z","steps":["trace[630786023] 'process raft request' (duration: 373.410995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:27.034281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:26.659507Z","time spent":"374.59701ms","remote":"127.0.0.1:38400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:47:27.0343Z","caller":"traceutil/trace.go:171","msg":"trace[1583845048] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"235.532369ms","start":"2026-06-02T23:47:26.798161Z","end":"2026-06-02T23:47:27.033693Z","steps":["trace[1583845048] 'read index received' (duration: 234.562112ms)","trace[1583845048] 'applied index is now lower than readState.Index' (duration: 969.037µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:27.034541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.31363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T23:47:27.034674Z","caller":"traceutil/trace.go:171","msg":"trace[1627817488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"236.545118ms","start":"2026-06-02T23:47:26.798103Z","end":"2026-06-02T23:47:27.034649Z","steps":["trace[1627817488] 'agreement among raft nodes before linearized reading' (duration: 235.870382ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:27.308703Z","caller":"traceutil/trace.go:171","msg":"trace[1317526692] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"274.254102ms","start":"2026-06-02T23:47:27.034418Z","end":"2026-06-02T23:47:27.308672Z","steps":["trace[1317526692] 'read index received' (duration: 216.340057ms)","trace[1317526692] 'applied index is now lower than readState.Index' (duration: 57.913035ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:47:27.308764Z","caller":"traceutil/trace.go:171","msg":"trace[591289517] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"390.900065ms","start":"2026-06-02T23:47:26.917835Z","end":"2026-06-02T23:47:27.308735Z","steps":["trace[591289517] 'process raft request' (duration: 332.900908ms)","trace[591289517] 'compare' (duration: 57.233918ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:27.308881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:26.917819Z","time spent":"391.00095ms","remote":"127.0.0.1:38584","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-06-02T23:47:27.308944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.505132ms","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-06-02T23:47:27.309022Z","caller":"traceutil/trace.go:171","msg":"trace[471063811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"408.619777ms","start":"2026-06-02T23:47:26.900384Z","end":"2026-06-02T23:47:27.309003Z","steps":["trace[471063811] 'agreement among raft nodes before linearized reading' (duration: 408.388868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:27.309094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:26.900366Z","time spent":"408.720721ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T23:47:27.618933Z","caller":"traceutil/trace.go:171","msg":"trace[225755168] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"579.885644ms","start":"2026-06-02T23:47:27.039007Z","end":"2026-06-02T23:47:27.618893Z","steps":["trace[225755168] 'process raft request' (duration: 490.733704ms)","trace[225755168] 'compare' (duration: 88.840458ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:47:27.619071Z","caller":"traceutil/trace.go:171","msg":"trace[1745263408] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"528.082305ms","start":"2026-06-02T23:47:27.090942Z","end":"2026-06-02T23:47:27.619024Z","steps":["trace[1745263408] 'process raft request' (duration: 527.892178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:27.619133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:27.03898Z","time spent":"580.03926ms","remote":"127.0.0.1:38584","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-06-02T23:47:27.61923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:27.090918Z","time spent":"528.244081ms","remote":"127.0.0.1:38562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:27.655127Z","caller":"traceutil/trace.go:171","msg":"trace[38776744] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"338.595455ms","start":"2026-06-02T23:47:27.316504Z","end":"2026-06-02T23:47:27.6551Z","steps":["trace[38776744] 'process raft request' (duration: 338.486781ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:27.655218Z","caller":"traceutil/trace.go:171","msg":"trace[2068203923] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:631; }","duration":"346.397296ms","start":"2026-06-02T23:47:27.308771Z","end":"2026-06-02T23:47:27.655168Z","steps":["trace[2068203923] 'read index received' (duration: 221.050189ms)","trace[2068203923] 'applied index is now lower than readState.Index' (duration: 125.338627ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:27.655314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:27.316482Z","time spent":"338.720889ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:47:27.655338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.257865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:47:27.655447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.739357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-flh2d\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-02T23:47:27.655483Z","caller":"traceutil/trace.go:171","msg":"trace[2013015318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"392.449523ms","start":"2026-06-02T23:47:27.262989Z","end":"2026-06-02T23:47:27.655439Z","steps":["trace[2013015318] 'agreement among raft nodes before linearized reading' (duration: 392.198364ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:27.655537Z","caller":"traceutil/trace.go:171","msg":"trace[940144240] range","detail":"{range_begin:/registry/pods/kube-system/cilium-flh2d; range_end:; response_count:1; response_revision:607; }","duration":"581.83851ms","start":"2026-06-02T23:47:27.07368Z","end":"2026-06-02T23:47:27.655518Z","steps":["trace[940144240] 'agreement among raft nodes before linearized reading' (duration: 581.686114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:27.655585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:27.07363Z","time spent":"581.946545ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-flh2d\" "} {"level":"warn","ts":"2026-06-02T23:47:27.655596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:27.262966Z","time spent":"392.61411ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T23:47:28.515508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103491425449504,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:47:29.016384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103491425449504,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:47:29.052553Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.381143413s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T23:47:29.053076Z","caller":"traceutil/trace.go:171","msg":"trace[725767061] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.381728635s","start":"2026-06-02T23:47:27.671323Z","end":"2026-06-02T23:47:29.053052Z","steps":["trace[725767061] 'process raft request' (duration: 1.381599731s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:29.053185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:27.671308Z","time spent":"1.381818738s","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T23:47:29.108869Z","caller":"traceutil/trace.go:171","msg":"trace[1405395797] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"1.094049716s","start":"2026-06-02T23:47:28.01479Z","end":"2026-06-02T23:47:29.108839Z","steps":["trace[1405395797] 'read index received' (duration: 1.038151813s)","trace[1405395797] 'applied index is now lower than readState.Index' (duration: 55.896653ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:47:29.108993Z","caller":"traceutil/trace.go:171","msg":"trace[528752405] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.436406281s","start":"2026-06-02T23:47:27.67254Z","end":"2026-06-02T23:47:29.108946Z","steps":["trace[528752405] 'process raft request' (duration: 1.436125461s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:29.109171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:27.672519Z","time spent":"1.436559097s","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:47:29.109732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094844796s","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-06-02T23:47:29.109803Z","caller":"traceutil/trace.go:171","msg":"trace[635531141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"1.095043204s","start":"2026-06-02T23:47:28.014735Z","end":"2026-06-02T23:47:29.109778Z","steps":["trace[635531141] 'agreement among raft nodes before linearized reading' (duration: 1.094259464s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:29.109909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:28.014713Z","time spent":"1.095178729s","remote":"127.0.0.1:38584","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-06-02T23:47:29.125963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.572688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:29.126029Z","caller":"traceutil/trace.go:171","msg":"trace[1624859043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"862.678672ms","start":"2026-06-02T23:47:28.263336Z","end":"2026-06-02T23:47:29.126014Z","steps":["trace[1624859043] 'agreement among raft nodes before linearized reading' (duration: 862.576928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:29.126058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:28.263315Z","time spent":"862.734404ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T23:47:46.555097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.992864ms","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-06-02T23:47:46.555225Z","caller":"traceutil/trace.go:171","msg":"trace[200911780] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:776; }","duration":"916.17626ms","start":"2026-06-02T23:47:45.639024Z","end":"2026-06-02T23:47:46.5552Z","steps":["trace[200911780] 'count revisions from in-memory index tree' (duration: 915.898931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:46.555392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.846372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-02T23:47:46.555468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.598161ms","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-06-02T23:47:46.555627Z","caller":"traceutil/trace.go:171","msg":"trace[847958950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"773.805387ms","start":"2026-06-02T23:47:45.781785Z","end":"2026-06-02T23:47:46.55559Z","steps":["trace[847958950] 'range keys from in-memory index tree' (duration: 773.238838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:46.555701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:45.781762Z","time spent":"773.897831ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T23:47:46.555535Z","caller":"traceutil/trace.go:171","msg":"trace[536755075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"759.029889ms","start":"2026-06-02T23:47:45.796475Z","end":"2026-06-02T23:47:46.555505Z","steps":["trace[536755075] 'range keys from in-memory index tree' (duration: 758.361907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:46.555284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:45.639005Z","time spent":"916.262692ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:47:46.555888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:45.796453Z","time spent":"759.40209ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T23:47:46.558584Z","caller":"traceutil/trace.go:171","msg":"trace[45432122] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"296.268751ms","start":"2026-06-02T23:47:46.262299Z","end":"2026-06-02T23:47:46.558568Z","steps":["trace[45432122] 'read index received' (duration: 296.080385ms)","trace[45432122] 'applied index is now lower than readState.Index' (duration: 187.796µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:47:46.558681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.373874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:47:46.558717Z","caller":"traceutil/trace.go:171","msg":"trace[480124541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"296.458887ms","start":"2026-06-02T23:47:46.262242Z","end":"2026-06-02T23:47:46.558701Z","steps":["trace[480124541] 'agreement among raft nodes before linearized reading' (duration: 296.404535ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:46.558691Z","caller":"traceutil/trace.go:171","msg":"trace[1289720950] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"368.248741ms","start":"2026-06-02T23:47:46.190397Z","end":"2026-06-02T23:47:46.558645Z","steps":["trace[1289720950] 'process raft request' (duration: 368.045085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:46.559052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:47:46.190368Z","time spent":"368.574262ms","remote":"127.0.0.1:38562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:47:58.767415Z","caller":"traceutil/trace.go:171","msg":"trace[699092271] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"149.680126ms","start":"2026-06-02T23:47:58.617684Z","end":"2026-06-02T23:47:58.767364Z","steps":["trace[699092271] 'process raft request' (duration: 148.45073ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:47:58.768161Z","caller":"traceutil/trace.go:171","msg":"trace[1874878920] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:960; }","duration":"147.071891ms","start":"2026-06-02T23:47:58.621053Z","end":"2026-06-02T23:47:58.768125Z","steps":["trace[1874878920] 'read index received' (duration: 145.009191ms)","trace[1874878920] 'applied index is now lower than readState.Index' (duration: 2.0617ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:47:58.768199Z","caller":"traceutil/trace.go:171","msg":"trace[652632767] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"148.909934ms","start":"2026-06-02T23:47:58.619255Z","end":"2026-06-02T23:47:58.768165Z","steps":["trace[652632767] 'process raft request' (duration: 148.759239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:47:58.768795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.722839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T23:47:58.768845Z","caller":"traceutil/trace.go:171","msg":"trace[563615782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"147.802422ms","start":"2026-06-02T23:47:58.621029Z","end":"2026-06-02T23:47:58.768831Z","steps":["trace[563615782] 'agreement among raft nodes before linearized reading' (duration: 147.200975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:21.429613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103491425450697,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T23:48:21.572519Z","caller":"traceutil/trace.go:171","msg":"trace[1026058830] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"643.877193ms","start":"2026-06-02T23:48:20.928616Z","end":"2026-06-02T23:48:21.572493Z","steps":["trace[1026058830] 'read index received' (duration: 643.424102ms)","trace[1026058830] 'applied index is now lower than readState.Index' (duration: 451.721µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:48:21.572819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.108838ms","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-06-02T23:48:21.572897Z","caller":"traceutil/trace.go:171","msg":"trace[1720640441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"148.278642ms","start":"2026-06-02T23:48:21.424603Z","end":"2026-06-02T23:48:21.572882Z","steps":["trace[1720640441] 'agreement among raft nodes before linearized reading' (duration: 148.097058ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:21.572823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.19367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-02T23:48:21.57292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.844261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:48:21.573052Z","caller":"traceutil/trace.go:171","msg":"trace[641158887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"644.448457ms","start":"2026-06-02T23:48:20.928581Z","end":"2026-06-02T23:48:21.573029Z","steps":["trace[641158887] 'agreement among raft nodes before linearized reading' (duration: 644.067637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:21.573143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:48:20.928565Z","time spent":"644.560859ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T23:48:21.573132Z","caller":"traceutil/trace.go:171","msg":"trace[1392873625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"311.098537ms","start":"2026-06-02T23:48:21.262006Z","end":"2026-06-02T23:48:21.573105Z","steps":["trace[1392873625] 'agreement among raft nodes before linearized reading' (duration: 310.80553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:21.573234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.752347ms","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-06-02T23:48:21.572789Z","caller":"traceutil/trace.go:171","msg":"trace[1192016093] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"672.234213ms","start":"2026-06-02T23:48:20.900345Z","end":"2026-06-02T23:48:21.572579Z","steps":["trace[1192016093] 'process raft request' (duration: 671.980137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:21.573506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:48:20.900329Z","time spent":"673.095294ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:48:21.573294Z","caller":"traceutil/trace.go:171","msg":"trace[1933266447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"423.841599ms","start":"2026-06-02T23:48:21.149435Z","end":"2026-06-02T23:48:21.573277Z","steps":["trace[1933266447] 'agreement among raft nodes before linearized reading' (duration: 423.207563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:21.573497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:48:21.261987Z","time spent":"311.491847ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T23:48:21.573726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:48:21.149419Z","time spent":"424.28525ms","remote":"127.0.0.1:38584","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-06-02T23:48:21.767577Z","caller":"traceutil/trace.go:171","msg":"trace[206022792] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"188.64201ms","start":"2026-06-02T23:48:21.578907Z","end":"2026-06-02T23:48:21.767549Z","steps":["trace[206022792] 'process raft request' (duration: 102.294918ms)","trace[206022792] 'compare' (duration: 85.927032ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:48:21.767581Z","caller":"traceutil/trace.go:171","msg":"trace[1525150429] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"187.77689ms","start":"2026-06-02T23:48:21.579778Z","end":"2026-06-02T23:48:21.767555Z","steps":["trace[1525150429] 'process raft request' (duration: 187.656187ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:48:21.76762Z","caller":"traceutil/trace.go:171","msg":"trace[1989164817] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1234; }","duration":"136.779275ms","start":"2026-06-02T23:48:21.630815Z","end":"2026-06-02T23:48:21.767595Z","steps":["trace[1989164817] 'read index received' (duration: 50.503864ms)","trace[1989164817] 'applied index is now lower than readState.Index' (duration: 86.274501ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:48:21.767712Z","caller":"traceutil/trace.go:171","msg":"trace[1979366393] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"187.869421ms","start":"2026-06-02T23:48:21.579836Z","end":"2026-06-02T23:48:21.767705Z","steps":["trace[1979366393] 'process raft request' (duration: 187.681227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:48:21.76809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.259586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:48:21.768125Z","caller":"traceutil/trace.go:171","msg":"trace[928467495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1196; }","duration":"137.346918ms","start":"2026-06-02T23:48:21.630769Z","end":"2026-06-02T23:48:21.768116Z","steps":["trace[928467495] 'agreement among raft nodes before linearized reading' (duration: 136.911498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:02.133765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.426221ms","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-06-02T23:49:02.133867Z","caller":"traceutil/trace.go:171","msg":"trace[16209411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1738; }","duration":"137.558573ms","start":"2026-06-02T23:49:01.996287Z","end":"2026-06-02T23:49:02.133845Z","steps":["trace[16209411] 'range keys from in-memory index tree' (duration: 137.288738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:02.134019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.147224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-m96tk\" ","response":"range_response_count:1 size:7304"} {"level":"info","ts":"2026-06-02T23:49:02.134076Z","caller":"traceutil/trace.go:171","msg":"trace[779826678] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-m96tk; range_end:; response_count:1; response_revision:1738; }","duration":"139.236335ms","start":"2026-06-02T23:49:01.994825Z","end":"2026-06-02T23:49:02.134061Z","steps":["trace[779826678] 'range keys from in-memory index tree' (duration: 138.998591ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:03.207109Z","caller":"traceutil/trace.go:171","msg":"trace[1987183381] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"132.893501ms","start":"2026-06-02T23:49:03.074186Z","end":"2026-06-02T23:49:03.20708Z","steps":["trace[1987183381] 'process raft request' (duration: 132.724798ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:03.375825Z","caller":"traceutil/trace.go:171","msg":"trace[824681583] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"109.901394ms","start":"2026-06-02T23:49:03.265876Z","end":"2026-06-02T23:49:03.375777Z","steps":["trace[824681583] 'process raft request' (duration: 98.790332ms)","trace[824681583] 'compare' (duration: 11.00542ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:49:11.972252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.013224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-02T23:49:11.97234Z","caller":"traceutil/trace.go:171","msg":"trace[1914810950] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:1869; }","duration":"134.158597ms","start":"2026-06-02T23:49:11.838159Z","end":"2026-06-02T23:49:11.972318Z","steps":["trace[1914810950] 'range keys from in-memory index tree' (duration: 133.79357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.361292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:11.973364Z","time spent":"387.923247ms","remote":"127.0.0.1:38334","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T23:49:12.372004Z","caller":"traceutil/trace.go:171","msg":"trace[1864374763] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"393.52634ms","start":"2026-06-02T23:49:11.978463Z","end":"2026-06-02T23:49:12.37199Z","steps":["trace[1864374763] 'read index received' (duration: 382.811553ms)","trace[1864374763] 'applied index is now lower than readState.Index' (duration: 10.714247ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:49:12.372087Z","caller":"traceutil/trace.go:171","msg":"trace[1249528188] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"395.502846ms","start":"2026-06-02T23:49:11.976562Z","end":"2026-06-02T23:49:12.372065Z","steps":["trace[1249528188] 'process raft request' (duration: 395.323403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.372211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:11.976537Z","time spent":"395.604877ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:49:12.372266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.781654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-02T23:49:12.372694Z","caller":"traceutil/trace.go:171","msg":"trace[1503777156] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"394.254152ms","start":"2026-06-02T23:49:11.978429Z","end":"2026-06-02T23:49:12.372684Z","steps":["trace[1503777156] 'agreement among raft nodes before linearized reading' (duration: 393.743933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.372745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:11.978418Z","time spent":"394.319034ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-02T23:49:12.431079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.424832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-02T23:49:12.431165Z","caller":"traceutil/trace.go:171","msg":"trace[1354930601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1872; }","duration":"252.519964ms","start":"2026-06-02T23:49:12.178624Z","end":"2026-06-02T23:49:12.431144Z","steps":["trace[1354930601] 'agreement among raft nodes before linearized reading' (duration: 252.28734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.431692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.529022ms","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-06-02T23:49:12.431774Z","caller":"traceutil/trace.go:171","msg":"trace[1169780074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1872; }","duration":"206.654074ms","start":"2026-06-02T23:49:12.225099Z","end":"2026-06-02T23:49:12.431753Z","steps":["trace[1169780074] 'agreement among raft nodes before linearized reading' (duration: 206.44016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.431882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.484478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:49:12.431938Z","caller":"traceutil/trace.go:171","msg":"trace[210690948] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1872; }","duration":"190.57411ms","start":"2026-06-02T23:49:12.241351Z","end":"2026-06-02T23:49:12.431925Z","steps":["trace[210690948] 'agreement among raft nodes before linearized reading' (duration: 190.466588ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.432024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.715808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:49:12.43209Z","caller":"traceutil/trace.go:171","msg":"trace[1593396632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1872; }","duration":"169.81011ms","start":"2026-06-02T23:49:12.262268Z","end":"2026-06-02T23:49:12.432078Z","steps":["trace[1593396632] 'agreement among raft nodes before linearized reading' (duration: 169.720968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.432182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.893807ms","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-06-02T23:49:12.432267Z","caller":"traceutil/trace.go:171","msg":"trace[754680311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1872; }","duration":"208.05493ms","start":"2026-06-02T23:49:12.224193Z","end":"2026-06-02T23:49:12.432248Z","steps":["trace[754680311] 'agreement among raft nodes before linearized reading' (duration: 207.772465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.432481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.7865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T23:49:12.432237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.926066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-02T23:49:12.43254Z","caller":"traceutil/trace.go:171","msg":"trace[619521014] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1872; }","duration":"198.869131ms","start":"2026-06-02T23:49:12.233661Z","end":"2026-06-02T23:49:12.43253Z","steps":["trace[619521014] 'agreement among raft nodes before linearized reading' (duration: 198.27369ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:12.432701Z","caller":"traceutil/trace.go:171","msg":"trace[769076055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1872; }","duration":"209.429115ms","start":"2026-06-02T23:49:12.223256Z","end":"2026-06-02T23:49:12.432685Z","steps":["trace[769076055] 'agreement among raft nodes before linearized reading' (duration: 208.500888ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:12.945657Z","caller":"traceutil/trace.go:171","msg":"trace[825879223] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"507.764039ms","start":"2026-06-02T23:49:12.437864Z","end":"2026-06-02T23:49:12.945628Z","steps":["trace[825879223] 'process raft request' (duration: 507.469494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.945915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:12.437854Z","time spent":"507.964343ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:49:12.94605Z","caller":"traceutil/trace.go:171","msg":"trace[336264740] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"507.941293ms","start":"2026-06-02T23:49:12.438074Z","end":"2026-06-02T23:49:12.946015Z","steps":["trace[336264740] 'process raft request' (duration: 507.496264ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:12.94609Z","caller":"traceutil/trace.go:171","msg":"trace[891819627] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1951; }","duration":"365.448013ms","start":"2026-06-02T23:49:12.58057Z","end":"2026-06-02T23:49:12.946018Z","steps":["trace[891819627] 'read index received' (duration: 364.78559ms)","trace[891819627] 'applied index is now lower than readState.Index' (duration: 661.213µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:49:12.9462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:12.438061Z","time spent":"508.060595ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:49:12.946176Z","caller":"traceutil/trace.go:171","msg":"trace[1507063392] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"507.453593ms","start":"2026-06-02T23:49:12.438666Z","end":"2026-06-02T23:49:12.94612Z","steps":["trace[1507063392] 'process raft request' (duration: 507.196909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.946382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:12.438629Z","time spent":"507.648137ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:49:12.946459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.849421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc3e1fdc-7b6a-4260-aa7a-f4938f777fce\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-02T23:49:12.946546Z","caller":"traceutil/trace.go:171","msg":"trace[2095217507] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cc3e1fdc-7b6a-4260-aa7a-f4938f777fce; range_end:; response_count:1; response_revision:1876; }","duration":"365.972763ms","start":"2026-06-02T23:49:12.580555Z","end":"2026-06-02T23:49:12.946528Z","steps":["trace[2095217507] 'agreement among raft nodes before linearized reading' (duration: 365.645177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:12.946592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:12.580473Z","time spent":"366.108635ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc3e1fdc-7b6a-4260-aa7a-f4938f777fce\" "} {"level":"info","ts":"2026-06-02T23:49:12.96401Z","caller":"traceutil/trace.go:171","msg":"trace[1840189223] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"103.881013ms","start":"2026-06-02T23:49:12.860108Z","end":"2026-06-02T23:49:12.963989Z","steps":["trace[1840189223] 'process raft request' (duration: 103.429755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.061695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.083704ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:49:25.061815Z","caller":"traceutil/trace.go:171","msg":"trace[318168341] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1983; }","duration":"137.218406ms","start":"2026-06-02T23:49:24.924579Z","end":"2026-06-02T23:49:25.061797Z","steps":["trace[318168341] 'range keys from in-memory index tree' (duration: 137.069734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.062984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.715121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:49:25.063091Z","caller":"traceutil/trace.go:171","msg":"trace[1754147817] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"255.531336ms","start":"2026-06-02T23:49:24.807541Z","end":"2026-06-02T23:49:25.063072Z","steps":["trace[1754147817] 'process raft request' (duration: 123.650432ms)","trace[1754147817] 'compare' (duration: 130.751234ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:49:25.669885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.151939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:49:25.67005Z","caller":"traceutil/trace.go:171","msg":"trace[1770717164] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"666.207639ms","start":"2026-06-02T23:49:25.00381Z","end":"2026-06-02T23:49:25.670018Z","steps":["trace[1770717164] 'process raft request' (duration: 225.830076ms)","trace[1770717164] 'compare' (duration: 440.019867ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:49:25.67011Z","caller":"traceutil/trace.go:171","msg":"trace[586878653] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"254.968423ms","start":"2026-06-02T23:49:25.415117Z","end":"2026-06-02T23:49:25.670085Z","steps":["trace[586878653] 'process raft request' (duration: 254.890312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.670203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:25.003765Z","time spent":"666.358801ms","remote":"127.0.0.1:38584","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-06-02T23:49:25.670378Z","caller":"traceutil/trace.go:171","msg":"trace[2060914059] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2065; }","duration":"664.910706ms","start":"2026-06-02T23:49:25.005457Z","end":"2026-06-02T23:49:25.670367Z","steps":["trace[2060914059] 'read index received' (duration: 224.187747ms)","trace[2060914059] 'applied index is now lower than readState.Index' (duration: 440.721829ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:49:25.670572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.1155ms","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-06-02T23:49:25.670612Z","caller":"traceutil/trace.go:171","msg":"trace[121196328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1986; }","duration":"665.18747ms","start":"2026-06-02T23:49:25.005415Z","end":"2026-06-02T23:49:25.670602Z","steps":["trace[121196328] 'agreement among raft nodes before linearized reading' (duration: 665.037168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.670644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:25.005394Z","time spent":"665.242972ms","remote":"127.0.0.1:38584","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-06-02T23:49:25.670902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.021013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-02T23:49:25.670955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.486817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:49:25.670966Z","caller":"traceutil/trace.go:171","msg":"trace[80613120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1986; }","duration":"659.120225ms","start":"2026-06-02T23:49:25.01183Z","end":"2026-06-02T23:49:25.67095Z","steps":["trace[80613120] 'agreement among raft nodes before linearized reading' (duration: 658.910531ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:25.67099Z","caller":"traceutil/trace.go:171","msg":"trace[406824974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1986; }","duration":"408.542848ms","start":"2026-06-02T23:49:25.262437Z","end":"2026-06-02T23:49:25.670979Z","steps":["trace[406824974] 'agreement among raft nodes before linearized reading' (duration: 408.486947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.671018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:25.262425Z","time spent":"408.58774ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T23:49:25.671015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:25.011812Z","time spent":"659.189235ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T23:49:25.6712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.568636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-02T23:49:25.67126Z","caller":"traceutil/trace.go:171","msg":"trace[1087203041] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1986; }","duration":"196.647297ms","start":"2026-06-02T23:49:25.4746Z","end":"2026-06-02T23:49:25.671247Z","steps":["trace[1087203041] 'agreement among raft nodes before linearized reading' (duration: 196.534145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.671313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.364929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-02T23:49:25.671371Z","caller":"traceutil/trace.go:171","msg":"trace[234858637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1986; }","duration":"659.437491ms","start":"2026-06-02T23:49:25.011919Z","end":"2026-06-02T23:49:25.671357Z","steps":["trace[234858637] 'agreement among raft nodes before linearized reading' (duration: 659.271448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.671509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:25.011912Z","time spent":"659.582493ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T23:49:25.67154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.574412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-02T23:49:25.671582Z","caller":"traceutil/trace.go:171","msg":"trace[994018864] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1986; }","duration":"217.661453ms","start":"2026-06-02T23:49:25.45391Z","end":"2026-06-02T23:49:25.671572Z","steps":["trace[994018864] 'agreement among raft nodes before linearized reading' (duration: 217.47695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.672044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.333457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:49:25.67158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.453583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11978"} {"level":"info","ts":"2026-06-02T23:49:25.672195Z","caller":"traceutil/trace.go:171","msg":"trace[814854009] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1986; }","duration":"457.181516ms","start":"2026-06-02T23:49:25.214998Z","end":"2026-06-02T23:49:25.672179Z","steps":["trace[814854009] 'agreement among raft nodes before linearized reading' (duration: 456.172648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.672261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:25.214971Z","time spent":"457.279797ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12001,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-02T23:49:25.672958Z","caller":"traceutil/trace.go:171","msg":"trace[1510949605] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1986; }","duration":"474.329625ms","start":"2026-06-02T23:49:25.19784Z","end":"2026-06-02T23:49:25.672169Z","steps":["trace[1510949605] 'agreement among raft nodes before linearized reading' (duration: 473.300257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:25.673099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:25.197788Z","time spent":"475.287782ms","remote":"127.0.0.1:38566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:49:52.071689Z","caller":"traceutil/trace.go:171","msg":"trace[854331738] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"164.991886ms","start":"2026-06-02T23:49:51.906671Z","end":"2026-06-02T23:49:52.071663Z","steps":["trace[854331738] 'process raft request' (duration: 81.447053ms)","trace[854331738] 'compare' (duration: 83.37398ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:49:52.071881Z","caller":"traceutil/trace.go:171","msg":"trace[795040085] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"164.952655ms","start":"2026-06-02T23:49:51.90688Z","end":"2026-06-02T23:49:52.071833Z","steps":["trace[795040085] 'process raft request' (duration: 164.746582ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:52.545198Z","caller":"traceutil/trace.go:171","msg":"trace[1353313394] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"265.990655ms","start":"2026-06-02T23:49:52.27918Z","end":"2026-06-02T23:49:52.545171Z","steps":["trace[1353313394] 'process raft request' (duration: 265.824073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:53.318764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.313834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-02T23:49:53.318811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.340078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-02T23:49:53.318905Z","caller":"traceutil/trace.go:171","msg":"trace[87880417] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2140; }","duration":"128.48707ms","start":"2026-06-02T23:49:53.190389Z","end":"2026-06-02T23:49:53.318876Z","steps":["trace[87880417] 'range keys from in-memory index tree' (duration: 128.199045ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:53.318836Z","caller":"traceutil/trace.go:171","msg":"trace[590927846] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"407.421096ms","start":"2026-06-02T23:49:52.911397Z","end":"2026-06-02T23:49:53.318818Z","steps":["trace[590927846] 'range keys from in-memory index tree' (duration: 407.126141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:53.319088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:52.911382Z","time spent":"407.692671ms","remote":"127.0.0.1:44712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-02T23:49:53.615078Z","caller":"traceutil/trace.go:171","msg":"trace[1799813513] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"292.234376ms","start":"2026-06-02T23:49:53.322814Z","end":"2026-06-02T23:49:53.615048Z","steps":["trace[1799813513] 'process raft request' (duration: 292.057503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:53.868959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.330593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:49:53.869056Z","caller":"traceutil/trace.go:171","msg":"trace[2042927421] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2141; }","duration":"109.505836ms","start":"2026-06-02T23:49:53.75953Z","end":"2026-06-02T23:49:53.869036Z","steps":["trace[2042927421] 'count revisions from in-memory index tree' (duration: 109.237761ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:54.039866Z","caller":"traceutil/trace.go:171","msg":"trace[166352181] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2230; }","duration":"134.691597ms","start":"2026-06-02T23:49:53.905151Z","end":"2026-06-02T23:49:54.039843Z","steps":["trace[166352181] 'read index received' (duration: 130.260256ms)","trace[166352181] 'applied index is now lower than readState.Index' (duration: 4.430361ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:49:54.039947Z","caller":"traceutil/trace.go:171","msg":"trace[1711126978] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"159.046028ms","start":"2026-06-02T23:49:53.880887Z","end":"2026-06-02T23:49:54.039933Z","steps":["trace[1711126978] 'process raft request' (duration: 154.567616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:54.040902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.60675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T23:49:54.040966Z","caller":"traceutil/trace.go:171","msg":"trace[174088577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2143; }","duration":"113.706251ms","start":"2026-06-02T23:49:53.927242Z","end":"2026-06-02T23:49:54.040948Z","steps":["trace[174088577] 'agreement among raft nodes before linearized reading' (duration: 113.553708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:54.040122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.90979ms","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-06-02T23:49:54.041167Z","caller":"traceutil/trace.go:171","msg":"trace[2119644299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"136.054399ms","start":"2026-06-02T23:49:53.905096Z","end":"2026-06-02T23:49:54.041151Z","steps":["trace[2119644299] 'agreement among raft nodes before linearized reading' (duration: 134.88297ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:54.37348Z","caller":"traceutil/trace.go:171","msg":"trace[78764617] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2231; }","duration":"297.261263ms","start":"2026-06-02T23:49:54.076195Z","end":"2026-06-02T23:49:54.373456Z","steps":["trace[78764617] 'read index received' (duration: 29.157606ms)","trace[78764617] 'applied index is now lower than readState.Index' (duration: 268.102567ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:49:54.373549Z","caller":"traceutil/trace.go:171","msg":"trace[1820092756] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"326.610983ms","start":"2026-06-02T23:49:54.0469Z","end":"2026-06-02T23:49:54.373511Z","steps":["trace[1820092756] 'process raft request' (duration: 326.42862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:54.373718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:49:54.046872Z","time spent":"326.766266ms","remote":"127.0.0.1:38584","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-06-02T23:49:54.37373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.06564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:49:54.373796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.528378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:49:54.373861Z","caller":"traceutil/trace.go:171","msg":"trace[371800353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2145; }","duration":"297.59572ms","start":"2026-06-02T23:49:54.076248Z","end":"2026-06-02T23:49:54.373844Z","steps":["trace[371800353] 'agreement among raft nodes before linearized reading' (duration: 297.385266ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:49:54.37381Z","caller":"traceutil/trace.go:171","msg":"trace[1618830663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2145; }","duration":"110.197872ms","start":"2026-06-02T23:49:54.263593Z","end":"2026-06-02T23:49:54.373791Z","steps":["trace[1618830663] 'agreement among raft nodes before linearized reading' (duration: 110.08007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:49:54.373741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.512508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-02T23:49:54.374159Z","caller":"traceutil/trace.go:171","msg":"trace[353573268] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2145; }","duration":"297.990965ms","start":"2026-06-02T23:49:54.076146Z","end":"2026-06-02T23:49:54.374137Z","steps":["trace[353573268] 'agreement among raft nodes before linearized reading' (duration: 297.465527ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:50:42.451565Z","caller":"traceutil/trace.go:171","msg":"trace[215726400] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"386.836278ms","start":"2026-06-02T23:50:42.064706Z","end":"2026-06-02T23:50:42.451542Z","steps":["trace[215726400] 'process raft request' (duration: 386.709917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:42.451736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:50:42.064681Z","time spent":"386.983971ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:50:42.452789Z","caller":"traceutil/trace.go:171","msg":"trace[2080704271] linearizableReadLoop","detail":"{readStateIndex:2609; appliedIndex:2607; }","duration":"190.538727ms","start":"2026-06-02T23:50:42.26222Z","end":"2026-06-02T23:50:42.452759Z","steps":["trace[2080704271] 'read index received' (duration: 189.179287ms)","trace[2080704271] 'applied index is now lower than readState.Index' (duration: 1.35809ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:50:42.452881Z","caller":"traceutil/trace.go:171","msg":"trace[557835396] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"240.039974ms","start":"2026-06-02T23:50:42.212826Z","end":"2026-06-02T23:50:42.452866Z","steps":["trace[557835396] 'process raft request' (duration: 239.731839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:42.453044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.778691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:50:42.453112Z","caller":"traceutil/trace.go:171","msg":"trace[1513827253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2510; }","duration":"190.905532ms","start":"2026-06-02T23:50:42.262182Z","end":"2026-06-02T23:50:42.453087Z","steps":["trace[1513827253] 'agreement among raft nodes before linearized reading' (duration: 190.77433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:50:42.453207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.490967ms","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-06-02T23:50:42.4533Z","caller":"traceutil/trace.go:171","msg":"trace[416708629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2510; }","duration":"126.615009ms","start":"2026-06-02T23:50:42.326665Z","end":"2026-06-02T23:50:42.45328Z","steps":["trace[416708629] 'agreement among raft nodes before linearized reading' (duration: 126.355375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.040893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.287196ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:51:15.041041Z","caller":"traceutil/trace.go:171","msg":"trace[289263384] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2685; }","duration":"116.445168ms","start":"2026-06-02T23:51:14.924556Z","end":"2026-06-02T23:51:15.041001Z","steps":["trace[289263384] 'range keys from in-memory index tree' (duration: 115.801889ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:15.041538Z","caller":"traceutil/trace.go:171","msg":"trace[1674618893] linearizableReadLoop","detail":"{readStateIndex:2792; appliedIndex:2790; }","duration":"443.165326ms","start":"2026-06-02T23:51:14.598349Z","end":"2026-06-02T23:51:15.041514Z","steps":["trace[1674618893] 'read index received' (duration: 60.713846ms)","trace[1674618893] 'applied index is now lower than readState.Index' (duration: 382.45046ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:51:15.041533Z","caller":"traceutil/trace.go:171","msg":"trace[1475437185] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"465.360668ms","start":"2026-06-02T23:51:14.576129Z","end":"2026-06-02T23:51:15.04149Z","steps":["trace[1475437185] 'process raft request' (duration: 404.58007ms)","trace[1475437185] 'compare' (duration: 60.557254ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:51:15.041765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:14.576063Z","time spent":"465.638532ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:51:15.041796Z","caller":"traceutil/trace.go:171","msg":"trace[1600196909] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"190.525084ms","start":"2026-06-02T23:51:14.851203Z","end":"2026-06-02T23:51:15.041728Z","steps":["trace[1600196909] 'process raft request' (duration: 190.27893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.042059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.305026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-06-02T23:51:15.042108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.756984ms","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-06-02T23:51:15.042211Z","caller":"traceutil/trace.go:171","msg":"trace[1822185944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2687; }","duration":"443.909597ms","start":"2026-06-02T23:51:14.598284Z","end":"2026-06-02T23:51:15.042193Z","steps":["trace[1822185944] 'agreement among raft nodes before linearized reading' (duration: 443.384099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.042257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:14.59826Z","time spent":"443.987689ms","remote":"127.0.0.1:38584","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-06-02T23:51:15.042333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.027005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-02T23:51:15.042444Z","caller":"traceutil/trace.go:171","msg":"trace[1675081855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2687; }","duration":"210.209028ms","start":"2026-06-02T23:51:14.83221Z","end":"2026-06-02T23:51:15.042419Z","steps":["trace[1675081855] 'agreement among raft nodes before linearized reading' (duration: 209.814142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.042447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.193258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T23:51:15.04414Z","caller":"traceutil/trace.go:171","msg":"trace[1590691421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2687; }","duration":"292.910393ms","start":"2026-06-02T23:51:14.751211Z","end":"2026-06-02T23:51:15.044121Z","steps":["trace[1590691421] 'agreement among raft nodes before linearized reading' (duration: 291.159557ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:15.042216Z","caller":"traceutil/trace.go:171","msg":"trace[859384328] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2687; }","duration":"398.429918ms","start":"2026-06-02T23:51:14.643682Z","end":"2026-06-02T23:51:15.042112Z","steps":["trace[859384328] 'agreement among raft nodes before linearized reading' (duration: 398.249205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:15.044301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:14.643661Z","time spent":"400.622901ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-02T23:51:15.042695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.729104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T23:51:15.044511Z","caller":"traceutil/trace.go:171","msg":"trace[1658549449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2687; }","duration":"240.597401ms","start":"2026-06-02T23:51:14.8039Z","end":"2026-06-02T23:51:15.044497Z","steps":["trace[1658549449] 'agreement among raft nodes before linearized reading' (duration: 238.348678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:16.121804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.244054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:51:16.122999Z","caller":"traceutil/trace.go:171","msg":"trace[316020453] linearizableReadLoop","detail":"{readStateIndex:2799; appliedIndex:2797; }","duration":"920.170968ms","start":"2026-06-02T23:51:15.202805Z","end":"2026-06-02T23:51:16.122976Z","steps":["trace[316020453] 'read index received' (duration: 249.619944ms)","trace[316020453] 'applied index is now lower than readState.Index' (duration: 670.549864ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:51:16.123107Z","caller":"traceutil/trace.go:171","msg":"trace[1424540944] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"973.672016ms","start":"2026-06-02T23:51:15.149407Z","end":"2026-06-02T23:51:16.123079Z","steps":["trace[1424540944] 'process raft request' (duration: 973.436933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:16.124154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:15.149384Z","time spent":"974.59167ms","remote":"127.0.0.1:38584","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-06-02T23:51:16.12466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.828329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b5689dedfb3070\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-02T23:51:16.123149Z","caller":"traceutil/trace.go:171","msg":"trace[1451982899] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"975.411562ms","start":"2026-06-02T23:51:15.147713Z","end":"2026-06-02T23:51:16.123125Z","steps":["trace[1451982899] 'process raft request' (duration: 304.697566ms)","trace[1451982899] 'compare' (duration: 668.806127ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:51:16.123246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.430752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:51:16.125108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.710923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-02T23:51:16.125744Z","caller":"traceutil/trace.go:171","msg":"trace[760415181] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2693; }","duration":"209.375603ms","start":"2026-06-02T23:51:15.916346Z","end":"2026-06-02T23:51:16.125721Z","steps":["trace[760415181] 'agreement among raft nodes before linearized reading' (duration: 208.646312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:16.125173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.312097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:51:16.12625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:15.147567Z","time spent":"978.592419ms","remote":"127.0.0.1:38584","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-06-02T23:51:16.126305Z","caller":"traceutil/trace.go:171","msg":"trace[1196603717] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2693; }","duration":"272.485134ms","start":"2026-06-02T23:51:15.853807Z","end":"2026-06-02T23:51:16.126293Z","steps":["trace[1196603717] 'agreement among raft nodes before linearized reading' (duration: 271.329157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:16.125225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.513723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:51:16.126804Z","caller":"traceutil/trace.go:171","msg":"trace[290970796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2693; }","duration":"865.117966ms","start":"2026-06-02T23:51:15.26167Z","end":"2026-06-02T23:51:16.126787Z","steps":["trace[290970796] 'agreement among raft nodes before linearized reading' (duration: 863.530643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:16.1252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.883961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T23:51:16.125274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.012109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:51:16.126105Z","caller":"traceutil/trace.go:171","msg":"trace[1639806880] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b5689dedfb3070; range_end:; response_count:1; response_revision:2693; }","duration":"918.2884ms","start":"2026-06-02T23:51:15.207798Z","end":"2026-06-02T23:51:16.126087Z","steps":["trace[1639806880] 'agreement among raft nodes before linearized reading' (duration: 916.717657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:16.127028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:15.207782Z","time spent":"919.232684ms","remote":"127.0.0.1:38384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b5689dedfb3070\" "} {"level":"info","ts":"2026-06-02T23:51:16.127225Z","caller":"traceutil/trace.go:171","msg":"trace[1962198954] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2693; }","duration":"777.028122ms","start":"2026-06-02T23:51:15.350185Z","end":"2026-06-02T23:51:16.127213Z","steps":["trace[1962198954] 'agreement among raft nodes before linearized reading' (duration: 774.965871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:16.127267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:15.350163Z","time spent":"777.091983ms","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":3,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:51:16.127396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:15.261647Z","time spent":"865.742786ms","remote":"127.0.0.1:38312","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-06-02T23:51:16.127561Z","caller":"traceutil/trace.go:171","msg":"trace[235310747] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2693; }","duration":"630.291673ms","start":"2026-06-02T23:51:15.497218Z","end":"2026-06-02T23:51:16.12751Z","steps":["trace[235310747] 'agreement among raft nodes before linearized reading' (duration: 628.03013ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:51:16.126169Z","caller":"traceutil/trace.go:171","msg":"trace[711358136] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2693; }","duration":"923.400006ms","start":"2026-06-02T23:51:15.202758Z","end":"2026-06-02T23:51:16.126158Z","steps":["trace[711358136] 'agreement among raft nodes before linearized reading' (duration: 920.436802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:51:16.127966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:15.202738Z","time spent":"925.191582ms","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-02T23:51:16.128218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:51:15.497195Z","time spent":"631.012344ms","remote":"127.0.0.1:38730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:53:23.6991Z","caller":"traceutil/trace.go:171","msg":"trace[319766137] transaction","detail":"{read_only:false; response_revision:3458; number_of_response:1; }","duration":"142.793499ms","start":"2026-06-02T23:53:23.556264Z","end":"2026-06-02T23:53:23.699057Z","steps":["trace[319766137] 'process raft request' (duration: 102.171924ms)","trace[319766137] 'compare' (duration: 40.338561ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:25.89829Z","caller":"traceutil/trace.go:171","msg":"trace[627486473] transaction","detail":"{read_only:false; response_revision:3467; number_of_response:1; }","duration":"305.684228ms","start":"2026-06-02T23:53:25.59257Z","end":"2026-06-02T23:53:25.898255Z","steps":["trace[627486473] 'process raft request' (duration: 305.477445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:25.89854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:53:25.592543Z","time spent":"305.833461ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:53:25.926224Z","caller":"traceutil/trace.go:171","msg":"trace[2112885812] linearizableReadLoop","detail":"{readStateIndex:3604; appliedIndex:3602; }","duration":"188.677316ms","start":"2026-06-02T23:53:25.737521Z","end":"2026-06-02T23:53:25.926199Z","steps":["trace[2112885812] 'read index received' (duration: 160.528958ms)","trace[2112885812] 'applied index is now lower than readState.Index' (duration: 28.147258ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:25.926253Z","caller":"traceutil/trace.go:171","msg":"trace[379985831] transaction","detail":"{read_only:false; response_revision:3468; number_of_response:1; }","duration":"221.55064ms","start":"2026-06-02T23:53:25.704683Z","end":"2026-06-02T23:53:25.926234Z","steps":["trace[379985831] 'process raft request' (duration: 221.387008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:25.926404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.881419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T23:53:25.926429Z","caller":"traceutil/trace.go:171","msg":"trace[200787512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3468; }","duration":"188.96841ms","start":"2026-06-02T23:53:25.737455Z","end":"2026-06-02T23:53:25.926423Z","steps":["trace[200787512] 'agreement among raft nodes before linearized reading' (duration: 188.830338ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:30.897828Z","caller":"traceutil/trace.go:171","msg":"trace[67953032] transaction","detail":"{read_only:false; response_revision:3494; number_of_response:1; }","duration":"117.987385ms","start":"2026-06-02T23:53:30.779812Z","end":"2026-06-02T23:53:30.8978Z","steps":["trace[67953032] 'process raft request' (duration: 108.193827ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:36.043018Z","caller":"traceutil/trace.go:171","msg":"trace[1263547450] transaction","detail":"{read_only:false; response_revision:3517; number_of_response:1; }","duration":"113.52534ms","start":"2026-06-02T23:53:35.929462Z","end":"2026-06-02T23:53:36.042987Z","steps":["trace[1263547450] 'process raft request' (duration: 113.338797ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:46.189287Z","caller":"traceutil/trace.go:171","msg":"trace[1609820169] transaction","detail":"{read_only:false; response_revision:3574; number_of_response:1; }","duration":"115.375913ms","start":"2026-06-02T23:53:46.073882Z","end":"2026-06-02T23:53:46.189258Z","steps":["trace[1609820169] 'process raft request' (duration: 115.1787ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:46.223173Z","caller":"traceutil/trace.go:171","msg":"trace[2086282362] linearizableReadLoop","detail":"{readStateIndex:3715; appliedIndex:3713; }","duration":"134.350769ms","start":"2026-06-02T23:53:46.088793Z","end":"2026-06-02T23:53:46.223144Z","steps":["trace[2086282362] 'read index received' (duration: 100.26842ms)","trace[2086282362] 'applied index is now lower than readState.Index' (duration: 34.081099ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:46.223273Z","caller":"traceutil/trace.go:171","msg":"trace[507253105] transaction","detail":"{read_only:false; response_revision:3575; number_of_response:1; }","duration":"148.400536ms","start":"2026-06-02T23:53:46.074837Z","end":"2026-06-02T23:53:46.223238Z","steps":["trace[507253105] 'process raft request' (duration: 148.162853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:46.223602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.801535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-06-02T23:53:46.223702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.966069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-02T23:53:46.223634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.637729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T23:53:46.223798Z","caller":"traceutil/trace.go:171","msg":"trace[501445722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3575; }","duration":"133.127122ms","start":"2026-06-02T23:53:46.090653Z","end":"2026-06-02T23:53:46.22378Z","steps":["trace[501445722] 'agreement among raft nodes before linearized reading' (duration: 132.822808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:53:46.223634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.168475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T23:53:46.223997Z","caller":"traceutil/trace.go:171","msg":"trace[144831394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3575; }","duration":"124.534931ms","start":"2026-06-02T23:53:46.099402Z","end":"2026-06-02T23:53:46.223937Z","steps":["trace[144831394] 'agreement among raft nodes before linearized reading' (duration: 124.101075ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:46.223761Z","caller":"traceutil/trace.go:171","msg":"trace[1000029950] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3575; }","duration":"134.969017ms","start":"2026-06-02T23:53:46.088773Z","end":"2026-06-02T23:53:46.223742Z","steps":["trace[1000029950] 'agreement among raft nodes before linearized reading' (duration: 134.657633ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:46.224547Z","caller":"traceutil/trace.go:171","msg":"trace[1262738003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3575; }","duration":"101.869792ms","start":"2026-06-02T23:53:46.121926Z","end":"2026-06-02T23:53:46.223796Z","steps":["trace[1262738003] 'agreement among raft nodes before linearized reading' (duration: 101.559828ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:46.335096Z","caller":"traceutil/trace.go:171","msg":"trace[582837042] transaction","detail":"{read_only:false; response_revision:3577; number_of_response:1; }","duration":"105.739306ms","start":"2026-06-02T23:53:46.229314Z","end":"2026-06-02T23:53:46.335053Z","steps":["trace[582837042] 'process raft request' (duration: 71.437424ms)","trace[582837042] 'compare' (duration: 33.985148ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:53:46.335372Z","caller":"traceutil/trace.go:171","msg":"trace[1664862377] transaction","detail":"{read_only:false; response_revision:3578; number_of_response:1; }","duration":"105.96064ms","start":"2026-06-02T23:53:46.229368Z","end":"2026-06-02T23:53:46.335329Z","steps":["trace[1664862377] 'process raft request' (duration: 105.618196ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:53:46.335957Z","caller":"traceutil/trace.go:171","msg":"trace[498212516] transaction","detail":"{read_only:false; response_revision:3579; number_of_response:1; }","duration":"106.214154ms","start":"2026-06-02T23:53:46.229713Z","end":"2026-06-02T23:53:46.335927Z","steps":["trace[498212516] 'process raft request' (duration: 105.488284ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:04.700992Z","caller":"traceutil/trace.go:171","msg":"trace[1285236605] transaction","detail":"{read_only:false; response_revision:3710; number_of_response:1; }","duration":"113.563001ms","start":"2026-06-02T23:54:04.587392Z","end":"2026-06-02T23:54:04.700955Z","steps":["trace[1285236605] 'process raft request' (duration: 112.915662ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:05.018548Z","caller":"traceutil/trace.go:171","msg":"trace[1271585120] transaction","detail":"{read_only:false; response_revision:3712; number_of_response:1; }","duration":"215.834165ms","start":"2026-06-02T23:54:04.802684Z","end":"2026-06-02T23:54:05.018518Z","steps":["trace[1271585120] 'process raft request' (duration: 213.421051ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:05.046444Z","caller":"traceutil/trace.go:171","msg":"trace[1669030715] transaction","detail":"{read_only:false; response_revision:3713; number_of_response:1; }","duration":"194.754558ms","start":"2026-06-02T23:54:04.851666Z","end":"2026-06-02T23:54:05.046421Z","steps":["trace[1669030715] 'process raft request' (duration: 194.531465ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:21.981976Z","caller":"traceutil/trace.go:171","msg":"trace[1092270560] transaction","detail":"{read_only:false; response_revision:3839; number_of_response:1; }","duration":"121.350395ms","start":"2026-06-02T23:54:21.860596Z","end":"2026-06-02T23:54:21.981947Z","steps":["trace[1092270560] 'process raft request' (duration: 117.787775ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:27.894928Z","caller":"traceutil/trace.go:171","msg":"trace[687927503] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3872; }","duration":"177.863352ms","start":"2026-06-02T23:54:27.717033Z","end":"2026-06-02T23:54:27.894896Z","steps":["trace[687927503] 'process raft request' (duration: 177.772141ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:34.922704Z","caller":"traceutil/trace.go:171","msg":"trace[215312357] transaction","detail":"{read_only:false; response_revision:3909; number_of_response:1; }","duration":"143.495871ms","start":"2026-06-02T23:54:34.779183Z","end":"2026-06-02T23:54:34.922679Z","steps":["trace[215312357] 'process raft request' (duration: 109.117462ms)","trace[215312357] 'compare' (duration: 34.275117ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:54:44.141277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.336886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-qrxx4\" ","response":"range_response_count:1 size:4356"} {"level":"info","ts":"2026-06-02T23:54:44.141482Z","caller":"traceutil/trace.go:171","msg":"trace[958565899] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-qrxx4; range_end:; response_count:1; response_revision:3980; }","duration":"110.57281ms","start":"2026-06-02T23:54:44.030883Z","end":"2026-06-02T23:54:44.141456Z","steps":["trace[958565899] 'range keys from in-memory index tree' (duration: 110.097882ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:50.806235Z","caller":"traceutil/trace.go:171","msg":"trace[1951193708] transaction","detail":"{read_only:false; response_revision:4350; number_of_response:1; }","duration":"223.963406ms","start":"2026-06-02T23:54:50.582242Z","end":"2026-06-02T23:54:50.806205Z","steps":["trace[1951193708] 'process raft request' (duration: 142.175528ms)","trace[1951193708] 'compare' (duration: 81.670166ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:50.842485Z","caller":"traceutil/trace.go:171","msg":"trace[995699467] transaction","detail":"{read_only:false; response_revision:4351; number_of_response:1; }","duration":"243.901453ms","start":"2026-06-02T23:54:50.598564Z","end":"2026-06-02T23:54:50.842466Z","steps":["trace[995699467] 'process raft request' (duration: 243.790162ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:50.842679Z","caller":"traceutil/trace.go:171","msg":"trace[1105918475] transaction","detail":"{read_only:false; response_revision:4352; number_of_response:1; }","duration":"231.599182ms","start":"2026-06-02T23:54:50.611069Z","end":"2026-06-02T23:54:50.842668Z","steps":["trace[1105918475] 'process raft request' (duration: 231.368579ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:50.842938Z","caller":"traceutil/trace.go:171","msg":"trace[857467910] linearizableReadLoop","detail":"{readStateIndex:4528; appliedIndex:4524; }","duration":"166.593407ms","start":"2026-06-02T23:54:50.676317Z","end":"2026-06-02T23:54:50.84291Z","steps":["trace[857467910] 'read index received' (duration: 48.19476ms)","trace[857467910] 'applied index is now lower than readState.Index' (duration: 118.397667ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:50.843047Z","caller":"traceutil/trace.go:171","msg":"trace[1797541520] transaction","detail":"{read_only:false; response_revision:4354; number_of_response:1; }","duration":"122.339332ms","start":"2026-06-02T23:54:50.720699Z","end":"2026-06-02T23:54:50.843038Z","steps":["trace[1797541520] 'process raft request' (duration: 122.1511ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:50.84303Z","caller":"traceutil/trace.go:171","msg":"trace[1622104167] transaction","detail":"{read_only:false; response_revision:4353; number_of_response:1; }","duration":"213.035544ms","start":"2026-06-02T23:54:50.62997Z","end":"2026-06-02T23:54:50.843006Z","steps":["trace[1622104167] 'process raft request' (duration: 212.648348ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:50.843149Z","caller":"traceutil/trace.go:171","msg":"trace[710769300] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4353; }","duration":"161.594088ms","start":"2026-06-02T23:54:50.681549Z","end":"2026-06-02T23:54:50.843143Z","steps":["trace[710769300] 'process raft request' (duration: 161.269963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:50.843444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.123395ms","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-06-02T23:54:50.843473Z","caller":"traceutil/trace.go:171","msg":"trace[1285161099] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4354; }","duration":"167.195726ms","start":"2026-06-02T23:54:50.676269Z","end":"2026-06-02T23:54:50.843464Z","steps":["trace[1285161099] 'agreement among raft nodes before linearized reading' (duration: 167.096295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:50.843727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.505569ms","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-06-02T23:54:50.843776Z","caller":"traceutil/trace.go:171","msg":"trace[747541061] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4354; }","duration":"113.61562ms","start":"2026-06-02T23:54:50.730149Z","end":"2026-06-02T23:54:50.843765Z","steps":["trace[747541061] 'agreement among raft nodes before linearized reading' (duration: 113.516619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:51.295057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.501848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T23:54:51.295113Z","caller":"traceutil/trace.go:171","msg":"trace[1391738377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4365; }","duration":"274.570509ms","start":"2026-06-02T23:54:51.020532Z","end":"2026-06-02T23:54:51.295103Z","steps":["trace[1391738377] 'range keys from in-memory index tree' (duration: 274.374336ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:51.295116Z","caller":"traceutil/trace.go:171","msg":"trace[857442457] transaction","detail":"{read_only:false; response_revision:4366; number_of_response:1; }","duration":"269.606539ms","start":"2026-06-02T23:54:51.025465Z","end":"2026-06-02T23:54:51.295072Z","steps":["trace[857442457] 'process raft request' (duration: 260.757466ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:51.297953Z","caller":"traceutil/trace.go:171","msg":"trace[222988385] linearizableReadLoop","detail":"{readStateIndex:4543; appliedIndex:4541; }","duration":"216.184027ms","start":"2026-06-02T23:54:51.081751Z","end":"2026-06-02T23:54:51.297935Z","steps":["trace[222988385] 'read index received' (duration: 204.487794ms)","trace[222988385] 'applied index is now lower than readState.Index' (duration: 11.695193ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:51.298039Z","caller":"traceutil/trace.go:171","msg":"trace[1996565139] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4366; }","duration":"218.530269ms","start":"2026-06-02T23:54:51.079498Z","end":"2026-06-02T23:54:51.298028Z","steps":["trace[1996565139] 'process raft request' (duration: 218.364857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:51.298362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.613083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-7tvg8\" ","response":"range_response_count:1 size:4331"} {"level":"info","ts":"2026-06-02T23:54:51.2984Z","caller":"traceutil/trace.go:171","msg":"trace[295325974] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-7tvg8; range_end:; response_count:1; response_revision:4366; }","duration":"216.680484ms","start":"2026-06-02T23:54:51.081706Z","end":"2026-06-02T23:54:51.298387Z","steps":["trace[295325974] 'agreement among raft nodes before linearized reading' (duration: 216.540542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:51.298654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.408166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:3224"} {"level":"info","ts":"2026-06-02T23:54:51.298686Z","caller":"traceutil/trace.go:171","msg":"trace[1297801456] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4366; }","duration":"174.441266ms","start":"2026-06-02T23:54:51.124235Z","end":"2026-06-02T23:54:51.298677Z","steps":["trace[1297801456] 'agreement among raft nodes before linearized reading' (duration: 174.354235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:51.298883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.135073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:3224"} {"level":"info","ts":"2026-06-02T23:54:51.298912Z","caller":"traceutil/trace.go:171","msg":"trace[1690248626] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4366; }","duration":"123.188843ms","start":"2026-06-02T23:54:51.175714Z","end":"2026-06-02T23:54:51.298903Z","steps":["trace[1690248626] 'agreement among raft nodes before linearized reading' (duration: 123.108422ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:52.846995Z","caller":"traceutil/trace.go:171","msg":"trace[2008544753] linearizableReadLoop","detail":"{readStateIndex:4629; appliedIndex:4628; }","duration":"117.479293ms","start":"2026-06-02T23:54:52.72949Z","end":"2026-06-02T23:54:52.846969Z","steps":["trace[2008544753] 'read index received' (duration: 116.752303ms)","trace[2008544753] 'applied index is now lower than readState.Index' (duration: 725.68µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:52.847072Z","caller":"traceutil/trace.go:171","msg":"trace[1481214458] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"131.045903ms","start":"2026-06-02T23:54:52.716004Z","end":"2026-06-02T23:54:52.84705Z","steps":["trace[1481214458] 'process raft request' (duration: 130.298512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:52.847228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.715057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:54:52.847262Z","caller":"traceutil/trace.go:171","msg":"trace[893048678] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:4451; }","duration":"117.793978ms","start":"2026-06-02T23:54:52.729458Z","end":"2026-06-02T23:54:52.847252Z","steps":["trace[893048678] 'agreement among raft nodes before linearized reading' (duration: 117.676717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:54.778945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.297782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa4acfa1-58ec-4906-9df1-95adfc943d33\" ","response":"range_response_count:1 size:4065"} {"level":"info","ts":"2026-06-02T23:54:54.779052Z","caller":"traceutil/trace.go:171","msg":"trace[691022633] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fa4acfa1-58ec-4906-9df1-95adfc943d33; range_end:; response_count:1; response_revision:4476; }","duration":"448.431294ms","start":"2026-06-02T23:54:54.330583Z","end":"2026-06-02T23:54:54.779014Z","steps":["trace[691022633] 'range keys from in-memory index tree' (duration: 448.14623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:54.779097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:54:54.330562Z","time spent":"448.522065ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4088,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fa4acfa1-58ec-4906-9df1-95adfc943d33\" "} {"level":"info","ts":"2026-06-02T23:54:54.962269Z","caller":"traceutil/trace.go:171","msg":"trace[2007320371] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"102.248122ms","start":"2026-06-02T23:54:54.859992Z","end":"2026-06-02T23:54:54.96224Z","steps":["trace[2007320371] 'process raft request' (duration: 102.08444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:55.146993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.745316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:54:55.147157Z","caller":"traceutil/trace.go:171","msg":"trace[727742172] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"177.718651ms","start":"2026-06-02T23:54:54.969427Z","end":"2026-06-02T23:54:55.147146Z","steps":["trace[727742172] 'process raft request' (duration: 177.65505ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:55.147158Z","caller":"traceutil/trace.go:171","msg":"trace[60589086] linearizableReadLoop","detail":"{readStateIndex:4656; appliedIndex:4654; }","duration":"262.330178ms","start":"2026-06-02T23:54:54.884808Z","end":"2026-06-02T23:54:55.147138Z","steps":["trace[60589086] 'read index received' (duration: 77.366636ms)","trace[60589086] 'applied index is now lower than readState.Index' (duration: 184.962241ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:54:55.147165Z","caller":"traceutil/trace.go:171","msg":"trace[731161710] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"286.324861ms","start":"2026-06-02T23:54:54.860798Z","end":"2026-06-02T23:54:55.147123Z","steps":["trace[731161710] 'process raft request' (duration: 135.387923ms)","trace[731161710] 'compare' (duration: 150.654734ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:54:55.147359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.53742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T23:54:55.147419Z","caller":"traceutil/trace.go:171","msg":"trace[980191092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4479; }","duration":"262.623491ms","start":"2026-06-02T23:54:54.884782Z","end":"2026-06-02T23:54:55.147405Z","steps":["trace[980191092] 'agreement among raft nodes before linearized reading' (duration: 262.450589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:55.147465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.771733ms","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":"warn","ts":"2026-06-02T23:54:55.147523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.03138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T23:54:55.147551Z","caller":"traceutil/trace.go:171","msg":"trace[1882317902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4479; }","duration":"133.09657ms","start":"2026-06-02T23:54:55.014447Z","end":"2026-06-02T23:54:55.147544Z","steps":["trace[1882317902] 'agreement among raft nodes before linearized reading' (duration: 133.023099ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:54:55.147554Z","caller":"traceutil/trace.go:171","msg":"trace[607010346] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4479; }","duration":"254.872404ms","start":"2026-06-02T23:54:54.892652Z","end":"2026-06-02T23:54:55.147525Z","steps":["trace[607010346] 'agreement among raft nodes before linearized reading' (duration: 254.677001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:54:55.147703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.125014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fef752dc-5e99-494f-896c-f9d886c2f52b\" ","response":"range_response_count:1 size:4066"} {"level":"info","ts":"2026-06-02T23:54:55.147753Z","caller":"traceutil/trace.go:171","msg":"trace[899237164] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fef752dc-5e99-494f-896c-f9d886c2f52b; range_end:; response_count:1; response_revision:4479; }","duration":"226.223796ms","start":"2026-06-02T23:54:54.921513Z","end":"2026-06-02T23:54:55.147737Z","steps":["trace[899237164] 'agreement among raft nodes before linearized reading' (duration: 226.009133ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:01.369304Z","caller":"traceutil/trace.go:171","msg":"trace[1144130931] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"181.429861ms","start":"2026-06-02T23:55:01.187855Z","end":"2026-06-02T23:55:01.369284Z","steps":["trace[1144130931] 'process raft request' (duration: 180.815682ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:01.699626Z","caller":"traceutil/trace.go:171","msg":"trace[45768823] linearizableReadLoop","detail":"{readStateIndex:4780; appliedIndex:4777; }","duration":"273.109925ms","start":"2026-06-02T23:55:01.42649Z","end":"2026-06-02T23:55:01.6996Z","steps":["trace[45768823] 'read index received' (duration: 177.215422ms)","trace[45768823] 'applied index is now lower than readState.Index' (duration: 95.893673ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:01.69991Z","caller":"traceutil/trace.go:171","msg":"trace[745347019] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"511.05378ms","start":"2026-06-02T23:55:01.188836Z","end":"2026-06-02T23:55:01.69989Z","steps":["trace[745347019] 'process raft request' (duration: 414.939824ms)","trace[745347019] 'compare' (duration: 95.602919ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:01.700038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.188816Z","time spent":"511.143141ms","remote":"127.0.0.1:38584","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-06-02T23:55:01.700389Z","caller":"traceutil/trace.go:171","msg":"trace[962953755] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"510.426991ms","start":"2026-06-02T23:55:01.189949Z","end":"2026-06-02T23:55:01.700376Z","steps":["trace[962953755] 'process raft request' (duration: 509.553189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:01.700464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.189937Z","time spent":"510.489612ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:01.700653Z","caller":"traceutil/trace.go:171","msg":"trace[2062549636] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"510.678705ms","start":"2026-06-02T23:55:01.189959Z","end":"2026-06-02T23:55:01.700638Z","steps":["trace[2062549636] 'process raft request' (duration: 509.60081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:01.700731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.189942Z","time spent":"510.752326ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:01.700995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.500464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T23:55:01.701044Z","caller":"traceutil/trace.go:171","msg":"trace[327574058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4599; }","duration":"274.582484ms","start":"2026-06-02T23:55:01.426451Z","end":"2026-06-02T23:55:01.701034Z","steps":["trace[327574058] 'agreement among raft nodes before linearized reading' (duration: 274.437132ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:01.989131Z","caller":"traceutil/trace.go:171","msg":"trace[2126609973] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"341.31959ms","start":"2026-06-02T23:55:01.647769Z","end":"2026-06-02T23:55:01.989089Z","steps":["trace[2126609973] 'process raft request' (duration: 243.908217ms)","trace[2126609973] 'compare' (duration: 97.066729ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:01.989272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.64775Z","time spent":"341.450043ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:01.989349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.961461ms","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-06-02T23:55:01.989385Z","caller":"traceutil/trace.go:171","msg":"trace[1670853894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4600; }","duration":"386.032742ms","start":"2026-06-02T23:55:01.603343Z","end":"2026-06-02T23:55:01.989375Z","steps":["trace[1670853894] 'agreement among raft nodes before linearized reading' (duration: 385.87144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:01.989408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.603316Z","time spent":"386.086883ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T23:55:01.989042Z","caller":"traceutil/trace.go:171","msg":"trace[1253696909] linearizableReadLoop","detail":"{readStateIndex:4781; appliedIndex:4780; }","duration":"289.274168ms","start":"2026-06-02T23:55:01.699714Z","end":"2026-06-02T23:55:01.988988Z","steps":["trace[1253696909] 'read index received' (duration: 191.981506ms)","trace[1253696909] 'applied index is now lower than readState.Index' (duration: 97.291822ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:01.990489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.443847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T23:55:01.990549Z","caller":"traceutil/trace.go:171","msg":"trace[306870503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4600; }","duration":"486.538858ms","start":"2026-06-02T23:55:01.504001Z","end":"2026-06-02T23:55:01.990539Z","steps":["trace[306870503] 'agreement among raft nodes before linearized reading' (duration: 486.383386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:01.990572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.503969Z","time spent":"486.597879ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T23:55:02.01508Z","caller":"traceutil/trace.go:171","msg":"trace[313951186] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"308.198991ms","start":"2026-06-02T23:55:01.706848Z","end":"2026-06-02T23:55:02.015047Z","steps":["trace[313951186] 'process raft request' (duration: 307.960717ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:02.015123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.628462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-02T23:55:02.015214Z","caller":"traceutil/trace.go:171","msg":"trace[1863672613] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:4601; }","duration":"155.754904ms","start":"2026-06-02T23:55:01.859435Z","end":"2026-06-02T23:55:02.01519Z","steps":["trace[1863672613] 'agreement among raft nodes before linearized reading' (duration: 155.50747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:02.015237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.706827Z","time spent":"308.330132ms","remote":"127.0.0.1:38584","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-06-02T23:55:02.506339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.521009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:55:02.50709Z","caller":"traceutil/trace.go:171","msg":"trace[961378071] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"514.079681ms","start":"2026-06-02T23:55:01.992987Z","end":"2026-06-02T23:55:02.507066Z","steps":["trace[961378071] 'process raft request' (duration: 193.731732ms)","trace[961378071] 'compare' (duration: 319.155833ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:02.507201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.99297Z","time spent":"514.175202ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:02.507282Z","caller":"traceutil/trace.go:171","msg":"trace[765436821] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"512.58492ms","start":"2026-06-02T23:55:01.994668Z","end":"2026-06-02T23:55:02.507253Z","steps":["trace[765436821] 'process raft request' (duration: 511.794739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:02.507361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:02.018667Z","time spent":"488.693168ms","remote":"127.0.0.1:38334","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-02T23:55:02.507429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:01.99466Z","time spent":"512.695651ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:02.507472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.134893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T23:55:02.507502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:02.186413Z","time spent":"321.08833ms","remote":"127.0.0.1:38384","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T23:55:02.507301Z","caller":"traceutil/trace.go:171","msg":"trace[1825032287] linearizableReadLoop","detail":"{readStateIndex:4786; appliedIndex:4782; }","duration":"246.93663ms","start":"2026-06-02T23:55:02.260336Z","end":"2026-06-02T23:55:02.507273Z","steps":["trace[1825032287] 'read index received' (duration: 227.182186ms)","trace[1825032287] 'applied index is now lower than readState.Index' (duration: 19.752174ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:02.508401Z","caller":"traceutil/trace.go:171","msg":"trace[736695048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4603; }","duration":"248.077686ms","start":"2026-06-02T23:55:02.260285Z","end":"2026-06-02T23:55:02.508363Z","steps":["trace[736695048] 'agreement among raft nodes before linearized reading' (duration: 247.060102ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:02.56587Z","caller":"traceutil/trace.go:171","msg":"trace[1114044413] transaction","detail":"{read_only:false; response_revision:4604; number_of_response:1; }","duration":"184.115118ms","start":"2026-06-02T23:55:02.381731Z","end":"2026-06-02T23:55:02.565846Z","steps":["trace[1114044413] 'process raft request' (duration: 183.909865ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:02.72309Z","caller":"traceutil/trace.go:171","msg":"trace[1447044453] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"213.352084ms","start":"2026-06-02T23:55:02.509713Z","end":"2026-06-02T23:55:02.723065Z","steps":["trace[1447044453] 'process raft request' (duration: 175.223424ms)","trace[1447044453] 'compare' (duration: 37.917837ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:02.723145Z","caller":"traceutil/trace.go:171","msg":"trace[1584576334] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"213.198831ms","start":"2026-06-02T23:55:02.509931Z","end":"2026-06-02T23:55:02.72313Z","steps":["trace[1584576334] 'process raft request' (duration: 212.997819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:05.260185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.739652ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:05.260386Z","caller":"traceutil/trace.go:171","msg":"trace[1776163009] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4678; }","duration":"335.958355ms","start":"2026-06-02T23:55:04.924407Z","end":"2026-06-02T23:55:05.260366Z","steps":["trace[1776163009] 'range keys from in-memory index tree' (duration: 335.718652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:05.2604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.924481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-02T23:55:05.260541Z","caller":"traceutil/trace.go:171","msg":"trace[1953501315] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4678; }","duration":"322.097553ms","start":"2026-06-02T23:55:04.938434Z","end":"2026-06-02T23:55:05.260532Z","steps":["trace[1953501315] 'range keys from in-memory index tree' (duration: 321.764469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:05.260568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:04.938413Z","time spent":"322.147294ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-02T23:55:05.26065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.882487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5a7d6b3-57e6-4cae-b4ad-63cdf98ac92d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-02T23:55:05.26075Z","caller":"traceutil/trace.go:171","msg":"trace[1051433883] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d5a7d6b3-57e6-4cae-b4ad-63cdf98ac92d; range_end:; response_count:1; response_revision:4678; }","duration":"202.991958ms","start":"2026-06-02T23:55:05.057739Z","end":"2026-06-02T23:55:05.260731Z","steps":["trace[1051433883] 'range keys from in-memory index tree' (duration: 202.652354ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:05.261081Z","caller":"traceutil/trace.go:171","msg":"trace[1927722909] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"143.437612ms","start":"2026-06-02T23:55:05.117631Z","end":"2026-06-02T23:55:05.261069Z","steps":["trace[1927722909] 'process raft request' (duration: 60.594711ms)","trace[1927722909] 'compare' (duration: 82.325444ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:08.035615Z","caller":"traceutil/trace.go:171","msg":"trace[948724669] linearizableReadLoop","detail":"{readStateIndex:4878; appliedIndex:4877; }","duration":"255.099471ms","start":"2026-06-02T23:55:07.780485Z","end":"2026-06-02T23:55:08.035585Z","steps":["trace[948724669] 'read index received' (duration: 254.97373ms)","trace[948724669] 'applied index is now lower than readState.Index' (duration: 124.101µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:08.035669Z","caller":"traceutil/trace.go:171","msg":"trace[1129467572] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"265.006469ms","start":"2026-06-02T23:55:07.770617Z","end":"2026-06-02T23:55:08.035623Z","steps":["trace[1129467572] 'process raft request' (duration: 264.712095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:08.0359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.384115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T23:55:08.035965Z","caller":"traceutil/trace.go:171","msg":"trace[1874606721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4693; }","duration":"255.504417ms","start":"2026-06-02T23:55:07.780445Z","end":"2026-06-02T23:55:08.035949Z","steps":["trace[1874606721] 'agreement among raft nodes before linearized reading' (duration: 255.293234ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:08.394667Z","caller":"traceutil/trace.go:171","msg":"trace[1272508478] linearizableReadLoop","detail":"{readStateIndex:4879; appliedIndex:4878; }","duration":"358.925703ms","start":"2026-06-02T23:55:08.035702Z","end":"2026-06-02T23:55:08.394628Z","steps":["trace[1272508478] 'read index received' (duration: 322.099661ms)","trace[1272508478] 'applied index is now lower than readState.Index' (duration: 36.824682ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:08.394911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.169097ms","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-06-02T23:55:08.394987Z","caller":"traceutil/trace.go:171","msg":"trace[1835668071] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4694; }","duration":"483.311849ms","start":"2026-06-02T23:55:07.911657Z","end":"2026-06-02T23:55:08.394969Z","steps":["trace[1835668071] 'agreement among raft nodes before linearized reading' (duration: 483.091966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:08.395027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:07.911636Z","time spent":"483.38501ms","remote":"127.0.0.1:38584","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-06-02T23:55:08.394847Z","caller":"traceutil/trace.go:171","msg":"trace[1676873416] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"387.099164ms","start":"2026-06-02T23:55:08.007687Z","end":"2026-06-02T23:55:08.394787Z","steps":["trace[1676873416] 'process raft request' (duration: 350.0951ms)","trace[1676873416] 'compare' (duration: 36.643179ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:08.397117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:08.007652Z","time spent":"387.495979ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:08.403949Z","caller":"traceutil/trace.go:171","msg":"trace[1972261796] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"362.934699ms","start":"2026-06-02T23:55:08.040993Z","end":"2026-06-02T23:55:08.403928Z","steps":["trace[1972261796] 'process raft request' (duration: 362.805997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:08.404085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:08.040963Z","time spent":"363.05715ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:08.404782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.211032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-02T23:55:08.404815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.458346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5a7d6b3-57e6-4cae-b4ad-63cdf98ac92d\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-06-02T23:55:08.404868Z","caller":"traceutil/trace.go:171","msg":"trace[716852490] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d5a7d6b3-57e6-4cae-b4ad-63cdf98ac92d; range_end:; response_count:1; response_revision:4695; }","duration":"126.546566ms","start":"2026-06-02T23:55:08.278307Z","end":"2026-06-02T23:55:08.404854Z","steps":["trace[716852490] 'agreement among raft nodes before linearized reading' (duration: 126.395684ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:08.404829Z","caller":"traceutil/trace.go:171","msg":"trace[816161706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4695; }","duration":"363.295293ms","start":"2026-06-02T23:55:08.041522Z","end":"2026-06-02T23:55:08.404817Z","steps":["trace[816161706] 'agreement among raft nodes before linearized reading' (duration: 363.163401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:08.405215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:08.041509Z","time spent":"363.691678ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T23:55:08.404872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.273081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:08.407032Z","caller":"traceutil/trace.go:171","msg":"trace[1243557610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4695; }","duration":"143.467241ms","start":"2026-06-02T23:55:08.263554Z","end":"2026-06-02T23:55:08.407021Z","steps":["trace[1243557610] 'agreement among raft nodes before linearized reading' (duration: 141.292501ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:12.568925Z","caller":"traceutil/trace.go:171","msg":"trace[472957619] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4950; }","duration":"147.08822ms","start":"2026-06-02T23:55:12.421811Z","end":"2026-06-02T23:55:12.568899Z","steps":["trace[472957619] 'read index received' (duration: 146.923048ms)","trace[472957619] 'applied index is now lower than readState.Index' (duration: 164.322µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:12.569057Z","caller":"traceutil/trace.go:171","msg":"trace[543231271] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"154.741026ms","start":"2026-06-02T23:55:12.414303Z","end":"2026-06-02T23:55:12.569044Z","steps":["trace[543231271] 'process raft request' (duration: 154.451402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:12.569335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.519876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T23:55:12.569372Z","caller":"traceutil/trace.go:171","msg":"trace[944396516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4765; }","duration":"147.654677ms","start":"2026-06-02T23:55:12.421708Z","end":"2026-06-02T23:55:12.569362Z","steps":["trace[944396516] 'agreement among raft nodes before linearized reading' (duration: 147.489765ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:16.746832Z","caller":"traceutil/trace.go:171","msg":"trace[1084897073] linearizableReadLoop","detail":"{readStateIndex:5011; appliedIndex:5010; }","duration":"160.02184ms","start":"2026-06-02T23:55:16.586786Z","end":"2026-06-02T23:55:16.746808Z","steps":["trace[1084897073] 'read index received' (duration: 159.834777ms)","trace[1084897073] 'applied index is now lower than readState.Index' (duration: 186.173µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:16.747084Z","caller":"traceutil/trace.go:171","msg":"trace[458636016] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"163.966094ms","start":"2026-06-02T23:55:16.583104Z","end":"2026-06-02T23:55:16.74707Z","steps":["trace[458636016] 'process raft request' (duration: 163.569939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:16.747382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.587587ms","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-06-02T23:55:16.747426Z","caller":"traceutil/trace.go:171","msg":"trace[1874100552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4823; }","duration":"160.685219ms","start":"2026-06-02T23:55:16.58673Z","end":"2026-06-02T23:55:16.747415Z","steps":["trace[1874100552] 'agreement among raft nodes before linearized reading' (duration: 160.546147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:16.747643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.260701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T23:55:16.747683Z","caller":"traceutil/trace.go:171","msg":"trace[2120181635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4823; }","duration":"157.319573ms","start":"2026-06-02T23:55:16.590352Z","end":"2026-06-02T23:55:16.747672Z","steps":["trace[2120181635] 'agreement among raft nodes before linearized reading' (duration: 157.231061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:16.747925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.820989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T23:55:16.747963Z","caller":"traceutil/trace.go:171","msg":"trace[1106503147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4823; }","duration":"152.882891ms","start":"2026-06-02T23:55:16.595072Z","end":"2026-06-02T23:55:16.747955Z","steps":["trace[1106503147] 'agreement among raft nodes before linearized reading' (duration: 152.795229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:16.748127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.0149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T23:55:16.748164Z","caller":"traceutil/trace.go:171","msg":"trace[1680831106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4823; }","duration":"155.07405ms","start":"2026-06-02T23:55:16.593082Z","end":"2026-06-02T23:55:16.748156Z","steps":["trace[1680831106] 'agreement among raft nodes before linearized reading' (duration: 154.988939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:20.589807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.62034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:55:20.589907Z","caller":"traceutil/trace.go:171","msg":"trace[1073553012] linearizableReadLoop","detail":"{readStateIndex:5041; appliedIndex:5040; }","duration":"357.159332ms","start":"2026-06-02T23:55:20.232732Z","end":"2026-06-02T23:55:20.589891Z","steps":["trace[1073553012] 'read index received' (duration: 20.801µs)","trace[1073553012] 'applied index is now lower than readState.Index' (duration: 357.137501ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:20.590051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.291414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:55:20.59014Z","caller":"traceutil/trace.go:171","msg":"trace[220871066] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4852; }","duration":"357.413505ms","start":"2026-06-02T23:55:20.232703Z","end":"2026-06-02T23:55:20.590116Z","steps":["trace[220871066] 'agreement among raft nodes before linearized reading' (duration: 357.270603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:20.590191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.232688Z","time spent":"357.492526ms","remote":"127.0.0.1:38692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-06-02T23:55:20.5902Z","caller":"traceutil/trace.go:171","msg":"trace[391312964] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"471.854131ms","start":"2026-06-02T23:55:20.118326Z","end":"2026-06-02T23:55:20.59018Z","steps":["trace[391312964] 'process raft request' (duration: 102.790075ms)","trace[391312964] 'compare' (duration: 368.047882ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:20.590275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.118306Z","time spent":"471.931823ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:20.590426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.247242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2101"} {"level":"info","ts":"2026-06-02T23:55:20.590538Z","caller":"traceutil/trace.go:171","msg":"trace[198571793] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4852; }","duration":"274.389104ms","start":"2026-06-02T23:55:20.316102Z","end":"2026-06-02T23:55:20.590491Z","steps":["trace[198571793] 'agreement among raft nodes before linearized reading' (duration: 274.135271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:20.590704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.242269ms","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-06-02T23:55:20.590816Z","caller":"traceutil/trace.go:171","msg":"trace[1528749221] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4852; }","duration":"193.31292ms","start":"2026-06-02T23:55:20.397426Z","end":"2026-06-02T23:55:20.590739Z","steps":["trace[1528749221] 'agreement among raft nodes before linearized reading' (duration: 193.149558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:20.590941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.087082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-06-02T23:55:20.591076Z","caller":"traceutil/trace.go:171","msg":"trace[227351444] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4852; }","duration":"241.248594ms","start":"2026-06-02T23:55:20.349815Z","end":"2026-06-02T23:55:20.591064Z","steps":["trace[227351444] 'agreement among raft nodes before linearized reading' (duration: 241.049022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.153447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103491425459330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T23:55:21.64144Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001665426s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T23:55:21.642085Z","caller":"traceutil/trace.go:171","msg":"trace[349076380] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"1.038178881s","start":"2026-06-02T23:55:20.603876Z","end":"2026-06-02T23:55:21.642055Z","steps":["trace[349076380] 'process raft request' (duration: 1.037942578s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.642284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.603852Z","time spent":"1.038344044s","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:21.662059Z","caller":"traceutil/trace.go:171","msg":"trace[1779250319] linearizableReadLoop","detail":"{readStateIndex:5043; appliedIndex:5041; }","duration":"1.008651122s","start":"2026-06-02T23:55:20.653378Z","end":"2026-06-02T23:55:21.662029Z","steps":["trace[1779250319] 'read index received' (duration: 988.556804ms)","trace[1779250319] 'applied index is now lower than readState.Index' (duration: 20.092748ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:21.66243Z","caller":"traceutil/trace.go:171","msg":"trace[567805936] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"903.005287ms","start":"2026-06-02T23:55:20.759408Z","end":"2026-06-02T23:55:21.662413Z","steps":["trace[567805936] 'process raft request' (duration: 902.338518ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:21.662545Z","caller":"traceutil/trace.go:171","msg":"trace[1371876828] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"351.506862ms","start":"2026-06-02T23:55:21.310971Z","end":"2026-06-02T23:55:21.662478Z","steps":["trace[1371876828] 'process raft request' (duration: 351.004175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.662647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.759381Z","time spent":"903.154879ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:21.663023Z","caller":"traceutil/trace.go:171","msg":"trace[1265654011] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"352.437115ms","start":"2026-06-02T23:55:21.310372Z","end":"2026-06-02T23:55:21.66281Z","steps":["trace[1265654011] 'process raft request' (duration: 351.549353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.663106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.310342Z","time spent":"352.72939ms","remote":"127.0.0.1:38584","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-06-02T23:55:21.662761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.31094Z","time spent":"351.702965ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:21.662889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009504194s","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-06-02T23:55:21.66336Z","caller":"traceutil/trace.go:171","msg":"trace[912592915] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4856; }","duration":"1.010008741s","start":"2026-06-02T23:55:20.653338Z","end":"2026-06-02T23:55:21.663346Z","steps":["trace[912592915] 'agreement among raft nodes before linearized reading' (duration: 1.009495944s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.663415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.653322Z","time spent":"1.010072972s","remote":"127.0.0.1:38772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-02T23:55:21.944195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.408957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:55:21.944545Z","caller":"traceutil/trace.go:171","msg":"trace[1985057177] linearizableReadLoop","detail":"{readStateIndex:5049; appliedIndex:5045; }","duration":"282.372444ms","start":"2026-06-02T23:55:21.662149Z","end":"2026-06-02T23:55:21.944521Z","steps":["trace[1985057177] 'read index received' (duration: 89.45856ms)","trace[1985057177] 'applied index is now lower than readState.Index' (duration: 192.912784ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:21.944646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124942844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:21.944682Z","caller":"traceutil/trace.go:171","msg":"trace[636112894] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:4860; }","duration":"1.124984064s","start":"2026-06-02T23:55:20.819686Z","end":"2026-06-02T23:55:21.94467Z","steps":["trace[636112894] 'agreement among raft nodes before linearized reading' (duration: 1.124892073s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.944708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.819639Z","time spent":"1.125061346s","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-06-02T23:55:21.94486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173530308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-02T23:55:21.944887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.749554ms","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":"warn","ts":"2026-06-02T23:55:21.944848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.304364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-02T23:55:21.944904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167710597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-02T23:55:21.944933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.036795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:21.944919Z","caller":"traceutil/trace.go:171","msg":"trace[1408041503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4860; }","duration":"1.173620609s","start":"2026-06-02T23:55:20.77128Z","end":"2026-06-02T23:55:21.944901Z","steps":["trace[1408041503] 'agreement among raft nodes before linearized reading' (duration: 1.173452216s)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:21.944952Z","caller":"traceutil/trace.go:171","msg":"trace[1485334727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4860; }","duration":"448.462407ms","start":"2026-06-02T23:55:21.496462Z","end":"2026-06-02T23:55:21.944925Z","steps":["trace[1485334727] 'agreement among raft nodes before linearized reading' (duration: 448.092442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.944969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.771267Z","time spent":"1.1736887s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T23:55:21.944998Z","caller":"traceutil/trace.go:171","msg":"trace[1596646270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4860; }","duration":"682.112226ms","start":"2026-06-02T23:55:21.262833Z","end":"2026-06-02T23:55:21.944945Z","steps":["trace[1596646270] 'agreement among raft nodes before linearized reading' (duration: 682.086856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.945013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.496441Z","time spent":"448.563008ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T23:55:21.945048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.262802Z","time spent":"682.233537ms","remote":"127.0.0.1:38312","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-06-02T23:55:21.945138Z","caller":"traceutil/trace.go:171","msg":"trace[1988918906] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"293.58617ms","start":"2026-06-02T23:55:21.651544Z","end":"2026-06-02T23:55:21.94513Z","steps":["trace[1988918906] 'process raft request' (duration: 292.849739ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:21.945174Z","caller":"traceutil/trace.go:171","msg":"trace[1113028858] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"293.563119ms","start":"2026-06-02T23:55:21.651595Z","end":"2026-06-02T23:55:21.945158Z","steps":["trace[1113028858] 'process raft request' (duration: 292.866659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.945201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.788321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:21.945246Z","caller":"traceutil/trace.go:171","msg":"trace[2132267814] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"294.100007ms","start":"2026-06-02T23:55:21.651138Z","end":"2026-06-02T23:55:21.945238Z","steps":["trace[2132267814] 'process raft request' (duration: 100.548974ms)","trace[2132267814] 'compare' (duration: 192.266685ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:21.945249Z","caller":"traceutil/trace.go:171","msg":"trace[1149016315] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4860; }","duration":"277.960183ms","start":"2026-06-02T23:55:21.667275Z","end":"2026-06-02T23:55:21.945235Z","steps":["trace[1149016315] 'agreement among raft nodes before linearized reading' (duration: 277.886372ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:21.945323Z","caller":"traceutil/trace.go:171","msg":"trace[1627321964] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"294.080116ms","start":"2026-06-02T23:55:21.651237Z","end":"2026-06-02T23:55:21.945317Z","steps":["trace[1627321964] 'process raft request' (duration: 293.079062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.945322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1686648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T23:55:21.944908Z","caller":"traceutil/trace.go:171","msg":"trace[1157645795] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4860; }","duration":"537.814096ms","start":"2026-06-02T23:55:21.407088Z","end":"2026-06-02T23:55:21.944902Z","steps":["trace[1157645795] 'agreement among raft nodes before linearized reading' (duration: 537.745935ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:21.945372Z","caller":"traceutil/trace.go:171","msg":"trace[146798551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4860; }","duration":"1.168743152s","start":"2026-06-02T23:55:20.776615Z","end":"2026-06-02T23:55:21.945358Z","steps":["trace[146798551] 'agreement among raft nodes before linearized reading' (duration: 1.16860883s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.945416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.776595Z","time spent":"1.168809553s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T23:55:21.944961Z","caller":"traceutil/trace.go:171","msg":"trace[976938800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4860; }","duration":"1.167811608s","start":"2026-06-02T23:55:20.777133Z","end":"2026-06-02T23:55:21.944945Z","steps":["trace[976938800] 'agreement among raft nodes before linearized reading' (duration: 1.167522054s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.945458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.777108Z","time spent":"1.168342056s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T23:55:21.945413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.407057Z","time spent":"538.342573ms","remote":"127.0.0.1:38458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-02T23:55:21.945795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169750466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T23:55:21.945851Z","caller":"traceutil/trace.go:171","msg":"trace[145356512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4860; }","duration":"1.169844967s","start":"2026-06-02T23:55:20.775991Z","end":"2026-06-02T23:55:21.945836Z","steps":["trace[145356512] 'agreement among raft nodes before linearized reading' (duration: 1.169696335s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:21.945895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:20.775972Z","time spent":"1.169911598s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T23:55:21.946356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.213691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T23:55:21.946403Z","caller":"traceutil/trace.go:171","msg":"trace[1772881047] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4860; }","duration":"165.306871ms","start":"2026-06-02T23:55:21.781083Z","end":"2026-06-02T23:55:21.94639Z","steps":["trace[1772881047] 'agreement among raft nodes before linearized reading' (duration: 165.106469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.605969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.279647ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-06-02T23:55:22.606091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.948065Z","time spent":"658.016391ms","remote":"127.0.0.1:38334","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T23:55:22.606359Z","caller":"traceutil/trace.go:171","msg":"trace[627966726] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"647.646817ms","start":"2026-06-02T23:55:21.958689Z","end":"2026-06-02T23:55:22.606336Z","steps":["trace[627966726] 'process raft request' (duration: 647.612377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.606476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.95868Z","time spent":"647.725698ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:22.606566Z","caller":"traceutil/trace.go:171","msg":"trace[76958921] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"649.29514ms","start":"2026-06-02T23:55:21.957238Z","end":"2026-06-02T23:55:22.606533Z","steps":["trace[76958921] 'process raft request' (duration: 648.834464ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:22.606583Z","caller":"traceutil/trace.go:171","msg":"trace[1774709184] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"649.199808ms","start":"2026-06-02T23:55:21.957358Z","end":"2026-06-02T23:55:22.606558Z","steps":["trace[1774709184] 'process raft request' (duration: 648.875534ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:22.606613Z","caller":"traceutil/trace.go:171","msg":"trace[885779005] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5049; }","duration":"649.449741ms","start":"2026-06-02T23:55:21.957116Z","end":"2026-06-02T23:55:22.606566Z","steps":["trace[885779005] 'read index received' (duration: 497.576026ms)","trace[885779005] 'applied index is now lower than readState.Index' (duration: 151.868745ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:22.606719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.957352Z","time spent":"649.287339ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:22.606685Z","caller":"traceutil/trace.go:171","msg":"trace[2130415000] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"649.290329ms","start":"2026-06-02T23:55:21.95735Z","end":"2026-06-02T23:55:22.606641Z","steps":["trace[2130415000] 'process raft request' (duration: 648.911974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.606737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.957217Z","time spent":"649.400481ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:22.606824Z","caller":"traceutil/trace.go:171","msg":"trace[94028083] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"649.357731ms","start":"2026-06-02T23:55:21.957321Z","end":"2026-06-02T23:55:22.606678Z","steps":["trace[94028083] 'process raft request' (duration: 648.874864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.606906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.752834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:22.606932Z","caller":"traceutil/trace.go:171","msg":"trace[1957908685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4865; }","duration":"343.839965ms","start":"2026-06-02T23:55:22.263084Z","end":"2026-06-02T23:55:22.606924Z","steps":["trace[1957908685] 'agreement among raft nodes before linearized reading' (duration: 343.801285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.606951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:22.263064Z","time spent":"343.882717ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T23:55:22.606947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.957341Z","time spent":"649.435911ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T23:55:22.606989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.643805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d5a7d6b3-57e6-4cae-b4ad-63cdf98ac92d\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-02T23:55:22.607034Z","caller":"traceutil/trace.go:171","msg":"trace[454726454] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d5a7d6b3-57e6-4cae-b4ad-63cdf98ac92d; range_end:; response_count:1; response_revision:4865; }","duration":"649.726376ms","start":"2026-06-02T23:55:21.957291Z","end":"2026-06-02T23:55:22.607018Z","steps":["trace[454726454] 'agreement among raft nodes before linearized reading' (duration: 649.483282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.607075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.957285Z","time spent":"649.782386ms","remote":"127.0.0.1:38454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-d5a7d6b3-57e6-4cae-b4ad-63cdf98ac92d\" "} {"level":"warn","ts":"2026-06-02T23:55:22.607164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.957316Z","time spent":"649.588064ms","remote":"127.0.0.1:38584","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-06-02T23:55:22.606845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.720125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-02T23:55:22.607303Z","caller":"traceutil/trace.go:171","msg":"trace[842386302] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4865; }","duration":"650.213442ms","start":"2026-06-02T23:55:21.957073Z","end":"2026-06-02T23:55:22.607286Z","steps":["trace[842386302] 'agreement among raft nodes before linearized reading' (duration: 649.653665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:22.607356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:21.957054Z","time spent":"650.288263ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-02T23:55:23.81982Z","caller":"traceutil/trace.go:171","msg":"trace[520994555] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"151.918575ms","start":"2026-06-02T23:55:23.667872Z","end":"2026-06-02T23:55:23.819791Z","steps":["trace[520994555] 'process raft request' (duration: 121.350441ms)","trace[520994555] 'compare' (duration: 30.31279ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:24.129142Z","caller":"traceutil/trace.go:171","msg":"trace[1583147563] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"460.875527ms","start":"2026-06-02T23:55:23.668237Z","end":"2026-06-02T23:55:24.129112Z","steps":["trace[1583147563] 'process raft request' (duration: 460.417471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.129393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:23.668208Z","time spent":"461.0811ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:24.129691Z","caller":"traceutil/trace.go:171","msg":"trace[1138462533] linearizableReadLoop","detail":"{readStateIndex:5062; appliedIndex:5059; }","duration":"356.645633ms","start":"2026-06-02T23:55:23.773016Z","end":"2026-06-02T23:55:24.129662Z","steps":["trace[1138462533] 'read index received' (duration: 16.217355ms)","trace[1138462533] 'applied index is now lower than readState.Index' (duration: 340.427158ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:24.129898Z","caller":"traceutil/trace.go:171","msg":"trace[1960455074] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"459.185414ms","start":"2026-06-02T23:55:23.670701Z","end":"2026-06-02T23:55:24.129886Z","steps":["trace[1960455074] 'process raft request' (duration: 458.289011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.130006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:23.670646Z","time spent":"459.286065ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:24.130189Z","caller":"traceutil/trace.go:171","msg":"trace[964589054] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"354.730567ms","start":"2026-06-02T23:55:23.77544Z","end":"2026-06-02T23:55:24.13017Z","steps":["trace[964589054] 'process raft request' (duration: 354.178469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.130223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.610266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-06-02T23:55:24.130295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:23.775424Z","time spent":"354.827238ms","remote":"127.0.0.1:38584","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-06-02T23:55:24.130321Z","caller":"traceutil/trace.go:171","msg":"trace[2093665677] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4873; }","duration":"256.699438ms","start":"2026-06-02T23:55:23.873565Z","end":"2026-06-02T23:55:24.130264Z","steps":["trace[2093665677] 'agreement among raft nodes before linearized reading' (duration: 256.511255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.130229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.874915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-shvz2.18b568d6f7032e48\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-06-02T23:55:24.130376Z","caller":"traceutil/trace.go:171","msg":"trace[1455086857] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-shvz2.18b568d6f7032e48; range_end:; response_count:1; response_revision:4873; }","duration":"178.059658ms","start":"2026-06-02T23:55:23.952303Z","end":"2026-06-02T23:55:24.130363Z","steps":["trace[1455086857] 'agreement among raft nodes before linearized reading' (duration: 177.820985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.130964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.92499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-06-02T23:55:24.131046Z","caller":"traceutil/trace.go:171","msg":"trace[676589048] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4873; }","duration":"358.053812ms","start":"2026-06-02T23:55:23.772973Z","end":"2026-06-02T23:55:24.131026Z","steps":["trace[676589048] 'agreement among raft nodes before linearized reading' (duration: 357.062419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:24.131086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:23.772951Z","time spent":"358.126963ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-06-02T23:55:40.97523Z","caller":"traceutil/trace.go:171","msg":"trace[220636929] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5199; }","duration":"225.712124ms","start":"2026-06-02T23:55:40.749466Z","end":"2026-06-02T23:55:40.975179Z","steps":["trace[220636929] 'read index received' (duration: 225.540902ms)","trace[220636929] 'applied index is now lower than readState.Index' (duration: 168.582µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:55:40.975321Z","caller":"traceutil/trace.go:171","msg":"trace[1941782990] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"263.07178ms","start":"2026-06-02T23:55:40.712239Z","end":"2026-06-02T23:55:40.975311Z","steps":["trace[1941782990] 'process raft request' (duration: 262.824336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:40.975572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.991074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-06-02T23:55:40.975627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.15011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T23:55:40.975642Z","caller":"traceutil/trace.go:171","msg":"trace[136974804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5007; }","duration":"220.097745ms","start":"2026-06-02T23:55:40.755529Z","end":"2026-06-02T23:55:40.975626Z","steps":["trace[136974804] 'agreement among raft nodes before linearized reading' (duration: 219.929943ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:40.975678Z","caller":"traceutil/trace.go:171","msg":"trace[1172723250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5007; }","duration":"226.249021ms","start":"2026-06-02T23:55:40.749418Z","end":"2026-06-02T23:55:40.975667Z","steps":["trace[1172723250] 'agreement among raft nodes before linearized reading' (duration: 226.043478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:40.975694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.001524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T23:55:40.975914Z","caller":"traceutil/trace.go:171","msg":"trace[1966810696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5007; }","duration":"220.257838ms","start":"2026-06-02T23:55:40.755639Z","end":"2026-06-02T23:55:40.975897Z","steps":["trace[1966810696] 'agreement among raft nodes before linearized reading' (duration: 219.989664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:40.975704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.542229ms","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-06-02T23:55:40.976002Z","caller":"traceutil/trace.go:171","msg":"trace[477455667] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5007; }","duration":"193.861743ms","start":"2026-06-02T23:55:40.782093Z","end":"2026-06-02T23:55:40.975955Z","steps":["trace[477455667] 'agreement among raft nodes before linearized reading' (duration: 193.438237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:40.975777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.828472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T23:55:40.976122Z","caller":"traceutil/trace.go:171","msg":"trace[1098801765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5007; }","duration":"220.214987ms","start":"2026-06-02T23:55:40.755895Z","end":"2026-06-02T23:55:40.97611Z","steps":["trace[1098801765] 'agreement among raft nodes before linearized reading' (duration: 219.7102ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:41.616417Z","caller":"traceutil/trace.go:171","msg":"trace[85474257] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"634.975005ms","start":"2026-06-02T23:55:40.981417Z","end":"2026-06-02T23:55:41.616392Z","steps":["trace[85474257] 'process raft request' (duration: 634.931324ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:41.6165Z","caller":"traceutil/trace.go:171","msg":"trace[2013573613] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"636.258152ms","start":"2026-06-02T23:55:40.980212Z","end":"2026-06-02T23:55:41.616471Z","steps":["trace[2013573613] 'process raft request' (duration: 636.10307ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:55:41.616538Z","caller":"traceutil/trace.go:171","msg":"trace[761435504] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"636.85951ms","start":"2026-06-02T23:55:40.979655Z","end":"2026-06-02T23:55:41.616515Z","steps":["trace[761435504] 'process raft request' (duration: 579.476156ms)","trace[761435504] 'compare' (duration: 57.027389ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:41.616562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:40.981399Z","time spent":"635.086696ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:41.616467Z","caller":"traceutil/trace.go:171","msg":"trace[1373259163] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"636.630757ms","start":"2026-06-02T23:55:40.979804Z","end":"2026-06-02T23:55:41.616435Z","steps":["trace[1373259163] 'process raft request' (duration: 636.472195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:41.616675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:40.979647Z","time spent":"636.951821ms","remote":"127.0.0.1:38584","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-06-02T23:55:41.616785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:40.979797Z","time spent":"636.895541ms","remote":"127.0.0.1:38584","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-06-02T23:55:41.616941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:40.980194Z","time spent":"636.382454ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:41.639577Z","caller":"traceutil/trace.go:171","msg":"trace[37822541] linearizableReadLoop","detail":"{readStateIndex:5206; appliedIndex:5201; }","duration":"379.217657ms","start":"2026-06-02T23:55:41.260343Z","end":"2026-06-02T23:55:41.63956Z","steps":["trace[37822541] 'read index received' (duration: 298.801064ms)","trace[37822541] 'applied index is now lower than readState.Index' (duration: 80.415513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:41.639664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.309778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T23:55:41.6397Z","caller":"traceutil/trace.go:171","msg":"trace[1507796522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5014; }","duration":"379.383439ms","start":"2026-06-02T23:55:41.260308Z","end":"2026-06-02T23:55:41.639691Z","steps":["trace[1507796522] 'agreement among raft nodes before linearized reading' (duration: 379.317748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:41.639734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:41.260292Z","time spent":"379.43579ms","remote":"127.0.0.1:38312","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-06-02T23:55:41.639803Z","caller":"traceutil/trace.go:171","msg":"trace[1616781748] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"489.494222ms","start":"2026-06-02T23:55:41.150277Z","end":"2026-06-02T23:55:41.639771Z","steps":["trace[1616781748] 'process raft request' (duration: 489.122127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:41.639912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:41.150256Z","time spent":"489.607573ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:41.642128Z","caller":"traceutil/trace.go:171","msg":"trace[12773029] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"360.182073ms","start":"2026-06-02T23:55:41.279756Z","end":"2026-06-02T23:55:41.639938Z","steps":["trace[12773029] 'process raft request' (duration: 359.733207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:55:41.642402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T23:55:41.279714Z","time spent":"362.486935ms","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9565,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T23:55:42.007339Z","caller":"traceutil/trace.go:171","msg":"trace[1422524564] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"117.271763ms","start":"2026-06-02T23:55:41.890028Z","end":"2026-06-02T23:55:42.0073Z","steps":["trace[1422524564] 'process raft request' (duration: 37.370538ms)","trace[1422524564] 'compare' (duration: 79.757093ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:45.840666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.725463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:55:45.84106Z","caller":"traceutil/trace.go:171","msg":"trace[1516896692] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"112.612567ms","start":"2026-06-02T23:55:45.728425Z","end":"2026-06-02T23:55:45.841037Z","steps":["trace[1516896692] 'process raft request' (duration: 11.392967ms)","trace[1516896692] 'compare' (duration: 100.541591ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:55:57.649358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.698801ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T23:56:02.458304Z","caller":"traceutil/trace.go:171","msg":"trace[1153634014] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"127.183637ms","start":"2026-06-02T23:56:02.331022Z","end":"2026-06-02T23:56:02.458206Z","steps":["trace[1153634014] 'process raft request' (duration: 122.035636ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:11.844706Z","caller":"traceutil/trace.go:171","msg":"trace[588805151] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"112.139698ms","start":"2026-06-02T23:56:11.73254Z","end":"2026-06-02T23:56:11.84468Z","steps":["trace[588805151] 'process raft request' (duration: 111.969796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:56:12.108461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.685659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:56:12.108712Z","caller":"traceutil/trace.go:171","msg":"trace[2073646195] linearizableReadLoop","detail":"{readStateIndex:5482; appliedIndex:5481; }","duration":"143.711784ms","start":"2026-06-02T23:56:11.964974Z","end":"2026-06-02T23:56:12.108686Z","steps":["trace[2073646195] 'read index received' (duration: 46.161µs)","trace[2073646195] 'applied index is now lower than readState.Index' (duration: 143.663653ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:56:12.108739Z","caller":"traceutil/trace.go:171","msg":"trace[1412098198] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"162.444552ms","start":"2026-06-02T23:56:11.946258Z","end":"2026-06-02T23:56:12.108703Z","steps":["trace[1412098198] 'process raft request' (duration: 11.419908ms)","trace[1412098198] 'compare' (duration: 150.569058ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T23:56:12.108952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.964127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/keystone-credential-setup-287tt\" ","response":"range_response_count:1 size:2893"} {"level":"info","ts":"2026-06-02T23:56:12.108994Z","caller":"traceutil/trace.go:171","msg":"trace[247756681] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/keystone-credential-setup-287tt; range_end:; response_count:1; response_revision:5281; }","duration":"144.047738ms","start":"2026-06-02T23:56:11.964935Z","end":"2026-06-02T23:56:12.108982Z","steps":["trace[247756681] 'agreement among raft nodes before linearized reading' (duration: 143.882646ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:12.362468Z","caller":"traceutil/trace.go:171","msg":"trace[1666389027] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"248.950206ms","start":"2026-06-02T23:56:12.113475Z","end":"2026-06-02T23:56:12.362425Z","steps":["trace[1666389027] 'process raft request' (duration: 218.667838ms)","trace[1666389027] 'compare' (duration: 29.614519ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:56:12.362985Z","caller":"traceutil/trace.go:171","msg":"trace[1562746232] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"249.268401ms","start":"2026-06-02T23:56:12.113688Z","end":"2026-06-02T23:56:12.362956Z","steps":["trace[1562746232] 'process raft request' (duration: 248.301847ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:14.040313Z","caller":"traceutil/trace.go:171","msg":"trace[761458035] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"100.400016ms","start":"2026-06-02T23:56:13.939881Z","end":"2026-06-02T23:56:14.040281Z","steps":["trace[761458035] 'process raft request' (duration: 53.353177ms)","trace[761458035] 'compare' (duration: 46.591333ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T23:56:18.709447Z","caller":"traceutil/trace.go:171","msg":"trace[517266366] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"102.578126ms","start":"2026-06-02T23:56:18.606841Z","end":"2026-06-02T23:56:18.709419Z","steps":["trace[517266366] 'process raft request' (duration: 102.13951ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:29.972444Z","caller":"traceutil/trace.go:171","msg":"trace[2071442468] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"103.609958ms","start":"2026-06-02T23:56:29.868804Z","end":"2026-06-02T23:56:29.972414Z","steps":["trace[2071442468] 'process raft request' (duration: 103.473306ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T23:56:38.859961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2814} {"level":"info","ts":"2026-06-02T23:56:38.962394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2814,"took":"100.923201ms","hash":2718523420,"current-db-size-bytes":53596160,"current-db-size":"54 MB","current-db-size-in-use-bytes":28930048,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-02T23:56:38.962547Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2718523420,"revision":2814,"compact-revision":-1} {"level":"warn","ts":"2026-06-02T23:59:23.962592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.841832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/atmosphere\" ","response":"range_response_count:1 size:1108"} {"level":"info","ts":"2026-06-02T23:59:23.962675Z","caller":"traceutil/trace.go:171","msg":"trace[1984205627] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/atmosphere; range_end:; response_count:1; response_revision:6643; }","duration":"124.944403ms","start":"2026-06-02T23:59:23.837712Z","end":"2026-06-02T23:59:23.962657Z","steps":["trace[1984205627] 'range keys from in-memory index tree' (duration: 124.71774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T23:59:27.672162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.391034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T23:59:27.672577Z","caller":"traceutil/trace.go:171","msg":"trace[530907866] transaction","detail":"{read_only:false; response_revision:6662; number_of_response:1; }","duration":"129.366953ms","start":"2026-06-02T23:59:27.543186Z","end":"2026-06-02T23:59:27.672553Z","steps":["trace[530907866] 'process raft request' (duration: 15.502342ms)","trace[530907866] 'compare' (duration: 113.294123ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:00:20.779636Z","caller":"traceutil/trace.go:171","msg":"trace[645723970] transaction","detail":"{read_only:false; response_revision:6989; number_of_response:1; }","duration":"113.423224ms","start":"2026-06-03T00:00:20.666182Z","end":"2026-06-03T00:00:20.779605Z","steps":["trace[645723970] 'process raft request' (duration: 113.239411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:00:20.968749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.464575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T00:00:20.968842Z","caller":"traceutil/trace.go:171","msg":"trace[823284238] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:6989; }","duration":"162.643218ms","start":"2026-06-03T00:00:20.806176Z","end":"2026-06-03T00:00:20.96882Z","steps":["trace[823284238] 'count revisions from in-memory index tree' (duration: 162.344184ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:00:27.929874Z","caller":"traceutil/trace.go:171","msg":"trace[1184450038] transaction","detail":"{read_only:false; response_revision:7025; number_of_response:1; }","duration":"134.837727ms","start":"2026-06-03T00:00:27.795016Z","end":"2026-06-03T00:00:27.929854Z","steps":["trace[1184450038] 'process raft request' (duration: 134.729306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:00:28.088318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.939353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T00:00:28.08843Z","caller":"traceutil/trace.go:171","msg":"trace[972291364] linearizableReadLoop","detail":"{readStateIndex:7288; appliedIndex:7287; }","duration":"119.358435ms","start":"2026-06-03T00:00:27.969054Z","end":"2026-06-03T00:00:28.088412Z","steps":["trace[972291364] 'read index received' (duration: 10.20305ms)","trace[972291364] 'applied index is now lower than readState.Index' (duration: 109.154395ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:00:28.0885Z","caller":"traceutil/trace.go:171","msg":"trace[338772579] transaction","detail":"{read_only:false; response_revision:7026; number_of_response:1; }","duration":"122.797202ms","start":"2026-06-03T00:00:27.965662Z","end":"2026-06-03T00:00:28.088459Z","steps":["trace[338772579] 'process raft request' (duration: 13.624656ms)","trace[338772579] 'compare' (duration: 108.820451ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T00:00:28.088542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.493087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T00:00:28.088692Z","caller":"traceutil/trace.go:171","msg":"trace[1714710937] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:7026; }","duration":"119.674549ms","start":"2026-06-03T00:00:27.969006Z","end":"2026-06-03T00:00:28.08868Z","steps":["trace[1714710937] 'agreement among raft nodes before linearized reading' (duration: 119.496816ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:00:28.270215Z","caller":"traceutil/trace.go:171","msg":"trace[1585504385] linearizableReadLoop","detail":"{readStateIndex:7289; appliedIndex:7288; }","duration":"102.074108ms","start":"2026-06-03T00:00:28.16811Z","end":"2026-06-03T00:00:28.270184Z","steps":["trace[1585504385] 'read index received' (duration: 62.07174ms)","trace[1585504385] 'applied index is now lower than readState.Index' (duration: 40.001128ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T00:00:28.270386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.24625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-03T00:00:28.270432Z","caller":"traceutil/trace.go:171","msg":"trace[1205513338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:7027; }","duration":"102.332921ms","start":"2026-06-03T00:00:28.168085Z","end":"2026-06-03T00:00:28.270417Z","steps":["trace[1205513338] 'agreement among raft nodes before linearized reading' (duration: 102.23044ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:00:28.270258Z","caller":"traceutil/trace.go:171","msg":"trace[6603305] transaction","detail":"{read_only:false; response_revision:7027; number_of_response:1; }","duration":"107.513503ms","start":"2026-06-03T00:00:28.162713Z","end":"2026-06-03T00:00:28.270227Z","steps":["trace[6603305] 'process raft request' (duration: 67.455084ms)","trace[6603305] 'compare' (duration: 39.875306ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:01:03.858781Z","caller":"traceutil/trace.go:171","msg":"trace[16616992] linearizableReadLoop","detail":"{readStateIndex:7475; appliedIndex:7474; }","duration":"106.644ms","start":"2026-06-03T00:01:03.752116Z","end":"2026-06-03T00:01:03.85876Z","steps":["trace[16616992] 'read index received' (duration: 106.468568ms)","trace[16616992] 'applied index is now lower than readState.Index' (duration: 174.572µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:01:03.859157Z","caller":"traceutil/trace.go:171","msg":"trace[127810747] transaction","detail":"{read_only:false; response_revision:7206; number_of_response:1; }","duration":"112.699133ms","start":"2026-06-03T00:01:03.746438Z","end":"2026-06-03T00:01:03.859137Z","steps":["trace[127810747] 'process raft request' (duration: 112.182826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T00:01:03.859188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.052386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-03T00:01:03.859343Z","caller":"traceutil/trace.go:171","msg":"trace[1974725178] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7206; }","duration":"107.247679ms","start":"2026-06-03T00:01:03.752078Z","end":"2026-06-03T00:01:03.859326Z","steps":["trace[1974725178] 'agreement among raft nodes before linearized reading' (duration: 106.954645ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:01:38.866448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5498} {"level":"info","ts":"2026-06-03T00:01:38.956805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5498,"took":"88.786235ms","hash":3081851383,"current-db-size-bytes":53596160,"current-db-size":"54 MB","current-db-size-in-use-bytes":18538496,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-03T00:01:38.95686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3081851383,"revision":5498,"compact-revision":2814} {"level":"info","ts":"2026-06-03T00:04:59.249952Z","caller":"traceutil/trace.go:171","msg":"trace[264072126] linearizableReadLoop","detail":"{readStateIndex:8712; appliedIndex:8711; }","duration":"138.89499ms","start":"2026-06-03T00:04:59.11103Z","end":"2026-06-03T00:04:59.249925Z","steps":["trace[264072126] 'read index received' (duration: 45.262809ms)","trace[264072126] 'applied index is now lower than readState.Index' (duration: 93.631171ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T00:04:59.25006Z","caller":"traceutil/trace.go:171","msg":"trace[361863585] transaction","detail":"{read_only:false; response_revision:8395; number_of_response:1; }","duration":"143.094748ms","start":"2026-06-03T00:04:59.106927Z","end":"2026-06-03T00:04:59.250022Z","steps":["trace[361863585] 'process raft request' (duration: 49.428987ms)","trace[361863585] 'compare' (duration: 93.439688ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T00:04:59.250317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.505329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-node-rsrc-use\" ","response":"range_response_count:1 size:14617"} {"level":"warn","ts":"2026-06-03T00:04:59.250347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.292785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-node-exporter\" ","response":"range_response_count:1 size:1340"} {"level":"info","ts":"2026-06-03T00:04:59.250403Z","caller":"traceutil/trace.go:171","msg":"trace[1315804057] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-node-exporter; range_end:; response_count:1; response_revision:8395; }","duration":"139.389177ms","start":"2026-06-03T00:04:59.110999Z","end":"2026-06-03T00:04:59.250388Z","steps":["trace[1315804057] 'agreement among raft nodes before linearized reading' (duration: 139.061363ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T00:04:59.250402Z","caller":"traceutil/trace.go:171","msg":"trace[1223055944] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-node-rsrc-use; range_end:; response_count:1; response_revision:8395; }","duration":"120.63757ms","start":"2026-06-03T00:04:59.129745Z","end":"2026-06-03T00:04:59.250382Z","steps":["trace[1223055944] 'agreement among raft nodes before linearized reading' (duration: 120.379647ms)"],"step_count":1}