{"level":"warn","ts":"2026-04-13T17:10:39.270086Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:10:39.270195Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.171:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.171:2380","--initial-cluster=instance=https://199.19.213.171:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.171:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.171:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-13T17:10:39.270277Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:10:39.270294Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-04-13T17:10:39.270316Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T17:10:39.27121Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"]} {"level":"info","ts":"2026-04-13T17:10:39.271385Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.171:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-13T17:10:39.276712Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.007274ms"} {"level":"info","ts":"2026-04-13T17:10:39.284078Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"837e89131cc6bd81","cluster-id":"8af92e75ab8f3e69"} {"level":"info","ts":"2026-04-13T17:10:39.284161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:10:39.284186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 0"} {"level":"info","ts":"2026-04-13T17:10:39.284203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 837e89131cc6bd81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:10:39.284219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 1"} {"level":"info","ts":"2026-04-13T17:10:39.284263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"warn","ts":"2026-04-13T17:10:39.29436Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:10:39.297129Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:10:39.298555Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-13T17:10:39.30084Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"837e89131cc6bd81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:10:39.30147Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"837e89131cc6bd81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:10:39.301582Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T17:10:39.3017Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T17:10:39.30173Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T17:10:39.303505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"info","ts":"2026-04-13T17:10:39.303635Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","added-peer-id":"837e89131cc6bd81","added-peer-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-04-13T17:10:39.305796Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T17:10:39.306027Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"837e89131cc6bd81","initial-advertise-peer-urls":["https://199.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:10:39.30606Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:10:39.306129Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-04-13T17:10:39.306142Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-04-13T17:10:40.084639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:10:40.0848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:10:40.084847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgPreVoteResp from 837e89131cc6bd81 at term 1"} {"level":"info","ts":"2026-04-13T17:10:40.084876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:10:40.084888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgVoteResp from 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-04-13T17:10:40.084907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became leader at term 2"} {"level":"info","ts":"2026-04-13T17:10:40.084928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 837e89131cc6bd81 elected leader 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-04-13T17:10:40.086407Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:10:40.08676Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"837e89131cc6bd81","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.171:2379]}","request-path":"/0/members/837e89131cc6bd81/attributes","cluster-id":"8af92e75ab8f3e69","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:10:40.086777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:10:40.086807Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:10:40.087238Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:10:40.087277Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:10:40.087784Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:10:40.087934Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:10:40.087984Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:10:40.090347Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.171:2379"} {"level":"info","ts":"2026-04-13T17:10:40.09078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:10:43.24036Z","caller":"traceutil/trace.go:171","msg":"trace[134520497] linearizableReadLoop","detail":"{readStateIndex:190; appliedIndex:189; }","duration":"125.245737ms","start":"2026-04-13T17:10:43.115083Z","end":"2026-04-13T17:10:43.240328Z","steps":["trace[134520497] 'read index received' (duration: 73.681656ms)","trace[134520497] 'applied index is now lower than readState.Index' (duration: 51.562981ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:43.240425Z","caller":"traceutil/trace.go:171","msg":"trace[938988630] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"125.404531ms","start":"2026-04-13T17:10:43.114984Z","end":"2026-04-13T17:10:43.240388Z","steps":["trace[938988630] 'process raft request' (duration: 73.791079ms)","trace[938988630] 'compare' (duration: 51.370476ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:10:43.240509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.411152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:10:43.240688Z","caller":"traceutil/trace.go:171","msg":"trace[1950347] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:0; response_revision:186; }","duration":"125.626278ms","start":"2026-04-13T17:10:43.115049Z","end":"2026-04-13T17:10:43.240676Z","steps":["trace[1950347] 'agreement among raft nodes before linearized reading' (duration: 125.391422ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:48.967495Z","caller":"traceutil/trace.go:171","msg":"trace[483244105] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"252.856615ms","start":"2026-04-13T17:10:48.714596Z","end":"2026-04-13T17:10:48.967453Z","steps":["trace[483244105] 'process raft request' (duration: 252.630469ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:48.967458Z","caller":"traceutil/trace.go:171","msg":"trace[115314536] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"212.316975ms","start":"2026-04-13T17:10:48.755104Z","end":"2026-04-13T17:10:48.967421Z","steps":["trace[115314536] 'read index received' (duration: 212.163441ms)","trace[115314536] 'applied index is now lower than readState.Index' (duration: 152.094µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:10:48.967772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.630862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-13T17:10:48.967945Z","caller":"traceutil/trace.go:171","msg":"trace[1590902677] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:293; }","duration":"212.862748ms","start":"2026-04-13T17:10:48.75505Z","end":"2026-04-13T17:10:48.967913Z","steps":["trace[1590902677] 'agreement among raft nodes before linearized reading' (duration: 212.508409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:48.96798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.100191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-13T17:10:48.968062Z","caller":"traceutil/trace.go:171","msg":"trace[1499848206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:293; }","duration":"163.247835ms","start":"2026-04-13T17:10:48.804777Z","end":"2026-04-13T17:10:48.968025Z","steps":["trace[1499848206] 'agreement among raft nodes before linearized reading' (duration: 163.032919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:48.968463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.138989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-13T17:10:48.968688Z","caller":"traceutil/trace.go:171","msg":"trace[908797385] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:293; }","duration":"113.464906ms","start":"2026-04-13T17:10:48.855129Z","end":"2026-04-13T17:10:48.968594Z","steps":["trace[908797385] 'agreement among raft nodes before linearized reading' (duration: 113.006425ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:49.368161Z","caller":"traceutil/trace.go:171","msg":"trace[1110514237] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:306; }","duration":"364.008155ms","start":"2026-04-13T17:10:49.004126Z","end":"2026-04-13T17:10:49.368134Z","steps":["trace[1110514237] 'read index received' (duration: 351.626159ms)","trace[1110514237] 'applied index is now lower than readState.Index' (duration: 12.378196ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:10:49.368251Z","caller":"traceutil/trace.go:171","msg":"trace[1233294158] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"391.217186ms","start":"2026-04-13T17:10:48.976999Z","end":"2026-04-13T17:10:49.368216Z","steps":["trace[1233294158] 'process raft request' (duration: 378.751328ms)","trace[1233294158] 'compare' (duration: 12.218252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:10:49.368398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.255741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-13T17:10:49.368455Z","caller":"traceutil/trace.go:171","msg":"trace[1623639735] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:294; }","duration":"364.377324ms","start":"2026-04-13T17:10:49.00406Z","end":"2026-04-13T17:10:49.368437Z","steps":["trace[1623639735] 'agreement among raft nodes before linearized reading' (duration: 364.21649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:49.368501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:49.004028Z","time spent":"364.463746ms","remote":"127.0.0.1:52648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-13T17:10:49.368578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.35817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-04-13T17:10:49.368569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.213451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"warn","ts":"2026-04-13T17:10:49.368682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:48.97697Z","time spent":"391.338779ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:10:49.368696Z","caller":"traceutil/trace.go:171","msg":"trace[1203448009] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:294; }","duration":"259.414137ms","start":"2026-04-13T17:10:49.109265Z","end":"2026-04-13T17:10:49.368679Z","steps":["trace[1203448009] 'agreement among raft nodes before linearized reading' (duration: 259.209082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:49.368659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.732321ms","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-04-13T17:10:49.368675Z","caller":"traceutil/trace.go:171","msg":"trace[1574769769] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:294; }","duration":"258.470132ms","start":"2026-04-13T17:10:49.11018Z","end":"2026-04-13T17:10:49.36865Z","steps":["trace[1574769769] 'agreement among raft nodes before linearized reading' (duration: 258.245997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:49.368765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.442717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-13T17:10:49.368773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.588971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-13T17:10:49.368808Z","caller":"traceutil/trace.go:171","msg":"trace[573951867] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:294; }","duration":"214.515339ms","start":"2026-04-13T17:10:49.15428Z","end":"2026-04-13T17:10:49.368795Z","steps":["trace[573951867] 'agreement among raft nodes before linearized reading' (duration: 214.428577ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:49.368802Z","caller":"traceutil/trace.go:171","msg":"trace[848563323] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:294; }","duration":"312.966517ms","start":"2026-04-13T17:10:49.055803Z","end":"2026-04-13T17:10:49.36877Z","steps":["trace[848563323] 'agreement among raft nodes before linearized reading' (duration: 312.570328ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:49.36883Z","caller":"traceutil/trace.go:171","msg":"trace[266389905] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:294; }","duration":"259.641412ms","start":"2026-04-13T17:10:49.109172Z","end":"2026-04-13T17:10:49.368813Z","steps":["trace[266389905] 'agreement among raft nodes before linearized reading' (duration: 259.56265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:49.368879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:10:49.055771Z","time spent":"313.094079ms","remote":"127.0.0.1:52648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-13T17:10:49.368877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.038224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-13T17:10:49.368902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.734549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-04-13T17:10:49.368908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.778095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-13T17:10:49.368962Z","caller":"traceutil/trace.go:171","msg":"trace[1897228256] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:294; }","duration":"258.81459ms","start":"2026-04-13T17:10:49.110136Z","end":"2026-04-13T17:10:49.36895Z","steps":["trace[1897228256] 'agreement among raft nodes before linearized reading' (duration: 258.712478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:49.36897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.293882ms","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-04-13T17:10:49.369013Z","caller":"traceutil/trace.go:171","msg":"trace[2002855394] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:294; }","duration":"263.400865ms","start":"2026-04-13T17:10:49.105603Z","end":"2026-04-13T17:10:49.369004Z","steps":["trace[2002855394] 'agreement among raft nodes before linearized reading' (duration: 263.330453ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:49.369026Z","caller":"traceutil/trace.go:171","msg":"trace[444839531] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:294; }","duration":"259.95885ms","start":"2026-04-13T17:10:49.109039Z","end":"2026-04-13T17:10:49.368998Z","steps":["trace[444839531] 'agreement among raft nodes before linearized reading' (duration: 259.695113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:49.369061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.504418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-13T17:10:49.369105Z","caller":"traceutil/trace.go:171","msg":"trace[1055384102] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:294; }","duration":"259.567409ms","start":"2026-04-13T17:10:49.109526Z","end":"2026-04-13T17:10:49.369094Z","steps":["trace[1055384102] 'agreement among raft nodes before linearized reading' (duration: 259.392785ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:10:49.369306Z","caller":"traceutil/trace.go:171","msg":"trace[1515523064] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:294; }","duration":"164.524736ms","start":"2026-04-13T17:10:49.204742Z","end":"2026-04-13T17:10:49.369266Z","steps":["trace[1515523064] 'agreement among raft nodes before linearized reading' (duration: 163.885001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:10:49.369859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.178205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-13T17:10:49.370018Z","caller":"traceutil/trace.go:171","msg":"trace[549874812] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:294; }","duration":"114.506263ms","start":"2026-04-13T17:10:49.255495Z","end":"2026-04-13T17:10:49.370001Z","steps":["trace[549874812] 'agreement among raft nodes before linearized reading' (duration: 113.043257ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:16.085262Z","caller":"traceutil/trace.go:171","msg":"trace[1056849080] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"120.171778ms","start":"2026-04-13T17:11:15.965064Z","end":"2026-04-13T17:11:16.085236Z","steps":["trace[1056849080] 'process raft request' (duration: 120.016425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:16.511714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.566723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b8jf8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-13T17:11:16.511815Z","caller":"traceutil/trace.go:171","msg":"trace[1131291242] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b8jf8; range_end:; response_count:1; response_revision:570; }","duration":"335.725457ms","start":"2026-04-13T17:11:16.176067Z","end":"2026-04-13T17:11:16.511792Z","steps":["trace[1131291242] 'range keys from in-memory index tree' (duration: 335.364678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:16.511859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.17604Z","time spent":"335.81034ms","remote":"127.0.0.1:52638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-b8jf8\" "} {"level":"info","ts":"2026-04-13T17:11:16.759658Z","caller":"traceutil/trace.go:171","msg":"trace[932527756] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"330.014205ms","start":"2026-04-13T17:11:16.429563Z","end":"2026-04-13T17:11:16.759577Z","steps":["trace[932527756] 'read index received' (duration: 325.657027ms)","trace[932527756] 'applied index is now lower than readState.Index' (duration: 4.355838ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:16.75983Z","caller":"traceutil/trace.go:171","msg":"trace[1095443694] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"576.271652ms","start":"2026-04-13T17:11:16.18347Z","end":"2026-04-13T17:11:16.759741Z","steps":["trace[1095443694] 'process raft request' (duration: 574.131268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:16.759873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.289593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-13T17:11:16.760191Z","caller":"traceutil/trace.go:171","msg":"trace[535181122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"330.621281ms","start":"2026-04-13T17:11:16.429515Z","end":"2026-04-13T17:11:16.760136Z","steps":["trace[535181122] 'agreement among raft nodes before linearized reading' (duration: 330.201771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:16.761305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.429496Z","time spent":"331.766809ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:11:16.761389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.183437Z","time spent":"576.571009ms","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:11:16.781385Z","caller":"traceutil/trace.go:171","msg":"trace[1987334909] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"254.115291ms","start":"2026-04-13T17:11:16.527241Z","end":"2026-04-13T17:11:16.781356Z","steps":["trace[1987334909] 'process raft request' (duration: 253.996518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.358815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.642049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:11:17.359031Z","caller":"traceutil/trace.go:171","msg":"trace[387692755] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"482.829591ms","start":"2026-04-13T17:11:16.876186Z","end":"2026-04-13T17:11:17.359016Z","steps":["trace[387692755] 'read index received' (duration: 17.754751ms)","trace[387692755] 'applied index is now lower than readState.Index' (duration: 465.07357ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:17.359073Z","caller":"traceutil/trace.go:171","msg":"trace[2033064837] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"539.366805ms","start":"2026-04-13T17:11:16.819681Z","end":"2026-04-13T17:11:17.359048Z","steps":["trace[2033064837] 'process raft request' (duration: 539.247042ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:17.35906Z","caller":"traceutil/trace.go:171","msg":"trace[78067171] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"594.257938ms","start":"2026-04-13T17:11:16.764764Z","end":"2026-04-13T17:11:17.359022Z","steps":["trace[78067171] 'process raft request' (duration: 129.158238ms)","trace[78067171] 'compare' (duration: 464.362011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:17.359161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.819644Z","time spent":"539.480178ms","remote":"127.0.0.1:52962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T17:11:17.359193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.004686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:11:17.359243Z","caller":"traceutil/trace.go:171","msg":"trace[1353092597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"483.112948ms","start":"2026-04-13T17:11:16.876116Z","end":"2026-04-13T17:11:17.359229Z","steps":["trace[1353092597] 'agreement among raft nodes before linearized reading' (duration: 482.995175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:17.359244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.764746Z","time spent":"594.396681ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:11:17.359266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.60985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:11:17.359286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:16.876091Z","time spent":"483.188551ms","remote":"127.0.0.1:52438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T17:11:17.3593Z","caller":"traceutil/trace.go:171","msg":"trace[1350949996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"216.705253ms","start":"2026-04-13T17:11:17.142585Z","end":"2026-04-13T17:11:17.35929Z","steps":["trace[1350949996] 'agreement among raft nodes before linearized reading' (duration: 216.58586ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:11.195388Z","caller":"traceutil/trace.go:171","msg":"trace[659843428] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"246.243925ms","start":"2026-04-13T17:12:10.949124Z","end":"2026-04-13T17:12:11.195368Z","steps":["trace[659843428] 'process raft request' (duration: 246.123691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:54.614286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.989665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d44da858-1f4e-48d2-a624-0e269293cb49\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-13T17:12:54.614397Z","caller":"traceutil/trace.go:171","msg":"trace[394240598] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d44da858-1f4e-48d2-a624-0e269293cb49; range_end:; response_count:1; response_revision:1806; }","duration":"106.146939ms","start":"2026-04-13T17:12:54.508224Z","end":"2026-04-13T17:12:54.614371Z","steps":["trace[394240598] 'range keys from in-memory index tree' (duration: 105.784471ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:54.990386Z","caller":"traceutil/trace.go:171","msg":"trace[1625095056] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"299.472031ms","start":"2026-04-13T17:12:54.690886Z","end":"2026-04-13T17:12:54.990358Z","steps":["trace[1625095056] 'process raft request' (duration: 299.363978ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:54.990425Z","caller":"traceutil/trace.go:171","msg":"trace[290462864] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"314.193552ms","start":"2026-04-13T17:12:54.67619Z","end":"2026-04-13T17:12:54.990384Z","steps":["trace[290462864] 'read index received' (duration: 268.503833ms)","trace[290462864] 'applied index is now lower than readState.Index' (duration: 45.686609ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:12:54.990468Z","caller":"traceutil/trace.go:171","msg":"trace[1192052228] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"371.925704ms","start":"2026-04-13T17:12:54.618468Z","end":"2026-04-13T17:12:54.990393Z","steps":["trace[1192052228] 'process raft request' (duration: 326.205274ms)","trace[1192052228] 'compare' (duration: 45.132625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:12:54.990689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:54.618445Z","time spent":"372.122999ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:12:54.990744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.505999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10630"} {"level":"info","ts":"2026-04-13T17:12:54.990787Z","caller":"traceutil/trace.go:171","msg":"trace[1213671657] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1809; }","duration":"314.620152ms","start":"2026-04-13T17:12:54.676156Z","end":"2026-04-13T17:12:54.990776Z","steps":["trace[1213671657] 'agreement among raft nodes before linearized reading' (duration: 314.354896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:54.990845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:12:54.676137Z","time spent":"314.693224ms","remote":"127.0.0.1:52638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10654,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"info","ts":"2026-04-13T17:12:55.16469Z","caller":"traceutil/trace.go:171","msg":"trace[1134522707] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"105.165015ms","start":"2026-04-13T17:12:55.059506Z","end":"2026-04-13T17:12:55.164671Z","steps":["trace[1134522707] 'process raft request' (duration: 105.059482ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:55.164715Z","caller":"traceutil/trace.go:171","msg":"trace[519689672] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"105.225686ms","start":"2026-04-13T17:12:55.059451Z","end":"2026-04-13T17:12:55.164677Z","steps":["trace[519689672] 'process raft request' (duration: 104.940339ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:55.165069Z","caller":"traceutil/trace.go:171","msg":"trace[576158853] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"101.600775ms","start":"2026-04-13T17:12:55.063453Z","end":"2026-04-13T17:12:55.165054Z","steps":["trace[576158853] 'process raft request' (duration: 101.437601ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:55.165063Z","caller":"traceutil/trace.go:171","msg":"trace[1559867581] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"104.887608ms","start":"2026-04-13T17:12:55.060151Z","end":"2026-04-13T17:12:55.165038Z","steps":["trace[1559867581] 'process raft request' (duration: 104.450857ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:44.019556Z","caller":"traceutil/trace.go:171","msg":"trace[1185050862] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"161.233403ms","start":"2026-04-13T17:13:43.8583Z","end":"2026-04-13T17:13:44.019533Z","steps":["trace[1185050862] 'process raft request' (duration: 161.040208ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:44.260626Z","caller":"traceutil/trace.go:171","msg":"trace[810770520] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2211; }","duration":"156.475334ms","start":"2026-04-13T17:13:44.104122Z","end":"2026-04-13T17:13:44.260597Z","steps":["trace[810770520] 'read index received' (duration: 137.056991ms)","trace[810770520] 'applied index is now lower than readState.Index' (duration: 19.417093ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:44.260903Z","caller":"traceutil/trace.go:171","msg":"trace[102752361] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"252.060614ms","start":"2026-04-13T17:13:44.008826Z","end":"2026-04-13T17:13:44.260886Z","steps":["trace[102752361] 'process raft request' (duration: 232.302923ms)","trace[102752361] 'compare' (duration: 19.239269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:13:44.260903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.817117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:44.260986Z","caller":"traceutil/trace.go:171","msg":"trace[1337764264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2126; }","duration":"120.9534ms","start":"2026-04-13T17:13:44.140014Z","end":"2026-04-13T17:13:44.260968Z","steps":["trace[1337764264] 'agreement among raft nodes before linearized reading' (duration: 120.822227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:44.261071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.909015ms","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-04-13T17:13:44.26121Z","caller":"traceutil/trace.go:171","msg":"trace[943995740] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2126; }","duration":"157.144081ms","start":"2026-04-13T17:13:44.104042Z","end":"2026-04-13T17:13:44.261186Z","steps":["trace[943995740] 'agreement among raft nodes before linearized reading' (duration: 156.773822ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:24.669951Z","caller":"traceutil/trace.go:171","msg":"trace[913190964] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"100.727787ms","start":"2026-04-13T17:14:24.569155Z","end":"2026-04-13T17:14:24.669883Z","steps":["trace[913190964] 'process raft request' (duration: 100.542432ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:58.617239Z","caller":"traceutil/trace.go:171","msg":"trace[1593003952] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"114.937387ms","start":"2026-04-13T17:14:58.502269Z","end":"2026-04-13T17:14:58.617206Z","steps":["trace[1593003952] 'process raft request' (duration: 85.654604ms)","trace[1593003952] 'compare' (duration: 28.832721ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:59.065632Z","caller":"traceutil/trace.go:171","msg":"trace[244228851] linearizableReadLoop","detail":"{readStateIndex:2838; appliedIndex:2837; }","duration":"365.439099ms","start":"2026-04-13T17:14:58.700169Z","end":"2026-04-13T17:14:59.065608Z","steps":["trace[244228851] 'read index received' (duration: 365.173453ms)","trace[244228851] 'applied index is now lower than readState.Index' (duration: 264.536µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:59.065776Z","caller":"traceutil/trace.go:171","msg":"trace[1064098202] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"365.87437ms","start":"2026-04-13T17:14:58.699882Z","end":"2026-04-13T17:14:59.065757Z","steps":["trace[1064098202] 'process raft request' (duration: 365.501511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:59.065956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:58.699867Z","time spent":"365.958802ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:14:59.066231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.031884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T17:14:59.066318Z","caller":"traceutil/trace.go:171","msg":"trace[1486067505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2729; }","duration":"366.151927ms","start":"2026-04-13T17:14:58.700148Z","end":"2026-04-13T17:14:59.0663Z","steps":["trace[1486067505] 'agreement among raft nodes before linearized reading' (duration: 365.911551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:59.066363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:58.700139Z","time spent":"366.215379ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T17:14:59.181801Z","caller":"traceutil/trace.go:171","msg":"trace[1788309301] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"481.547156ms","start":"2026-04-13T17:14:58.700226Z","end":"2026-04-13T17:14:59.181773Z","steps":["trace[1788309301] 'process raft request' (duration: 432.115168ms)","trace[1788309301] 'compare' (duration: 49.241934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:59.181997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:58.700215Z","time spent":"481.663969ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:14:59.182053Z","caller":"traceutil/trace.go:171","msg":"trace[909780914] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"479.580937ms","start":"2026-04-13T17:14:58.702441Z","end":"2026-04-13T17:14:59.182022Z","steps":["trace[909780914] 'process raft request' (duration: 479.275729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:59.182296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:58.702419Z","time spent":"479.721779ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:14:59.18232Z","caller":"traceutil/trace.go:171","msg":"trace[94089442] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"390.972328ms","start":"2026-04-13T17:14:58.791322Z","end":"2026-04-13T17:14:59.182294Z","steps":["trace[94089442] 'process raft request' (duration: 390.600539ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:59.182351Z","caller":"traceutil/trace.go:171","msg":"trace[1514898310] linearizableReadLoop","detail":"{readStateIndex:2841; appliedIndex:2838; }","duration":"116.628379ms","start":"2026-04-13T17:14:59.0657Z","end":"2026-04-13T17:14:59.182328Z","steps":["trace[1514898310] 'read index received' (duration: 66.605306ms)","trace[1514898310] 'applied index is now lower than readState.Index' (duration: 50.021743ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:59.182363Z","caller":"traceutil/trace.go:171","msg":"trace[1669594981] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"111.975423ms","start":"2026-04-13T17:14:59.070361Z","end":"2026-04-13T17:14:59.182337Z","steps":["trace[1669594981] 'process raft request' (duration: 111.85543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:59.182535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.70958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-13T17:14:59.182529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:14:58.791294Z","time spent":"391.07388ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:14:59.182577Z","caller":"traceutil/trace.go:171","msg":"trace[1178172958] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2733; }","duration":"207.793642ms","start":"2026-04-13T17:14:58.974775Z","end":"2026-04-13T17:14:59.182568Z","steps":["trace[1178172958] 'agreement among raft nodes before linearized reading' (duration: 207.636428ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:16:56.743713Z","caller":"traceutil/trace.go:171","msg":"trace[152691636] transaction","detail":"{read_only:false; response_revision:3481; number_of_response:1; }","duration":"113.144643ms","start":"2026-04-13T17:16:56.63054Z","end":"2026-04-13T17:16:56.743685Z","steps":["trace[152691636] 'process raft request' (duration: 113.083192ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:16:56.743804Z","caller":"traceutil/trace.go:171","msg":"trace[232269786] transaction","detail":"{read_only:false; response_revision:3480; number_of_response:1; }","duration":"114.926696ms","start":"2026-04-13T17:16:56.628834Z","end":"2026-04-13T17:16:56.743761Z","steps":["trace[232269786] 'process raft request' (duration: 94.337425ms)","trace[232269786] 'compare' (duration: 20.260523ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:17:29.820595Z","caller":"traceutil/trace.go:171","msg":"trace[543009711] transaction","detail":"{read_only:false; response_revision:3722; number_of_response:1; }","duration":"129.304724ms","start":"2026-04-13T17:17:29.691241Z","end":"2026-04-13T17:17:29.820546Z","steps":["trace[543009711] 'process raft request' (duration: 129.073808ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:17:30.008515Z","caller":"traceutil/trace.go:171","msg":"trace[1294977044] transaction","detail":"{read_only:false; response_revision:3723; number_of_response:1; }","duration":"183.602834ms","start":"2026-04-13T17:17:29.824888Z","end":"2026-04-13T17:17:30.00849Z","steps":["trace[1294977044] 'process raft request' (duration: 183.117792ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:51.108871Z","caller":"traceutil/trace.go:171","msg":"trace[736320547] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"151.114191ms","start":"2026-04-13T17:18:50.957724Z","end":"2026-04-13T17:18:51.108838Z","steps":["trace[736320547] 'process raft request' (duration: 150.965317ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:53.639477Z","caller":"traceutil/trace.go:171","msg":"trace[1083981371] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"170.929074ms","start":"2026-04-13T17:18:53.468527Z","end":"2026-04-13T17:18:53.639456Z","steps":["trace[1083981371] 'process raft request' (duration: 170.790091ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:53.640364Z","caller":"traceutil/trace.go:171","msg":"trace[65156075] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"171.738044ms","start":"2026-04-13T17:18:53.468586Z","end":"2026-04-13T17:18:53.640324Z","steps":["trace[65156075] 'process raft request' (duration: 171.536299ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:53.860751Z","caller":"traceutil/trace.go:171","msg":"trace[1319269620] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"216.065215ms","start":"2026-04-13T17:18:53.644662Z","end":"2026-04-13T17:18:53.860727Z","steps":["trace[1319269620] 'process raft request' (duration: 206.379354ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:05.146405Z","caller":"traceutil/trace.go:171","msg":"trace[2005719922] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"118.617095ms","start":"2026-04-13T17:19:05.027755Z","end":"2026-04-13T17:19:05.146373Z","steps":["trace[2005719922] 'process raft request' (duration: 81.493413ms)","trace[2005719922] 'compare' (duration: 36.913807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:19:12.286123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.972091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:19:12.286206Z","caller":"traceutil/trace.go:171","msg":"trace[1413238245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5298; }","duration":"143.086303ms","start":"2026-04-13T17:19:12.143101Z","end":"2026-04-13T17:19:12.286187Z","steps":["trace[1413238245] 'range keys from in-memory index tree' (duration: 142.870318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:12.286383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.361938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m64jm\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-13T17:19:12.286457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.352459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-13T17:19:12.286466Z","caller":"traceutil/trace.go:171","msg":"trace[1588100952] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m64jm; range_end:; response_count:1; response_revision:5298; }","duration":"155.481191ms","start":"2026-04-13T17:19:12.130966Z","end":"2026-04-13T17:19:12.286447Z","steps":["trace[1588100952] 'range keys from in-memory index tree' (duration: 155.160373ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:12.286496Z","caller":"traceutil/trace.go:171","msg":"trace[1752143302] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5298; }","duration":"153.41357ms","start":"2026-04-13T17:19:12.133072Z","end":"2026-04-13T17:19:12.286486Z","steps":["trace[1752143302] 'range keys from in-memory index tree' (duration: 153.201125ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:12.557341Z","caller":"traceutil/trace.go:171","msg":"trace[96280805] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"110.469573ms","start":"2026-04-13T17:19:12.446848Z","end":"2026-04-13T17:19:12.557318Z","steps":["trace[96280805] 'process raft request' (duration: 110.141375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:12.862987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.863003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:19:12.863253Z","caller":"traceutil/trace.go:171","msg":"trace[646073844] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5501; }","duration":"230.616787ms","start":"2026-04-13T17:19:12.632616Z","end":"2026-04-13T17:19:12.863233Z","steps":["trace[646073844] 'read index received' (duration: 61.433916ms)","trace[646073844] 'applied index is now lower than readState.Index' (duration: 169.180671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:19:12.863615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.976805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7691903d-48f7-4623-9ee2-042f21bae5af\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-13T17:19:12.863633Z","caller":"traceutil/trace.go:171","msg":"trace[379376544] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"300.306977ms","start":"2026-04-13T17:19:12.562997Z","end":"2026-04-13T17:19:12.863304Z","steps":["trace[379376544] 'process raft request' (duration: 131.040154ms)","trace[379376544] 'compare' (duration: 168.402782ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:12.863774Z","caller":"traceutil/trace.go:171","msg":"trace[1594487095] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7691903d-48f7-4623-9ee2-042f21bae5af; range_end:; response_count:1; response_revision:5302; }","duration":"231.143069ms","start":"2026-04-13T17:19:12.63257Z","end":"2026-04-13T17:19:12.863713Z","steps":["trace[1594487095] 'agreement among raft nodes before linearized reading' (duration: 230.819961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:12.865077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:19:12.56263Z","time spent":"301.169488ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:19:12.866264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.61228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T17:19:12.866376Z","caller":"traceutil/trace.go:171","msg":"trace[1419805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5302; }","duration":"210.763864ms","start":"2026-04-13T17:19:12.65559Z","end":"2026-04-13T17:19:12.866354Z","steps":["trace[1419805] 'agreement among raft nodes before linearized reading' (duration: 208.095258ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:13.052867Z","caller":"traceutil/trace.go:171","msg":"trace[2076030748] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"180.677477ms","start":"2026-04-13T17:19:12.872136Z","end":"2026-04-13T17:19:13.052813Z","steps":["trace[2076030748] 'process raft request' (duration: 104.960926ms)","trace[2076030748] 'compare' (duration: 75.367592ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:19:13.052898Z","caller":"traceutil/trace.go:171","msg":"trace[1406657446] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"180.317008ms","start":"2026-04-13T17:19:12.872546Z","end":"2026-04-13T17:19:13.052863Z","steps":["trace[1406657446] 'process raft request' (duration: 180.252876ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:13.05294Z","caller":"traceutil/trace.go:171","msg":"trace[1223145671] transaction","detail":"{read_only:false; response_revision:5305; number_of_response:1; }","duration":"180.618866ms","start":"2026-04-13T17:19:12.872283Z","end":"2026-04-13T17:19:13.052902Z","steps":["trace[1223145671] 'process raft request' (duration: 180.445961ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:13.052899Z","caller":"traceutil/trace.go:171","msg":"trace[2003135767] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"180.669717ms","start":"2026-04-13T17:19:12.872205Z","end":"2026-04-13T17:19:13.052875Z","steps":["trace[2003135767] 'process raft request' (duration: 180.420701ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:20:40.116854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2956} {"level":"info","ts":"2026-04-13T17:20:40.188726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2956,"took":"70.151638ms","hash":3676852342,"current-db-size-bytes":57266176,"current-db-size":"57 MB","current-db-size-in-use-bytes":33828864,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-13T17:20:40.188782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3676852342,"revision":2956,"compact-revision":-1} {"level":"info","ts":"2026-04-13T17:25:14.70141Z","caller":"traceutil/trace.go:171","msg":"trace[1220470435] transaction","detail":"{read_only:false; response_revision:8111; number_of_response:1; }","duration":"109.360288ms","start":"2026-04-13T17:25:14.592027Z","end":"2026-04-13T17:25:14.701388Z","steps":["trace[1220470435] 'process raft request' (duration: 109.227304ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:26.209729Z","caller":"traceutil/trace.go:171","msg":"trace[1578134115] linearizableReadLoop","detail":"{readStateIndex:8462; appliedIndex:8461; }","duration":"353.608682ms","start":"2026-04-13T17:25:25.856102Z","end":"2026-04-13T17:25:26.209711Z","steps":["trace[1578134115] 'read index received' (duration: 353.423557ms)","trace[1578134115] 'applied index is now lower than readState.Index' (duration: 184.085µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:26.209781Z","caller":"traceutil/trace.go:171","msg":"trace[1485902224] transaction","detail":"{read_only:false; response_revision:8171; number_of_response:1; }","duration":"371.666991ms","start":"2026-04-13T17:25:25.838085Z","end":"2026-04-13T17:25:26.209752Z","steps":["trace[1485902224] 'process raft request' (duration: 371.507227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:26.209949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:25.838059Z","time spent":"371.785464ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:25:26.210496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.377252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-13T17:25:26.21058Z","caller":"traceutil/trace.go:171","msg":"trace[1499181780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8171; }","duration":"354.549317ms","start":"2026-04-13T17:25:25.856014Z","end":"2026-04-13T17:25:26.210563Z","steps":["trace[1499181780] 'agreement among raft nodes before linearized reading' (duration: 353.811377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:26.210626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:25:25.855987Z","time spent":"354.629758ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:25:26.859114Z","caller":"traceutil/trace.go:171","msg":"trace[1996168243] linearizableReadLoop","detail":"{readStateIndex:8464; appliedIndex:8463; }","duration":"110.468833ms","start":"2026-04-13T17:25:26.748611Z","end":"2026-04-13T17:25:26.85908Z","steps":["trace[1996168243] 'read index received' (duration: 110.209237ms)","trace[1996168243] 'applied index is now lower than readState.Index' (duration: 258.116µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:26.859208Z","caller":"traceutil/trace.go:171","msg":"trace[1384258934] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"115.522309ms","start":"2026-04-13T17:25:26.743651Z","end":"2026-04-13T17:25:26.859173Z","steps":["trace[1384258934] 'process raft request' (duration: 115.258842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:26.859431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.792971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T17:25:26.859514Z","caller":"traceutil/trace.go:171","msg":"trace[437912177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8173; }","duration":"110.916845ms","start":"2026-04-13T17:25:26.748578Z","end":"2026-04-13T17:25:26.859495Z","steps":["trace[437912177] 'agreement among raft nodes before linearized reading' (duration: 110.649188ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:27.040818Z","caller":"traceutil/trace.go:171","msg":"trace[243711027] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"177.230142ms","start":"2026-04-13T17:25:26.863556Z","end":"2026-04-13T17:25:27.040786Z","steps":["trace[243711027] 'process raft request' (duration: 124.706227ms)","trace[243711027] 'compare' (duration: 52.34112ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:25:27.040829Z","caller":"traceutil/trace.go:171","msg":"trace[1917751014] linearizableReadLoop","detail":"{readStateIndex:8465; appliedIndex:8464; }","duration":"142.840628ms","start":"2026-04-13T17:25:26.897954Z","end":"2026-04-13T17:25:27.040795Z","steps":["trace[1917751014] 'read index received' (duration: 90.292682ms)","trace[1917751014] 'applied index is now lower than readState.Index' (duration: 52.542546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:25:27.041097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.108804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-13T17:25:27.041127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.211802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-13T17:25:27.041151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.989554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-13T17:25:27.041195Z","caller":"traceutil/trace.go:171","msg":"trace[443310730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8175; }","duration":"136.317535ms","start":"2026-04-13T17:25:26.904861Z","end":"2026-04-13T17:25:27.041179Z","steps":["trace[443310730] 'agreement among raft nodes before linearized reading' (duration: 136.130401ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:27.041198Z","caller":"traceutil/trace.go:171","msg":"trace[599078289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8175; }","duration":"143.227357ms","start":"2026-04-13T17:25:26.897949Z","end":"2026-04-13T17:25:27.041176Z","steps":["trace[599078289] 'agreement among raft nodes before linearized reading' (duration: 142.93681ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:27.041222Z","caller":"traceutil/trace.go:171","msg":"trace[9727699] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8175; }","duration":"125.106327ms","start":"2026-04-13T17:25:26.916094Z","end":"2026-04-13T17:25:27.0412Z","steps":["trace[9727699] 'agreement among raft nodes before linearized reading' (duration: 124.950533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:28.5281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.586929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m64jm\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-13T17:25:28.528173Z","caller":"traceutil/trace.go:171","msg":"trace[58781333] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m64jm; range_end:; response_count:1; response_revision:8183; }","duration":"127.702482ms","start":"2026-04-13T17:25:28.40045Z","end":"2026-04-13T17:25:28.528153Z","steps":["trace[58781333] 'range keys from in-memory index tree' (duration: 127.420924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:25:28.528291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.407036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:25:28.528436Z","caller":"traceutil/trace.go:171","msg":"trace[1761990748] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8183; }","duration":"113.58014ms","start":"2026-04-13T17:25:28.414833Z","end":"2026-04-13T17:25:28.528413Z","steps":["trace[1761990748] 'count revisions from in-memory index tree' (duration: 113.274503ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:40.129525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6083} {"level":"info","ts":"2026-04-13T17:25:40.227269Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6083,"took":"95.596114ms","hash":2007726001,"current-db-size-bytes":57266176,"current-db-size":"57 MB","current-db-size-in-use-bytes":23113728,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-13T17:25:40.22741Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2007726001,"revision":6083,"compact-revision":2956} {"level":"info","ts":"2026-04-13T17:25:56.025339Z","caller":"traceutil/trace.go:171","msg":"trace[816671504] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"182.970535ms","start":"2026-04-13T17:25:55.842313Z","end":"2026-04-13T17:25:56.025284Z","steps":["trace[816671504] 'process raft request' (duration: 182.78323ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.06728Z","caller":"traceutil/trace.go:171","msg":"trace[1557427008] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"101.04496ms","start":"2026-04-13T17:25:56.966202Z","end":"2026-04-13T17:25:57.067247Z","steps":["trace[1557427008] 'process raft request' (duration: 100.857425ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:25:57.340995Z","caller":"traceutil/trace.go:171","msg":"trace[1272887396] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"160.548417ms","start":"2026-04-13T17:25:57.180412Z","end":"2026-04-13T17:25:57.340961Z","steps":["trace[1272887396] 'process raft request' (duration: 160.329692ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:07.80135Z","caller":"traceutil/trace.go:171","msg":"trace[348209537] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8490; }","duration":"149.244846ms","start":"2026-04-13T17:26:07.652074Z","end":"2026-04-13T17:26:07.801319Z","steps":["trace[348209537] 'process raft request' (duration: 128.0359ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:07.93174Z","caller":"traceutil/trace.go:171","msg":"trace[1299472348] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"278.724314ms","start":"2026-04-13T17:26:07.652989Z","end":"2026-04-13T17:26:07.931713Z","steps":["trace[1299472348] 'process raft request' (duration: 270.150601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:08.762306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.667888ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-13T17:26:08.762494Z","caller":"traceutil/trace.go:171","msg":"trace[634801345] linearizableReadLoop","detail":"{readStateIndex:8803; appliedIndex:8802; }","duration":"309.502566ms","start":"2026-04-13T17:26:08.452972Z","end":"2026-04-13T17:26:08.762474Z","steps":["trace[634801345] 'read index received' (duration: 159.644535ms)","trace[634801345] 'applied index is now lower than readState.Index' (duration: 149.856421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:26:08.762722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.791844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-13T17:26:08.762784Z","caller":"traceutil/trace.go:171","msg":"trace[1991735923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8496; }","duration":"309.897077ms","start":"2026-04-13T17:26:08.45287Z","end":"2026-04-13T17:26:08.762767Z","steps":["trace[1991735923] 'agreement among raft nodes before linearized reading' (duration: 309.703662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:08.762822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:08.452847Z","time spent":"309.966179ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T17:26:08.774006Z","caller":"traceutil/trace.go:171","msg":"trace[1900010621] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"137.379422ms","start":"2026-04-13T17:26:08.63659Z","end":"2026-04-13T17:26:08.773969Z","steps":["trace[1900010621] 'process raft request' (duration: 137.115405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:08.774088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.834299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-13T17:26:08.774173Z","caller":"traceutil/trace.go:171","msg":"trace[798697429] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8497; }","duration":"171.966292ms","start":"2026-04-13T17:26:08.602188Z","end":"2026-04-13T17:26:08.774154Z","steps":["trace[798697429] 'agreement among raft nodes before linearized reading' (duration: 171.749206ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:59.017238Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002477.wal"} {"level":"info","ts":"2026-04-13T17:27:19.64911Z","caller":"traceutil/trace.go:171","msg":"trace[1871536593] transaction","detail":"{read_only:false; response_revision:9213; number_of_response:1; }","duration":"179.038317ms","start":"2026-04-13T17:27:19.470041Z","end":"2026-04-13T17:27:19.649079Z","steps":["trace[1871536593] 'process raft request' (duration: 178.840252ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:28:22.247099Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"837e89131cc6bd81","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-13T17:28:22.252725Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-13T17:28:22.252798Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-13T17:30:40.136552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8252} {"level":"info","ts":"2026-04-13T17:30:40.215872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8252,"took":"76.579658ms","hash":2470973870,"current-db-size-bytes":57266176,"current-db-size":"57 MB","current-db-size-in-use-bytes":24866816,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-13T17:30:40.215925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2470973870,"revision":8252,"compact-revision":6083} {"level":"info","ts":"2026-04-13T17:35:40.14317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10647} {"level":"info","ts":"2026-04-13T17:35:40.218392Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10647,"took":"73.420448ms","hash":3133151223,"current-db-size-bytes":57266176,"current-db-size":"57 MB","current-db-size-in-use-bytes":20262912,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-13T17:35:40.218445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3133151223,"revision":10647,"compact-revision":8252} {"level":"warn","ts":"2026-04-13T17:36:18.958625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368751848636073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:36:19.45938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368751848636073,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T17:36:19.697721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.244162523s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T17:36:19.698266Z","caller":"traceutil/trace.go:171","msg":"trace[233758883] transaction","detail":"{read_only:false; response_revision:12386; number_of_response:1; }","duration":"1.244785518s","start":"2026-04-13T17:36:18.453454Z","end":"2026-04-13T17:36:19.698239Z","steps":["trace[233758883] 'process raft request' (duration: 1.244589813s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.698431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.453427Z","time spent":"1.244900901s","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T17:36:19.767861Z","caller":"traceutil/trace.go:171","msg":"trace[1597514702] transaction","detail":"{read_only:false; response_revision:12387; number_of_response:1; }","duration":"466.673956ms","start":"2026-04-13T17:36:19.301157Z","end":"2026-04-13T17:36:19.767831Z","steps":["trace[1597514702] 'process raft request' (duration: 466.541523ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:36:19.767891Z","caller":"traceutil/trace.go:171","msg":"trace[2005512953] linearizableReadLoop","detail":"{readStateIndex:12833; appliedIndex:12830; }","duration":"1.309286923s","start":"2026-04-13T17:36:18.458583Z","end":"2026-04-13T17:36:19.76787Z","steps":["trace[2005512953] 'read index received' (duration: 1.239472907s)","trace[2005512953] 'applied index is now lower than readState.Index' (duration: 69.813226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:36:19.768021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:19.301136Z","time spent":"466.796429ms","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:36:19.768178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296876965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/glance-rabbit-init\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-13T17:36:19.768213Z","caller":"traceutil/trace.go:171","msg":"trace[1290165135] range","detail":"{range_begin:/registry/serviceaccounts/openstack/glance-rabbit-init; range_end:; response_count:1; response_revision:12388; }","duration":"1.29712095s","start":"2026-04-13T17:36:18.471082Z","end":"2026-04-13T17:36:19.768203Z","steps":["trace[1290165135] 'agreement among raft nodes before linearized reading' (duration: 1.296946906s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.471062Z","time spent":"1.297169642s","remote":"127.0.0.1:52648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":562,"request content":"key:\"/registry/serviceaccounts/openstack/glance-rabbit-init\" "} {"level":"warn","ts":"2026-04-13T17:36:19.768248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216621638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-13T17:36:19.768293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20385646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-13T17:36:19.768318Z","caller":"traceutil/trace.go:171","msg":"trace[1250754908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12388; }","duration":"1.21671556s","start":"2026-04-13T17:36:18.551572Z","end":"2026-04-13T17:36:19.768288Z","steps":["trace[1250754908] 'agreement among raft nodes before linearized reading' (duration: 1.216533706s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:36:19.768334Z","caller":"traceutil/trace.go:171","msg":"trace[1066478085] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:12388; }","duration":"1.203895061s","start":"2026-04-13T17:36:18.564425Z","end":"2026-04-13T17:36:19.76832Z","steps":["trace[1066478085] 'agreement among raft nodes before linearized reading' (duration: 1.203771248s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.564388Z","time spent":"1.203976323s","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T17:36:19.768371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255147287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/internal-percona-xtradb\" ","response":"range_response_count:1 size:1556"} {"level":"warn","ts":"2026-04-13T17:36:19.768438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309853737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T17:36:19.76845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216558026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T17:36:19.768453Z","caller":"traceutil/trace.go:171","msg":"trace[173215666] range","detail":"{range_begin:/registry/secrets/openstack/internal-percona-xtradb; range_end:; response_count:1; response_revision:12388; }","duration":"1.25525669s","start":"2026-04-13T17:36:18.513174Z","end":"2026-04-13T17:36:19.768431Z","steps":["trace[173215666] 'agreement among raft nodes before linearized reading' (duration: 1.255003174s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216953646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-13T17:36:19.768485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294907975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-13T17:36:19.768484Z","caller":"traceutil/trace.go:171","msg":"trace[296366967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12388; }","duration":"1.216608688s","start":"2026-04-13T17:36:18.551867Z","end":"2026-04-13T17:36:19.768476Z","steps":["trace[296366967] 'agreement among raft nodes before linearized reading' (duration: 1.216523226s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.513162Z","time spent":"1.255324021s","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":1580,"request content":"key:\"/registry/secrets/openstack/internal-percona-xtradb\" "} {"level":"info","ts":"2026-04-13T17:36:19.768499Z","caller":"traceutil/trace.go:171","msg":"trace[1673440129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12388; }","duration":"1.309922069s","start":"2026-04-13T17:36:18.458549Z","end":"2026-04-13T17:36:19.768471Z","steps":["trace[1673440129] 'agreement among raft nodes before linearized reading' (duration: 1.309440137s)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:36:19.76851Z","caller":"traceutil/trace.go:171","msg":"trace[198476589] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:12388; }","duration":"1.294970507s","start":"2026-04-13T17:36:18.473533Z","end":"2026-04-13T17:36:19.768503Z","steps":["trace[198476589] 'agreement among raft nodes before linearized reading' (duration: 1.294901065s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.551859Z","time spent":"1.216648949s","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T17:36:19.768512Z","caller":"traceutil/trace.go:171","msg":"trace[1526065770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12388; }","duration":"1.217034399s","start":"2026-04-13T17:36:18.55146Z","end":"2026-04-13T17:36:19.768495Z","steps":["trace[1526065770] 'agreement among raft nodes before linearized reading' (duration: 1.216840554s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.473513Z","time spent":"1.295017808s","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-13T17:36:19.768308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.735252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m64jm\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-13T17:36:19.768541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.458531Z","time spent":"1.31000145s","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T17:36:19.768552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.551448Z","time spent":"1.21709663s","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T17:36:19.768372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.551554Z","time spent":"1.216809083s","remote":"127.0.0.1:52702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T17:36:19.768576Z","caller":"traceutil/trace.go:171","msg":"trace[913894248] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m64jm; range_end:; response_count:1; response_revision:12388; }","duration":"821.06597ms","start":"2026-04-13T17:36:18.947496Z","end":"2026-04-13T17:36:19.768562Z","steps":["trace[913894248] 'agreement among raft nodes before linearized reading' (duration: 820.559008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.898262ms","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":"warn","ts":"2026-04-13T17:36:19.768655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.717152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:36:19.768657Z","caller":"traceutil/trace.go:171","msg":"trace[695427510] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:12388; }","duration":"505.976284ms","start":"2026-04-13T17:36:19.262671Z","end":"2026-04-13T17:36:19.768647Z","steps":["trace[695427510] 'agreement among raft nodes before linearized reading' (duration: 505.904162ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:36:19.768681Z","caller":"traceutil/trace.go:171","msg":"trace[386857803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12388; }","duration":"627.760753ms","start":"2026-04-13T17:36:19.140912Z","end":"2026-04-13T17:36:19.768673Z","steps":["trace[386857803] 'agreement among raft nodes before linearized reading' (duration: 627.718882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:19.262656Z","time spent":"506.024495ms","remote":"127.0.0.1:52520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-13T17:36:19.768708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:19.140902Z","time spent":"627.800945ms","remote":"127.0.0.1:52438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T17:36:19.768722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.120766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T17:36:19.768736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.586961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"warn","ts":"2026-04-13T17:36:19.768623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:18.947477Z","time spent":"821.130432ms","remote":"127.0.0.1:52638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-m64jm\" "} {"level":"info","ts":"2026-04-13T17:36:19.768765Z","caller":"traceutil/trace.go:171","msg":"trace[740991187] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:12388; }","duration":"290.194098ms","start":"2026-04-13T17:36:19.47856Z","end":"2026-04-13T17:36:19.768754Z","steps":["trace[740991187] 'agreement among raft nodes before linearized reading' (duration: 290.086754ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:36:19.768768Z","caller":"traceutil/trace.go:171","msg":"trace[1556082110] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:12388; }","duration":"732.653393ms","start":"2026-04-13T17:36:19.036105Z","end":"2026-04-13T17:36:19.768759Z","steps":["trace[1556082110] 'agreement among raft nodes before linearized reading' (duration: 732.549969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:36:19.768796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:36:19.03609Z","time spent":"732.701114ms","remote":"127.0.0.1:52686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5153,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "}