{"level":"warn","ts":"2026-07-28T09:51:57.398743Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T09:51:57.398833Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.76: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.76:2380","--initial-cluster=instance=https://199.19.213.76:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.76:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.76:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-28T09:51:57.398906Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T09:51:57.398923Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.76:2380"]} {"level":"info","ts":"2026-07-28T09:51:57.398949Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-28T09:51:57.399758Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.76:2379"]} {"level":"info","ts":"2026-07-28T09:51:57.39986Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.76:2380"],"listen-peer-urls":["https://199.19.213.76:2380"],"advertise-client-urls":["https://199.19.213.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.76:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-28T09:51:57.404583Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.479097ms"} {"level":"info","ts":"2026-07-28T09:51:57.413566Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f66825837990484a","cluster-id":"3de51efb4d16c986"} {"level":"info","ts":"2026-07-28T09:51:57.413641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a switched to configuration voters=()"} {"level":"info","ts":"2026-07-28T09:51:57.413664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became follower at term 0"} {"level":"info","ts":"2026-07-28T09:51:57.413931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f66825837990484a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-28T09:51:57.413943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became follower at term 1"} {"level":"info","ts":"2026-07-28T09:51:57.413979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a switched to configuration voters=(17755482777518622794)"} {"level":"warn","ts":"2026-07-28T09:51:57.421866Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-28T09:51:57.422966Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-28T09:51:57.424023Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-28T09:51:57.426174Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f66825837990484a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-28T09:51:57.426662Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-28T09:51:57.426743Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-28T09:51:57.42676Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-28T09:51:57.426755Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f66825837990484a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-28T09:51:57.427592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a switched to configuration voters=(17755482777518622794)"} {"level":"info","ts":"2026-07-28T09:51:57.429816Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3de51efb4d16c986","local-member-id":"f66825837990484a","added-peer-id":"f66825837990484a","added-peer-peer-urls":["https://199.19.213.76:2380"]} {"level":"info","ts":"2026-07-28T09:51:57.431355Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-28T09:51:57.431451Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.76:2380"} {"level":"info","ts":"2026-07-28T09:51:57.431482Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.76:2380"} {"level":"info","ts":"2026-07-28T09:51:57.432492Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-28T09:51:57.432446Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f66825837990484a","initial-advertise-peer-urls":["https://199.19.213.76:2380"],"listen-peer-urls":["https://199.19.213.76:2380"],"advertise-client-urls":["https://199.19.213.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-28T09:51:57.514405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a is starting a new election at term 1"} {"level":"info","ts":"2026-07-28T09:51:57.514468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-28T09:51:57.514506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a received MsgPreVoteResp from f66825837990484a at term 1"} {"level":"info","ts":"2026-07-28T09:51:57.51453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became candidate at term 2"} {"level":"info","ts":"2026-07-28T09:51:57.514545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a received MsgVoteResp from f66825837990484a at term 2"} {"level":"info","ts":"2026-07-28T09:51:57.514561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f66825837990484a became leader at term 2"} {"level":"info","ts":"2026-07-28T09:51:57.514577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f66825837990484a elected leader f66825837990484a at term 2"} {"level":"info","ts":"2026-07-28T09:51:57.51594Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f66825837990484a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.76:2379]}","request-path":"/0/members/f66825837990484a/attributes","cluster-id":"3de51efb4d16c986","publish-timeout":"7s"} {"level":"info","ts":"2026-07-28T09:51:57.515985Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T09:51:57.516079Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T09:51:57.516093Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:51:57.516216Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-28T09:51:57.516282Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-28T09:51:57.516892Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3de51efb4d16c986","local-member-id":"f66825837990484a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:51:57.517012Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:51:57.51706Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:51:57.519314Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-28T09:51:57.519614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.76:2379"} {"level":"warn","ts":"2026-07-28T09:52:08.251155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.672243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-28T09:52:08.251276Z","caller":"traceutil/trace.go:171","msg":"trace[785134010] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:302; }","duration":"199.84703ms","start":"2026-07-28T09:52:08.05141Z","end":"2026-07-28T09:52:08.251257Z","steps":["trace[785134010] 'range keys from in-memory index tree' (duration: 199.482627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.251332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.516656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-07-28T09:52:08.251404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.487342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-28T09:52:08.251427Z","caller":"traceutil/trace.go:171","msg":"trace[1560546934] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:302; }","duration":"451.530694ms","start":"2026-07-28T09:52:07.79989Z","end":"2026-07-28T09:52:08.251421Z","steps":["trace[1560546934] 'range keys from in-memory index tree' (duration: 451.440841ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:08.251421Z","caller":"traceutil/trace.go:171","msg":"trace[428383600] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:302; }","duration":"150.667661ms","start":"2026-07-28T09:52:08.100732Z","end":"2026-07-28T09:52:08.2514Z","steps":["trace[428383600] 'range keys from in-memory index tree' (duration: 150.34289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.251448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:07.799881Z","time spent":"451.560734ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-07-28T09:52:08.251572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.835497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-28T09:52:08.251599Z","caller":"traceutil/trace.go:171","msg":"trace[127162179] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:302; }","duration":"400.901299ms","start":"2026-07-28T09:52:07.850689Z","end":"2026-07-28T09:52:08.25159Z","steps":["trace[127162179] 'range keys from in-memory index tree' (duration: 400.719583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.251663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:07.850662Z","time spent":"400.993162ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-28T09:52:08.251762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.520023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T09:52:08.251743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.646124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-28T09:52:08.251816Z","caller":"traceutil/trace.go:171","msg":"trace[1658521049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:302; }","duration":"388.588505ms","start":"2026-07-28T09:52:07.863214Z","end":"2026-07-28T09:52:08.251803Z","steps":["trace[1658521049] 'range keys from in-memory index tree' (duration: 388.45689ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:08.251831Z","caller":"traceutil/trace.go:171","msg":"trace[63707903] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:302; }","duration":"100.846561ms","start":"2026-07-28T09:52:08.150965Z","end":"2026-07-28T09:52:08.251812Z","steps":["trace[63707903] 'range keys from in-memory index tree' (duration: 100.509329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.25185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:07.8632Z","time spent":"388.634276ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:52:08.252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.959602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-28T09:52:08.25206Z","caller":"traceutil/trace.go:171","msg":"trace[1754236983] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:302; }","duration":"352.043485ms","start":"2026-07-28T09:52:07.899998Z","end":"2026-07-28T09:52:08.252041Z","steps":["trace[1754236983] 'range keys from in-memory index tree' (duration: 351.829018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.252104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:07.899983Z","time spent":"352.107017ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-07-28T09:52:08.252153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.455616ms","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-07-28T09:52:08.252185Z","caller":"traceutil/trace.go:171","msg":"trace[1171485265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:302; }","duration":"250.56185ms","start":"2026-07-28T09:52:08.001615Z","end":"2026-07-28T09:52:08.252176Z","steps":["trace[1171485265] 'range keys from in-memory index tree' (duration: 250.376423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.252218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.141181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-07-28T09:52:08.252321Z","caller":"traceutil/trace.go:171","msg":"trace[1056749770] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:302; }","duration":"301.312188ms","start":"2026-07-28T09:52:07.950993Z","end":"2026-07-28T09:52:08.252305Z","steps":["trace[1056749770] 'range keys from in-memory index tree' (duration: 301.021608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.25242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:07.950969Z","time spent":"301.40566ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-07-28T09:52:08.566217Z","caller":"traceutil/trace.go:171","msg":"trace[288837965] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"309.641558ms","start":"2026-07-28T09:52:08.256555Z","end":"2026-07-28T09:52:08.566197Z","steps":["trace[288837965] 'read index received' (duration: 309.544445ms)","trace[288837965] 'applied index is now lower than readState.Index' (duration: 95.863µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:52:08.566742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.168576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-07-28T09:52:08.566811Z","caller":"traceutil/trace.go:171","msg":"trace[1162134984] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:303; }","duration":"310.264549ms","start":"2026-07-28T09:52:08.256536Z","end":"2026-07-28T09:52:08.566801Z","steps":["trace[1162134984] 'agreement among raft nodes before linearized reading' (duration: 309.756872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.566837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.256511Z","time spent":"310.316981ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-28T09:52:08.567106Z","caller":"traceutil/trace.go:171","msg":"trace[712318739] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"309.816344ms","start":"2026-07-28T09:52:08.256439Z","end":"2026-07-28T09:52:08.566256Z","steps":["trace[712318739] 'process raft request' (duration: 309.648908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:08.568668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.256425Z","time spent":"310.88088ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:52:09.010506Z","caller":"traceutil/trace.go:171","msg":"trace[1195010149] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"750.798406ms","start":"2026-07-28T09:52:08.259664Z","end":"2026-07-28T09:52:09.010463Z","steps":["trace[1195010149] 'process raft request' (duration: 750.60321ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:09.010612Z","caller":"traceutil/trace.go:171","msg":"trace[942052937] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:314; }","duration":"444.292582ms","start":"2026-07-28T09:52:08.566271Z","end":"2026-07-28T09:52:09.010563Z","steps":["trace[942052937] 'read index received' (duration: 443.93076ms)","trace[942052937] 'applied index is now lower than readState.Index' (duration: 360.632µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:52:09.010713Z","caller":"traceutil/trace.go:171","msg":"trace[1514853804] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"748.50966ms","start":"2026-07-28T09:52:08.262185Z","end":"2026-07-28T09:52:09.010694Z","steps":["trace[1514853804] 'process raft request' (duration: 748.249562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:09.01086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.409522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-07-28T09:52:09.010698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.259638Z","time spent":"750.983533ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T09:52:09.010907Z","caller":"traceutil/trace.go:171","msg":"trace[1874832834] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:308; }","duration":"509.539307ms","start":"2026-07-28T09:52:08.501357Z","end":"2026-07-28T09:52:09.010896Z","steps":["trace[1874832834] 'agreement among raft nodes before linearized reading' (duration: 509.377571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:09.010886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.641238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-07-28T09:52:09.010942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.262173Z","time spent":"748.642344ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:52:09.010996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.549201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-28T09:52:09.011006Z","caller":"traceutil/trace.go:171","msg":"trace[782639404] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:308; }","duration":"457.841103ms","start":"2026-07-28T09:52:08.553136Z","end":"2026-07-28T09:52:09.010978Z","steps":["trace[782639404] 'agreement among raft nodes before linearized reading' (duration: 457.529083ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:09.011031Z","caller":"traceutil/trace.go:171","msg":"trace[1047865356] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:308; }","duration":"709.676846ms","start":"2026-07-28T09:52:08.301345Z","end":"2026-07-28T09:52:09.011022Z","steps":["trace[1047865356] 'agreement among raft nodes before linearized reading' (duration: 709.623984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:09.011059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.301322Z","time spent":"709.730747ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-07-28T09:52:09.011068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.55311Z","time spent":"457.946227ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-28T09:52:09.011056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.834665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-07-28T09:52:09.010945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.501332Z","time spent":"509.604359ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-07-28T09:52:09.011253Z","caller":"traceutil/trace.go:171","msg":"trace[419140234] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"748.431977ms","start":"2026-07-28T09:52:08.262808Z","end":"2026-07-28T09:52:09.01124Z","steps":["trace[419140234] 'process raft request' (duration: 747.680482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:09.011333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.358031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-28T09:52:09.011374Z","caller":"traceutil/trace.go:171","msg":"trace[1639456314] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:308; }","duration":"410.438914ms","start":"2026-07-28T09:52:08.600925Z","end":"2026-07-28T09:52:09.011364Z","steps":["trace[1639456314] 'agreement among raft nodes before linearized reading' (duration: 410.371922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:09.011375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.262797Z","time spent":"748.497309ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:52:09.011402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.600898Z","time spent":"410.498386ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-28T09:52:09.011374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.423198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-28T09:52:09.011464Z","caller":"traceutil/trace.go:171","msg":"trace[1999523190] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:308; }","duration":"559.604745ms","start":"2026-07-28T09:52:08.451838Z","end":"2026-07-28T09:52:09.011443Z","steps":["trace[1999523190] 'agreement among raft nodes before linearized reading' (duration: 559.44586ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:09.011522Z","caller":"traceutil/trace.go:171","msg":"trace[2053394986] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"748.558011ms","start":"2026-07-28T09:52:08.262953Z","end":"2026-07-28T09:52:09.011511Z","steps":["trace[2053394986] 'process raft request' (duration: 747.570088ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:09.011215Z","caller":"traceutil/trace.go:171","msg":"trace[2069604763] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:308; }","duration":"660.00277ms","start":"2026-07-28T09:52:08.351152Z","end":"2026-07-28T09:52:09.011155Z","steps":["trace[2069604763] 'agreement among raft nodes before linearized reading' (duration: 659.745292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:09.011561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.655292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-07-28T09:52:09.011602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.262947Z","time spent":"748.597612ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:52:09.011598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.451787Z","time spent":"559.759849ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-07-28T09:52:09.011607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.351131Z","time spent":"660.460566ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-07-28T09:52:09.011627Z","caller":"traceutil/trace.go:171","msg":"trace[1529852274] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:308; }","duration":"609.761495ms","start":"2026-07-28T09:52:08.40185Z","end":"2026-07-28T09:52:09.011611Z","steps":["trace[1529852274] 'agreement among raft nodes before linearized reading' (duration: 609.654252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:09.011679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.401827Z","time spent":"609.843178ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-07-28T09:52:09.01063Z","caller":"traceutil/trace.go:171","msg":"trace[186099976] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"750.554048ms","start":"2026-07-28T09:52:08.25999Z","end":"2026-07-28T09:52:09.010544Z","steps":["trace[186099976] 'process raft request' (duration: 750.399853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:09.011847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:08.259978Z","time spent":"751.83764ms","remote":"127.0.0.1:34588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T09:52:44.804014Z","caller":"traceutil/trace.go:171","msg":"trace[1878000462] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"234.262186ms","start":"2026-07-28T09:52:44.569713Z","end":"2026-07-28T09:52:44.803975Z","steps":["trace[1878000462] 'process raft request' (duration: 229.425458ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:44.824296Z","caller":"traceutil/trace.go:171","msg":"trace[1720476443] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"189.54008ms","start":"2026-07-28T09:52:44.634706Z","end":"2026-07-28T09:52:44.824246Z","steps":["trace[1720476443] 'process raft request' (duration: 189.370066ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:44.824615Z","caller":"traceutil/trace.go:171","msg":"trace[1226102643] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"216.085155ms","start":"2026-07-28T09:52:44.608487Z","end":"2026-07-28T09:52:44.824573Z","steps":["trace[1226102643] 'process raft request' (duration: 215.312376ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:48.228693Z","caller":"traceutil/trace.go:171","msg":"trace[394539414] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"109.055478ms","start":"2026-07-28T09:52:48.119607Z","end":"2026-07-28T09:52:48.228663Z","steps":["trace[394539414] 'process raft request' (duration: 108.411813ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:50.777292Z","caller":"traceutil/trace.go:171","msg":"trace[1547857230] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"182.820301ms","start":"2026-07-28T09:52:50.594443Z","end":"2026-07-28T09:52:50.777263Z","steps":["trace[1547857230] 'process raft request' (duration: 182.190257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:51.561235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.905733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:52:51.561336Z","caller":"traceutil/trace.go:171","msg":"trace[434494925] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"710.962151ms","start":"2026-07-28T09:52:50.850357Z","end":"2026-07-28T09:52:51.56132Z","steps":["trace[434494925] 'read index received' (duration: 217.770032ms)","trace[434494925] 'applied index is now lower than readState.Index' (duration: 493.190999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:52:51.561556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.838469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T09:52:51.561548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.173996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-28T09:52:51.561587Z","caller":"traceutil/trace.go:171","msg":"trace[365651556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"673.900151ms","start":"2026-07-28T09:52:50.887677Z","end":"2026-07-28T09:52:51.561577Z","steps":["trace[365651556] 'agreement among raft nodes before linearized reading' (duration: 673.752128ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:51.561614Z","caller":"traceutil/trace.go:171","msg":"trace[299005354] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:617; }","duration":"711.267909ms","start":"2026-07-28T09:52:50.85033Z","end":"2026-07-28T09:52:51.561598Z","steps":["trace[299005354] 'agreement among raft nodes before linearized reading' (duration: 711.094745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:51.561618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:50.88766Z","time spent":"673.950422ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T09:52:51.561596Z","caller":"traceutil/trace.go:171","msg":"trace[269927641] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"727.697289ms","start":"2026-07-28T09:52:50.833778Z","end":"2026-07-28T09:52:51.561475Z","steps":["trace[269927641] 'process raft request' (duration: 234.359466ms)","trace[269927641] 'compare' (duration: 492.734209ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:52:51.561649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:50.850321Z","time spent":"711.32062ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-28T09:52:51.561766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:50.833764Z","time spent":"727.918594ms","remote":"127.0.0.1:34860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:52:52.613248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.482334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T09:52:52.613417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.66109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-28T09:52:52.613344Z","caller":"traceutil/trace.go:171","msg":"trace[312471155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"859.629607ms","start":"2026-07-28T09:52:51.753691Z","end":"2026-07-28T09:52:52.613321Z","steps":["trace[312471155] 'range keys from in-memory index tree' (duration: 859.382962ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:52.613476Z","caller":"traceutil/trace.go:171","msg":"trace[1802696149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"787.757252ms","start":"2026-07-28T09:52:51.825701Z","end":"2026-07-28T09:52:52.613458Z","steps":["trace[1802696149] 'range keys from in-memory index tree' (duration: 787.487095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:52.613517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:51.825681Z","time spent":"787.827113ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T09:52:52.613519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.409918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-28T09:52:52.613556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:51.75367Z","time spent":"859.806572ms","remote":"127.0.0.1:34488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T09:52:52.613571Z","caller":"traceutil/trace.go:171","msg":"trace[458696650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"894.49377ms","start":"2026-07-28T09:52:51.719062Z","end":"2026-07-28T09:52:52.613556Z","steps":["trace[458696650] 'range keys from in-memory index tree' (duration: 894.270165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:52.613618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:51.719048Z","time spent":"894.558622ms","remote":"127.0.0.1:34772","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-07-28T09:52:52.613537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.673453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T09:52:52.613757Z","caller":"traceutil/trace.go:171","msg":"trace[1130546824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"921.011991ms","start":"2026-07-28T09:52:51.692726Z","end":"2026-07-28T09:52:52.613738Z","steps":["trace[1130546824] 'range keys from in-memory index tree' (duration: 920.362056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:52.613845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:51.692705Z","time spent":"921.126134ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T09:52:53.108495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.121441657s","expected-duration":"1s"} {"level":"info","ts":"2026-07-28T09:52:53.113707Z","caller":"traceutil/trace.go:171","msg":"trace[1002846141] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"498.196163ms","start":"2026-07-28T09:52:52.615425Z","end":"2026-07-28T09:52:53.113621Z","steps":["trace[1002846141] 'read index received' (duration: 493.448785ms)","trace[1002846141] 'applied index is now lower than readState.Index' (duration: 4.745648ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:52:53.113705Z","caller":"traceutil/trace.go:171","msg":"trace[665684256] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.127403513s","start":"2026-07-28T09:52:51.986256Z","end":"2026-07-28T09:52:53.113659Z","steps":["trace[665684256] 'process raft request' (duration: 1.122798698s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:53.1148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.404717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:52:53.114865Z","caller":"traceutil/trace.go:171","msg":"trace[795528981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"499.458062ms","start":"2026-07-28T09:52:52.615389Z","end":"2026-07-28T09:52:53.114848Z","steps":["trace[795528981] 'agreement among raft nodes before linearized reading' (duration: 498.323736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:53.11491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:52.615373Z","time spent":"499.525743ms","remote":"127.0.0.1:34480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:52:53.114923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:51.986236Z","time spent":"1.127637848s","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:52:53.391012Z","caller":"traceutil/trace.go:171","msg":"trace[236302621] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"771.979268ms","start":"2026-07-28T09:52:52.619007Z","end":"2026-07-28T09:52:53.390987Z","steps":["trace[236302621] 'process raft request' (duration: 771.809494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:53.39118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:52.618989Z","time spent":"772.09492ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:52:53.391463Z","caller":"traceutil/trace.go:171","msg":"trace[1272927280] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"772.354466ms","start":"2026-07-28T09:52:52.619084Z","end":"2026-07-28T09:52:53.391438Z","steps":["trace[1272927280] 'process raft request' (duration: 771.865745ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:52:53.391602Z","caller":"traceutil/trace.go:171","msg":"trace[1377331119] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"772.405307ms","start":"2026-07-28T09:52:52.619161Z","end":"2026-07-28T09:52:53.391566Z","steps":["trace[1377331119] 'process raft request' (duration: 772.081409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:53.391652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.680306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:52:53.391707Z","caller":"traceutil/trace.go:171","msg":"trace[1949851032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"263.794549ms","start":"2026-07-28T09:52:53.127899Z","end":"2026-07-28T09:52:53.391694Z","steps":["trace[1949851032] 'agreement among raft nodes before linearized reading' (duration: 263.675256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:52:53.391757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:52.619152Z","time spent":"772.530289ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:52:53.391501Z","caller":"traceutil/trace.go:171","msg":"trace[147600836] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:647; }","duration":"263.524152ms","start":"2026-07-28T09:52:53.127931Z","end":"2026-07-28T09:52:53.391456Z","steps":["trace[147600836] 'read index received' (duration: 262.892948ms)","trace[147600836] 'applied index is now lower than readState.Index' (duration: 629.574µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:52:53.391979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:52:52.61906Z","time spent":"772.810727ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:52:53.406094Z","caller":"traceutil/trace.go:171","msg":"trace[787823473] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"239.411245ms","start":"2026-07-28T09:52:53.166671Z","end":"2026-07-28T09:52:53.406082Z","steps":["trace[787823473] 'process raft request' (duration: 239.149899ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:53:05.898241Z","caller":"traceutil/trace.go:171","msg":"trace[643230338] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"261.967222ms","start":"2026-07-28T09:53:05.63623Z","end":"2026-07-28T09:53:05.898197Z","steps":["trace[643230338] 'process raft request' (duration: 261.728307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:10.02708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209151463503949008,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T09:53:10.028982Z","caller":"traceutil/trace.go:171","msg":"trace[1875303932] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"502.708407ms","start":"2026-07-28T09:53:09.526253Z","end":"2026-07-28T09:53:10.028961Z","steps":["trace[1875303932] 'read index received' (duration: 502.599974ms)","trace[1875303932] 'applied index is now lower than readState.Index' (duration: 107.363µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:53:10.029109Z","caller":"traceutil/trace.go:171","msg":"trace[1251595999] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"607.23494ms","start":"2026-07-28T09:53:09.421827Z","end":"2026-07-28T09:53:10.029062Z","steps":["trace[1251595999] 'process raft request' (duration: 606.992965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:10.029345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.065984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-28T09:53:10.029355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:53:09.42179Z","time spent":"607.423694ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:53:10.029416Z","caller":"traceutil/trace.go:171","msg":"trace[882610719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"503.183686ms","start":"2026-07-28T09:53:09.526221Z","end":"2026-07-28T09:53:10.029405Z","steps":["trace[882610719] 'agreement among raft nodes before linearized reading' (duration: 502.973281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:10.029431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.178003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-28T09:53:10.029449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:53:09.526197Z","time spent":"503.243607ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-28T09:53:10.029487Z","caller":"traceutil/trace.go:171","msg":"trace[648336893] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:785; }","duration":"447.289165ms","start":"2026-07-28T09:53:09.582183Z","end":"2026-07-28T09:53:10.029472Z","steps":["trace[648336893] 'agreement among raft nodes before linearized reading' (duration: 447.145152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:10.029523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:53:09.582154Z","time spent":"447.361777ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:53:10.029772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.919416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T09:53:10.029803Z","caller":"traceutil/trace.go:171","msg":"trace[521375558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"443.013428ms","start":"2026-07-28T09:53:09.586782Z","end":"2026-07-28T09:53:10.029795Z","steps":["trace[521375558] 'agreement among raft nodes before linearized reading' (duration: 442.884635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:10.029823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:53:09.586736Z","time spent":"443.081689ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T09:53:10.029805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.345594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T09:53:10.02993Z","caller":"traceutil/trace.go:171","msg":"trace[1096481719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"441.514577ms","start":"2026-07-28T09:53:09.588386Z","end":"2026-07-28T09:53:10.0299Z","steps":["trace[1096481719] 'agreement among raft nodes before linearized reading' (duration: 441.136799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:10.029992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:53:09.588374Z","time spent":"441.607849ms","remote":"127.0.0.1:34772","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-07-28T09:53:10.030791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.105877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T09:53:10.031626Z","caller":"traceutil/trace.go:171","msg":"trace[1826929426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"118.27023ms","start":"2026-07-28T09:53:09.913326Z","end":"2026-07-28T09:53:10.031596Z","steps":["trace[1826929426] 'agreement among raft nodes before linearized reading' (duration: 116.27215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:56.387764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.792131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-28T09:53:56.387857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.943433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-28T09:53:56.387878Z","caller":"traceutil/trace.go:171","msg":"trace[1482373454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"109.921413ms","start":"2026-07-28T09:53:56.277936Z","end":"2026-07-28T09:53:56.387857Z","steps":["trace[1482373454] 'range keys from in-memory index tree' (duration: 109.640279ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:53:56.387908Z","caller":"traceutil/trace.go:171","msg":"trace[177672067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"118.037515ms","start":"2026-07-28T09:53:56.269855Z","end":"2026-07-28T09:53:56.387892Z","steps":["trace[177672067] 'range keys from in-memory index tree' (duration: 117.790861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:56.38794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.909603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-28T09:53:56.388022Z","caller":"traceutil/trace.go:171","msg":"trace[1846291533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"110.033285ms","start":"2026-07-28T09:53:56.277972Z","end":"2026-07-28T09:53:56.388005Z","steps":["trace[1846291533] 'range keys from in-memory index tree' (duration: 109.75023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:53:56.388002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.947863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-28T09:53:56.388084Z","caller":"traceutil/trace.go:171","msg":"trace[195698620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"110.086766ms","start":"2026-07-28T09:53:56.277978Z","end":"2026-07-28T09:53:56.388065Z","steps":["trace[195698620] 'range keys from in-memory index tree' (duration: 109.74322ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:09.894114Z","caller":"traceutil/trace.go:171","msg":"trace[1885978862] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1548; }","duration":"119.073368ms","start":"2026-07-28T09:54:09.775015Z","end":"2026-07-28T09:54:09.894089Z","steps":["trace[1885978862] 'process raft request' (duration: 118.953906ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:10.051202Z","caller":"traceutil/trace.go:171","msg":"trace[469611100] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1548; }","duration":"139.788556ms","start":"2026-07-28T09:54:09.911379Z","end":"2026-07-28T09:54:10.051168Z","steps":["trace[469611100] 'process raft request' (duration: 139.039414ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:28.149414Z","caller":"traceutil/trace.go:171","msg":"trace[1722547783] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"181.890867ms","start":"2026-07-28T09:54:27.967474Z","end":"2026-07-28T09:54:28.149365Z","steps":["trace[1722547783] 'read index received' (duration: 181.710745ms)","trace[1722547783] 'applied index is now lower than readState.Index' (duration: 178.842µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:54:28.149567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.07158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:54:28.149644Z","caller":"traceutil/trace.go:171","msg":"trace[145092020] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1860; }","duration":"182.207802ms","start":"2026-07-28T09:54:27.967417Z","end":"2026-07-28T09:54:28.149625Z","steps":["trace[145092020] 'agreement among raft nodes before linearized reading' (duration: 182.08492ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:28.149848Z","caller":"traceutil/trace.go:171","msg":"trace[185173745] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"330.103663ms","start":"2026-07-28T09:54:27.819721Z","end":"2026-07-28T09:54:28.149824Z","steps":["trace[185173745] 'process raft request' (duration: 329.450383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:28.149986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:27.819706Z","time spent":"330.203754ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10969,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:54:28.788521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.041198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:54:28.788747Z","caller":"traceutil/trace.go:171","msg":"trace[342945710] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1935; }","duration":"632.816089ms","start":"2026-07-28T09:54:28.155907Z","end":"2026-07-28T09:54:28.788723Z","steps":["trace[342945710] 'read index received' (duration: 397.415296ms)","trace[342945710] 'applied index is now lower than readState.Index' (duration: 235.399813ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:54:28.789153Z","caller":"traceutil/trace.go:171","msg":"trace[1804726529] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"678.840746ms","start":"2026-07-28T09:54:28.110283Z","end":"2026-07-28T09:54:28.789124Z","steps":["trace[1804726529] 'process raft request' (duration: 678.364589ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:28.789205Z","caller":"traceutil/trace.go:171","msg":"trace[576055636] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"684.152133ms","start":"2026-07-28T09:54:28.105016Z","end":"2026-07-28T09:54:28.789168Z","steps":["trace[576055636] 'process raft request' (duration: 448.350144ms)","trace[576055636] 'compare' (duration: 234.876726ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:54:28.789278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.356477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-804b30e8-52ad-4ed7-bc2a-3d24dfb6394c\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-07-28T09:54:28.789587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.104992Z","time spent":"684.424948ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:54:28.789682Z","caller":"traceutil/trace.go:171","msg":"trace[12068132] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-804b30e8-52ad-4ed7-bc2a-3d24dfb6394c; range_end:; response_count:1; response_revision:1862; }","duration":"633.767872ms","start":"2026-07-28T09:54:28.155868Z","end":"2026-07-28T09:54:28.789636Z","steps":["trace[12068132] 'agreement among raft nodes before linearized reading' (duration: 633.267455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:28.789308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.11027Z","time spent":"678.955537ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:54:28.78987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.155852Z","time spent":"633.946856ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-804b30e8-52ad-4ed7-bc2a-3d24dfb6394c\" "} {"level":"info","ts":"2026-07-28T09:54:28.872707Z","caller":"traceutil/trace.go:171","msg":"trace[1225926233] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"716.738303ms","start":"2026-07-28T09:54:28.155918Z","end":"2026-07-28T09:54:28.872656Z","steps":["trace[1225926233] 'process raft request' (duration: 716.473789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:28.873054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.155893Z","time spent":"716.997447ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:54:28.873133Z","caller":"traceutil/trace.go:171","msg":"trace[1457286848] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"716.944306ms","start":"2026-07-28T09:54:28.156157Z","end":"2026-07-28T09:54:28.873102Z","steps":["trace[1457286848] 'process raft request' (duration: 716.395698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:28.873341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.156148Z","time spent":"717.069118ms","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:54:28.873492Z","caller":"traceutil/trace.go:171","msg":"trace[440348351] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"716.441308ms","start":"2026-07-28T09:54:28.157029Z","end":"2026-07-28T09:54:28.873471Z","steps":["trace[440348351] 'process raft request' (duration: 716.270436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:28.8736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.157017Z","time spent":"716.52965ms","remote":"127.0.0.1:34782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:54:28.874387Z","caller":"traceutil/trace.go:171","msg":"trace[1812782395] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"718.080682ms","start":"2026-07-28T09:54:28.156283Z","end":"2026-07-28T09:54:28.874364Z","steps":["trace[1812782395] 'process raft request' (duration: 716.672202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:28.874694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.156264Z","time spent":"718.169434ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:54:29.541727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.521566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:54:29.542053Z","caller":"traceutil/trace.go:171","msg":"trace[257473027] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"745.479897ms","start":"2026-07-28T09:54:28.796529Z","end":"2026-07-28T09:54:29.542009Z","steps":["trace[257473027] 'process raft request' (duration: 745.292414ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:29.54219Z","caller":"traceutil/trace.go:171","msg":"trace[1478334320] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1937; }","duration":"753.368342ms","start":"2026-07-28T09:54:28.7888Z","end":"2026-07-28T09:54:29.542169Z","steps":["trace[1478334320] 'read index received' (duration: 83.380056ms)","trace[1478334320] 'applied index is now lower than readState.Index' (duration: 669.987276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:54:29.542202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.796506Z","time spent":"745.609728ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:54:29.542306Z","caller":"traceutil/trace.go:171","msg":"trace[1704937343] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"644.713914ms","start":"2026-07-28T09:54:28.89758Z","end":"2026-07-28T09:54:29.542294Z","steps":["trace[1704937343] 'process raft request' (duration: 644.341798ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:29.542315Z","caller":"traceutil/trace.go:171","msg":"trace[2137922692] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"953.179832ms","start":"2026-07-28T09:54:28.589109Z","end":"2026-07-28T09:54:29.542288Z","steps":["trace[2137922692] 'process raft request' (duration: 413.027246ms)","trace[2137922692] 'compare' (duration: 538.664664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:54:29.542366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.897556Z","time spent":"644.776386ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:54:29.542458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.589092Z","time spent":"953.295813ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:54:29.542599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.145919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:54:29.542632Z","caller":"traceutil/trace.go:171","msg":"trace[1567529706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1870; }","duration":"787.235701ms","start":"2026-07-28T09:54:28.755386Z","end":"2026-07-28T09:54:29.542622Z","steps":["trace[1567529706] 'agreement among raft nodes before linearized reading' (duration: 787.17953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:29.542659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.755305Z","time spent":"787.346922ms","remote":"127.0.0.1:34488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:54:29.542846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.093612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-804b30e8-52ad-4ed7-bc2a-3d24dfb6394c\" ","response":"range_response_count:1 size:3746"} {"level":"warn","ts":"2026-07-28T09:54:29.542871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011781293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-28T09:54:29.542889Z","caller":"traceutil/trace.go:171","msg":"trace[2123481748] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-804b30e8-52ad-4ed7-bc2a-3d24dfb6394c; range_end:; response_count:1; response_revision:1870; }","duration":"930.174464ms","start":"2026-07-28T09:54:28.612701Z","end":"2026-07-28T09:54:29.542875Z","steps":["trace[2123481748] 'agreement among raft nodes before linearized reading' (duration: 930.102892ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:29.542903Z","caller":"traceutil/trace.go:171","msg":"trace[31300591] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"1.011857785s","start":"2026-07-28T09:54:28.531035Z","end":"2026-07-28T09:54:29.542892Z","steps":["trace[31300591] 'agreement among raft nodes before linearized reading' (duration: 1.011759623s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:29.542902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.796776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T09:54:29.542928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.531005Z","time spent":"1.011917075s","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-28T09:54:29.542961Z","caller":"traceutil/trace.go:171","msg":"trace[869941411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1870; }","duration":"954.899037ms","start":"2026-07-28T09:54:28.588044Z","end":"2026-07-28T09:54:29.542943Z","steps":["trace[869941411] 'agreement among raft nodes before linearized reading' (duration: 954.701284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:29.543009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.58801Z","time spent":"954.980078ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T09:54:29.543038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.192559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-28T09:54:29.543068Z","caller":"traceutil/trace.go:171","msg":"trace[450865101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1870; }","duration":"929.242539ms","start":"2026-07-28T09:54:28.613818Z","end":"2026-07-28T09:54:29.54306Z","steps":["trace[450865101] 'agreement among raft nodes before linearized reading' (duration: 929.129858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:29.543097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.613806Z","time spent":"929.28269ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T09:54:29.543133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.440424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-28T09:54:29.543127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.928138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T09:54:29.543165Z","caller":"traceutil/trace.go:171","msg":"trace[1950775498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1870; }","duration":"192.510505ms","start":"2026-07-28T09:54:29.350646Z","end":"2026-07-28T09:54:29.543157Z","steps":["trace[1950775498] 'agreement among raft nodes before linearized reading' (duration: 192.426503ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:29.543174Z","caller":"traceutil/trace.go:171","msg":"trace[493806805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1870; }","duration":"933.993939ms","start":"2026-07-28T09:54:28.609168Z","end":"2026-07-28T09:54:29.543162Z","steps":["trace[493806805] 'agreement among raft nodes before linearized reading' (duration: 933.834586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:29.543206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.609158Z","time spent":"934.04149ms","remote":"127.0.0.1:34772","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-07-28T09:54:29.542923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.612678Z","time spent":"930.235484ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-804b30e8-52ad-4ed7-bc2a-3d24dfb6394c\" "} {"level":"warn","ts":"2026-07-28T09:54:29.543307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.100245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T09:54:29.543358Z","caller":"traceutil/trace.go:171","msg":"trace[1677952128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1870; }","duration":"939.170775ms","start":"2026-07-28T09:54:28.604172Z","end":"2026-07-28T09:54:29.543343Z","steps":["trace[1677952128] 'agreement among raft nodes before linearized reading' (duration: 939.031033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:29.5434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:28.604161Z","time spent":"939.227956ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T09:54:40.946213Z","caller":"traceutil/trace.go:171","msg":"trace[724297652] linearizableReadLoop","detail":"{readStateIndex:2046; appliedIndex:2045; }","duration":"192.398554ms","start":"2026-07-28T09:54:40.753787Z","end":"2026-07-28T09:54:40.946185Z","steps":["trace[724297652] 'read index received' (duration: 192.310653ms)","trace[724297652] 'applied index is now lower than readState.Index' (duration: 86.451µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:54:40.946709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.908231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:54:40.946789Z","caller":"traceutil/trace.go:171","msg":"trace[1356360886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1967; }","duration":"193.031512ms","start":"2026-07-28T09:54:40.753736Z","end":"2026-07-28T09:54:40.946767Z","steps":["trace[1356360886] 'agreement among raft nodes before linearized reading' (duration: 192.653187ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:40.998877Z","caller":"traceutil/trace.go:171","msg":"trace[863633737] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"162.717689ms","start":"2026-07-28T09:54:40.836143Z","end":"2026-07-28T09:54:40.99886Z","steps":["trace[863633737] 'process raft request' (duration: 162.407185ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:40.99894Z","caller":"traceutil/trace.go:171","msg":"trace[670929677] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"152.790697ms","start":"2026-07-28T09:54:40.846123Z","end":"2026-07-28T09:54:40.998913Z","steps":["trace[670929677] 'process raft request' (duration: 152.682745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:40.999129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.686988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11981"} {"level":"info","ts":"2026-07-28T09:54:40.999163Z","caller":"traceutil/trace.go:171","msg":"trace[1793085064] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1969; }","duration":"127.757408ms","start":"2026-07-28T09:54:40.871398Z","end":"2026-07-28T09:54:40.999155Z","steps":["trace[1793085064] 'agreement among raft nodes before linearized reading' (duration: 127.615046ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:54:44.919974Z","caller":"traceutil/trace.go:171","msg":"trace[1595772785] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"188.420304ms","start":"2026-07-28T09:54:44.731534Z","end":"2026-07-28T09:54:44.919955Z","steps":["trace[1595772785] 'read index received' (duration: 188.196751ms)","trace[1595772785] 'applied index is now lower than readState.Index' (duration: 222.453µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:54:44.920024Z","caller":"traceutil/trace.go:171","msg":"trace[1939044627] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"363.958827ms","start":"2026-07-28T09:54:44.556037Z","end":"2026-07-28T09:54:44.919995Z","steps":["trace[1939044627] 'process raft request' (duration: 363.785184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:44.920187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:54:44.556022Z","time spent":"364.072738ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:54:44.920217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.672716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:54:44.920265Z","caller":"traceutil/trace.go:171","msg":"trace[1623432999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2000; }","duration":"166.782608ms","start":"2026-07-28T09:54:44.753468Z","end":"2026-07-28T09:54:44.920251Z","steps":["trace[1623432999] 'agreement among raft nodes before linearized reading' (duration: 166.707957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:54:44.920744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.213796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-28T09:54:44.920789Z","caller":"traceutil/trace.go:171","msg":"trace[318269173] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2000; }","duration":"189.291747ms","start":"2026-07-28T09:54:44.731479Z","end":"2026-07-28T09:54:44.920771Z","steps":["trace[318269173] 'agreement among raft nodes before linearized reading' (duration: 188.585537ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:01.945912Z","caller":"traceutil/trace.go:171","msg":"trace[1220658236] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2180; }","duration":"127.680728ms","start":"2026-07-28T09:55:01.818204Z","end":"2026-07-28T09:55:01.945885Z","steps":["trace[1220658236] 'read index received' (duration: 52.089037ms)","trace[1220658236] 'applied index is now lower than readState.Index' (duration: 75.590591ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:55:01.946054Z","caller":"traceutil/trace.go:171","msg":"trace[1945183143] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"127.87297ms","start":"2026-07-28T09:55:01.818092Z","end":"2026-07-28T09:55:01.945965Z","steps":["trace[1945183143] 'process raft request' (duration: 71.828888ms)","trace[1945183143] 'compare' (duration: 55.844849ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:55:01.946282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.985262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T09:55:01.946334Z","caller":"traceutil/trace.go:171","msg":"trace[784004527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2099; }","duration":"128.167585ms","start":"2026-07-28T09:55:01.818152Z","end":"2026-07-28T09:55:01.94632Z","steps":["trace[784004527] 'agreement among raft nodes before linearized reading' (duration: 127.883421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:01.94637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.450217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-28T09:55:01.946393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.459857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T09:55:01.946547Z","caller":"traceutil/trace.go:171","msg":"trace[305618315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2099; }","duration":"125.65132ms","start":"2026-07-28T09:55:01.82087Z","end":"2026-07-28T09:55:01.946522Z","steps":["trace[305618315] 'agreement among raft nodes before linearized reading' (duration: 125.328545ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:01.946444Z","caller":"traceutil/trace.go:171","msg":"trace[2116761867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2099; }","duration":"125.561248ms","start":"2026-07-28T09:55:01.820859Z","end":"2026-07-28T09:55:01.946421Z","steps":["trace[2116761867] 'agreement among raft nodes before linearized reading' (duration: 125.330765ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:07.772301Z","caller":"traceutil/trace.go:171","msg":"trace[1454705260] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"329.315416ms","start":"2026-07-28T09:55:07.44295Z","end":"2026-07-28T09:55:07.772265Z","steps":["trace[1454705260] 'process raft request' (duration: 329.143683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:07.772481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:07.442926Z","time spent":"329.466637ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:55:07.824511Z","caller":"traceutil/trace.go:171","msg":"trace[34207475] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"140.451348ms","start":"2026-07-28T09:55:07.684035Z","end":"2026-07-28T09:55:07.824487Z","steps":["trace[34207475] 'process raft request' (duration: 139.92998ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:07.825688Z","caller":"traceutil/trace.go:171","msg":"trace[2007074275] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"137.465686ms","start":"2026-07-28T09:55:07.688019Z","end":"2026-07-28T09:55:07.825484Z","steps":["trace[2007074275] 'process raft request' (duration: 136.064617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:08.188074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.300469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T09:55:08.188171Z","caller":"traceutil/trace.go:171","msg":"trace[905726016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2131; }","duration":"205.445911ms","start":"2026-07-28T09:55:07.982701Z","end":"2026-07-28T09:55:08.188147Z","steps":["trace[905726016] 'range keys from in-memory index tree' (duration: 205.070816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:08.18826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.555593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-28T09:55:08.188256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.049532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-28T09:55:08.188316Z","caller":"traceutil/trace.go:171","msg":"trace[382212541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2131; }","duration":"205.640503ms","start":"2026-07-28T09:55:07.98266Z","end":"2026-07-28T09:55:08.1883Z","steps":["trace[382212541] 'range keys from in-memory index tree' (duration: 205.36363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:08.188308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.683725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T09:55:08.188331Z","caller":"traceutil/trace.go:171","msg":"trace[1667267767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2131; }","duration":"215.182474ms","start":"2026-07-28T09:55:07.973129Z","end":"2026-07-28T09:55:08.188312Z","steps":["trace[1667267767] 'range keys from in-memory index tree' (duration: 214.845169ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:08.188391Z","caller":"traceutil/trace.go:171","msg":"trace[442234943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2131; }","duration":"208.836177ms","start":"2026-07-28T09:55:07.979534Z","end":"2026-07-28T09:55:08.18837Z","steps":["trace[442234943] 'range keys from in-memory index tree' (duration: 208.458912ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:09.341539Z","caller":"traceutil/trace.go:171","msg":"trace[897023366] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"213.073951ms","start":"2026-07-28T09:55:09.12844Z","end":"2026-07-28T09:55:09.341514Z","steps":["trace[897023366] 'process raft request' (duration: 212.913619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:09.965484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.516086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:55:09.966235Z","caller":"traceutil/trace.go:171","msg":"trace[519407370] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"834.859664ms","start":"2026-07-28T09:55:09.13134Z","end":"2026-07-28T09:55:09.9662Z","steps":["trace[519407370] 'process raft request' (duration: 562.469046ms)","trace[519407370] 'compare' (duration: 271.188491ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:55:09.966311Z","caller":"traceutil/trace.go:171","msg":"trace[1589085102] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2220; }","duration":"770.407317ms","start":"2026-07-28T09:55:09.195882Z","end":"2026-07-28T09:55:09.966289Z","steps":["trace[1589085102] 'read index received' (duration: 145.42042ms)","trace[1589085102] 'applied index is now lower than readState.Index' (duration: 624.984317ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:55:09.966365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:09.131303Z","time spent":"834.998216ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:55:09.966563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.67007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-28T09:55:09.966628Z","caller":"traceutil/trace.go:171","msg":"trace[894489185] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2138; }","duration":"770.750241ms","start":"2026-07-28T09:55:09.195864Z","end":"2026-07-28T09:55:09.966614Z","steps":["trace[894489185] 'agreement among raft nodes before linearized reading' (duration: 770.490818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:09.966659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:09.1958Z","time spent":"770.852653ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-28T09:55:09.966236Z","caller":"traceutil/trace.go:171","msg":"trace[956757634] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"135.472294ms","start":"2026-07-28T09:55:09.830743Z","end":"2026-07-28T09:55:09.966215Z","steps":["trace[956757634] 'process raft request' (duration: 135.386313ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:10.320813Z","caller":"traceutil/trace.go:171","msg":"trace[1747712952] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"488.622965ms","start":"2026-07-28T09:55:09.832167Z","end":"2026-07-28T09:55:10.32079Z","steps":["trace[1747712952] 'process raft request' (duration: 488.474642ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:10.320946Z","caller":"traceutil/trace.go:171","msg":"trace[187593947] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2224; }","duration":"354.55397ms","start":"2026-07-28T09:55:09.966365Z","end":"2026-07-28T09:55:10.320919Z","steps":["trace[187593947] 'read index received' (duration: 354.54314ms)","trace[187593947] 'applied index is now lower than readState.Index' (duration: 9.09µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:55:10.321072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.816529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T09:55:10.320973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:09.832147Z","time spent":"488.735207ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:55:10.321119Z","caller":"traceutil/trace.go:171","msg":"trace[76406778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2139; }","duration":"566.896021ms","start":"2026-07-28T09:55:09.754209Z","end":"2026-07-28T09:55:10.321105Z","steps":["trace[76406778] 'agreement among raft nodes before linearized reading' (duration: 566.82457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:10.321159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:09.75419Z","time spent":"566.958571ms","remote":"127.0.0.1:34488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:55:10.321267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.466995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-28T09:55:10.321281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.322898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-28T09:55:10.321342Z","caller":"traceutil/trace.go:171","msg":"trace[1312642689] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2139; }","duration":"526.42749ms","start":"2026-07-28T09:55:09.794899Z","end":"2026-07-28T09:55:10.321327Z","steps":["trace[1312642689] 'agreement among raft nodes before linearized reading' (duration: 526.124806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:10.321423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:09.794882Z","time spent":"526.525871ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":252,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-28T09:55:10.32144Z","caller":"traceutil/trace.go:171","msg":"trace[426429470] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2139; }","duration":"175.585586ms","start":"2026-07-28T09:55:10.145715Z","end":"2026-07-28T09:55:10.321301Z","steps":["trace[426429470] 'agreement among raft nodes before linearized reading' (duration: 175.446455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:10.321456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.125592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T09:55:10.321511Z","caller":"traceutil/trace.go:171","msg":"trace[305008931] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2139; }","duration":"921.187603ms","start":"2026-07-28T09:55:09.400309Z","end":"2026-07-28T09:55:10.321497Z","steps":["trace[305008931] 'agreement among raft nodes before linearized reading' (duration: 921.039181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:10.321556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:09.400263Z","time spent":"921.278995ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":20,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:55:10.322022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.072336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:55:10.32208Z","caller":"traceutil/trace.go:171","msg":"trace[852485207] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2139; }","duration":"662.172997ms","start":"2026-07-28T09:55:09.659896Z","end":"2026-07-28T09:55:10.322069Z","steps":["trace[852485207] 'agreement among raft nodes before linearized reading' (duration: 661.197994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:10.322111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:09.659875Z","time spent":"662.225728ms","remote":"127.0.0.1:34898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-07-28T09:55:10.322534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.62265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T09:55:10.322612Z","caller":"traceutil/trace.go:171","msg":"trace[1334064591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2139; }","duration":"384.732131ms","start":"2026-07-28T09:55:09.937861Z","end":"2026-07-28T09:55:10.322593Z","steps":["trace[1334064591] 'agreement among raft nodes before linearized reading' (duration: 383.243151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:10.322655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:09.937801Z","time spent":"384.842423ms","remote":"127.0.0.1:34772","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-07-28T09:55:10.845351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.207515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:55:10.845686Z","caller":"traceutil/trace.go:171","msg":"trace[1591270901] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2226; }","duration":"115.417509ms","start":"2026-07-28T09:55:10.730256Z","end":"2026-07-28T09:55:10.845674Z","steps":["trace[1591270901] 'read index received' (duration: 59.431µs)","trace[1591270901] 'applied index is now lower than readState.Index' (duration: 115.357058ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:55:10.845739Z","caller":"traceutil/trace.go:171","msg":"trace[2125947600] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"519.055506ms","start":"2026-07-28T09:55:10.326657Z","end":"2026-07-28T09:55:10.845713Z","steps":["trace[2125947600] 'process raft request' (duration: 518.834593ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:10.845833Z","caller":"traceutil/trace.go:171","msg":"trace[372297203] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"519.013915ms","start":"2026-07-28T09:55:10.326738Z","end":"2026-07-28T09:55:10.845752Z","steps":["trace[372297203] 'process raft request' (duration: 518.861013ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:10.845929Z","caller":"traceutil/trace.go:171","msg":"trace[1262735324] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"519.829987ms","start":"2026-07-28T09:55:10.326072Z","end":"2026-07-28T09:55:10.845902Z","steps":["trace[1262735324] 'process raft request' (duration: 48.008733ms)","trace[1262735324] 'compare' (duration: 470.990222ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:55:10.845988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:10.32664Z","time spent":"519.263669ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:55:10.846078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:10.326059Z","time spent":"519.948128ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:55:10.846173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:10.326727Z","time spent":"519.245668ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T09:55:10.8459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.667842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.76\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-28T09:55:10.846494Z","caller":"traceutil/trace.go:171","msg":"trace[260083098] range","detail":"{range_begin:/registry/masterleases/199.19.213.76; range_end:; response_count:1; response_revision:2144; }","duration":"116.329321ms","start":"2026-07-28T09:55:10.730148Z","end":"2026-07-28T09:55:10.846477Z","steps":["trace[260083098] 'agreement among raft nodes before linearized reading' (duration: 115.609222ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:56.099405Z","caller":"traceutil/trace.go:171","msg":"trace[139007357] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"243.622695ms","start":"2026-07-28T09:55:55.855751Z","end":"2026-07-28T09:55:56.099373Z","steps":["trace[139007357] 'process raft request' (duration: 222.367098ms)","trace[139007357] 'compare' (duration: 20.889932ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:55:56.171667Z","caller":"traceutil/trace.go:171","msg":"trace[396182765] linearizableReadLoop","detail":"{readStateIndex:2555; appliedIndex:2552; }","duration":"175.91876ms","start":"2026-07-28T09:55:55.995726Z","end":"2026-07-28T09:55:56.171644Z","steps":["trace[396182765] 'read index received' (duration: 82.465126ms)","trace[396182765] 'applied index is now lower than readState.Index' (duration: 93.452534ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:55:56.171877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.148349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:55:56.171932Z","caller":"traceutil/trace.go:171","msg":"trace[266823428] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2460; }","duration":"176.256424ms","start":"2026-07-28T09:55:55.995666Z","end":"2026-07-28T09:55:56.171923Z","steps":["trace[266823428] 'agreement among raft nodes before linearized reading' (duration: 176.067827ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:56.171928Z","caller":"traceutil/trace.go:171","msg":"trace[467366987] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"316.097472ms","start":"2026-07-28T09:55:55.8558Z","end":"2026-07-28T09:55:56.171898Z","steps":["trace[467366987] 'process raft request' (duration: 315.666985ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:56.1721Z","caller":"traceutil/trace.go:171","msg":"trace[462801542] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"278.57377ms","start":"2026-07-28T09:55:55.893496Z","end":"2026-07-28T09:55:56.17207Z","steps":["trace[462801542] 'process raft request' (duration: 278.08245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:56.172127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:55.855774Z","time spent":"316.222297ms","remote":"127.0.0.1:34692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:55:56.339321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.603534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-28T09:55:56.339587Z","caller":"traceutil/trace.go:171","msg":"trace[2137698137] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"144.457965ms","start":"2026-07-28T09:55:56.195057Z","end":"2026-07-28T09:55:56.339515Z","steps":["trace[2137698137] 'process raft request' (duration: 15.57583ms)","trace[2137698137] 'compare' (duration: 128.328623ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:55:58.322562Z","caller":"traceutil/trace.go:171","msg":"trace[1263794405] transaction","detail":"{read_only:false; response_revision:2504; number_of_response:1; }","duration":"139.649391ms","start":"2026-07-28T09:55:58.182893Z","end":"2026-07-28T09:55:58.322542Z","steps":["trace[1263794405] 'process raft request' (duration: 139.544107ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:58.733036Z","caller":"traceutil/trace.go:171","msg":"trace[1486236934] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"213.296888ms","start":"2026-07-28T09:55:58.519716Z","end":"2026-07-28T09:55:58.733013Z","steps":["trace[1486236934] 'process raft request' (duration: 213.237496ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:58.73318Z","caller":"traceutil/trace.go:171","msg":"trace[55382333] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"550.129231ms","start":"2026-07-28T09:55:58.183004Z","end":"2026-07-28T09:55:58.733133Z","steps":["trace[55382333] 'process raft request' (duration: 487.78555ms)","trace[55382333] 'compare' (duration: 61.920124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:55:58.733361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:55:58.18298Z","time spent":"550.297157ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T09:55:58.733542Z","caller":"traceutil/trace.go:171","msg":"trace[2103812098] linearizableReadLoop","detail":"{readStateIndex:2601; appliedIndex:2600; }","duration":"278.431938ms","start":"2026-07-28T09:55:58.455088Z","end":"2026-07-28T09:55:58.73352Z","steps":["trace[2103812098] 'read index received' (duration: 215.772495ms)","trace[2103812098] 'applied index is now lower than readState.Index' (duration: 62.657543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:55:58.733759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.664427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-28T09:55:58.733799Z","caller":"traceutil/trace.go:171","msg":"trace[735227062] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2506; }","duration":"278.761292ms","start":"2026-07-28T09:55:58.455027Z","end":"2026-07-28T09:55:58.733788Z","steps":["trace[735227062] 'agreement among raft nodes before linearized reading' (duration: 278.594585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:55:58.733809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.023811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-28T09:55:58.733801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.269541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-28T09:55:58.733871Z","caller":"traceutil/trace.go:171","msg":"trace[404148482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2506; }","duration":"132.115744ms","start":"2026-07-28T09:55:58.601738Z","end":"2026-07-28T09:55:58.733854Z","steps":["trace[404148482] 'agreement among raft nodes before linearized reading' (duration: 131.932887ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:55:58.733886Z","caller":"traceutil/trace.go:171","msg":"trace[352032801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2506; }","duration":"131.398946ms","start":"2026-07-28T09:55:58.602473Z","end":"2026-07-28T09:55:58.733872Z","steps":["trace[352032801] 'agreement among raft nodes before linearized reading' (duration: 131.223409ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:56:25.001892Z","caller":"traceutil/trace.go:171","msg":"trace[1719560153] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"105.631025ms","start":"2026-07-28T09:56:24.896169Z","end":"2026-07-28T09:56:25.0018Z","steps":["trace[1719560153] 'process raft request' (duration: 97.059604ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:56:25.009414Z","caller":"traceutil/trace.go:171","msg":"trace[1119631013] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"111.417657ms","start":"2026-07-28T09:56:24.897764Z","end":"2026-07-28T09:56:25.009182Z","steps":["trace[1119631013] 'process raft request' (duration: 111.162148ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:56:25.354855Z","caller":"traceutil/trace.go:171","msg":"trace[852094233] linearizableReadLoop","detail":"{readStateIndex:2757; appliedIndex:2756; }","duration":"260.452725ms","start":"2026-07-28T09:56:25.094382Z","end":"2026-07-28T09:56:25.354834Z","steps":["trace[852094233] 'read index received' (duration: 260.228937ms)","trace[852094233] 'applied index is now lower than readState.Index' (duration: 222.738µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:56:25.354935Z","caller":"traceutil/trace.go:171","msg":"trace[485494660] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"262.817187ms","start":"2026-07-28T09:56:25.092099Z","end":"2026-07-28T09:56:25.354916Z","steps":["trace[485494660] 'process raft request' (duration: 262.591759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:56:25.355301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.893831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T09:56:25.355366Z","caller":"traceutil/trace.go:171","msg":"trace[1620247217] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2655; }","duration":"260.983863ms","start":"2026-07-28T09:56:25.094366Z","end":"2026-07-28T09:56:25.35535Z","steps":["trace[1620247217] 'agreement among raft nodes before linearized reading' (duration: 260.580929ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:56:30.512893Z","caller":"traceutil/trace.go:171","msg":"trace[518999433] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"157.776993ms","start":"2026-07-28T09:56:30.355063Z","end":"2026-07-28T09:56:30.51284Z","steps":["trace[518999433] 'process raft request' (duration: 157.625017ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:56:30.513235Z","caller":"traceutil/trace.go:171","msg":"trace[754294481] linearizableReadLoop","detail":"{readStateIndex:2783; appliedIndex:2783; }","duration":"124.089041ms","start":"2026-07-28T09:56:30.389097Z","end":"2026-07-28T09:56:30.513186Z","steps":["trace[754294481] 'read index received' (duration: 124.079741ms)","trace[754294481] 'applied index is now lower than readState.Index' (duration: 7.13µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:56:30.513534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.420982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T09:56:30.513598Z","caller":"traceutil/trace.go:171","msg":"trace[1933931178] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2680; }","duration":"124.524886ms","start":"2026-07-28T09:56:30.389056Z","end":"2026-07-28T09:56:30.513581Z","steps":["trace[1933931178] 'agreement among raft nodes before linearized reading' (duration: 124.294808ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:56:30.726275Z","caller":"traceutil/trace.go:171","msg":"trace[1459973989] linearizableReadLoop","detail":"{readStateIndex:2784; appliedIndex:2783; }","duration":"212.935608ms","start":"2026-07-28T09:56:30.513311Z","end":"2026-07-28T09:56:30.726247Z","steps":["trace[1459973989] 'read index received' (duration: 140.167301ms)","trace[1459973989] 'applied index is now lower than readState.Index' (duration: 72.767027ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:56:30.726825Z","caller":"traceutil/trace.go:171","msg":"trace[1454327482] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"295.430217ms","start":"2026-07-28T09:56:30.431362Z","end":"2026-07-28T09:56:30.726792Z","steps":["trace[1454327482] 'process raft request' (duration: 222.169734ms)","trace[1454327482] 'compare' (duration: 72.221428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:56:30.726915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.479724ms","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-07-28T09:56:30.726962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.595923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T09:56:30.726984Z","caller":"traceutil/trace.go:171","msg":"trace[1753341721] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2681; }","duration":"272.600467ms","start":"2026-07-28T09:56:30.454366Z","end":"2026-07-28T09:56:30.726966Z","steps":["trace[1753341721] 'agreement among raft nodes before linearized reading' (duration: 272.39368ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:56:30.727009Z","caller":"traceutil/trace.go:171","msg":"trace[222686214] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2681; }","duration":"209.674656ms","start":"2026-07-28T09:56:30.517324Z","end":"2026-07-28T09:56:30.726999Z","steps":["trace[222686214] 'agreement among raft nodes before linearized reading' (duration: 209.516051ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:33.187676Z","caller":"traceutil/trace.go:171","msg":"trace[1462790816] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4708; }","duration":"230.219308ms","start":"2026-07-28T10:00:32.957433Z","end":"2026-07-28T10:00:33.187652Z","steps":["trace[1462790816] 'read index received' (duration: 230.092836ms)","trace[1462790816] 'applied index is now lower than readState.Index' (duration: 125.162µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:00:33.187788Z","caller":"traceutil/trace.go:171","msg":"trace[145829221] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"236.585415ms","start":"2026-07-28T10:00:32.951166Z","end":"2026-07-28T10:00:33.187751Z","steps":["trace[145829221] 'process raft request' (duration: 236.31873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:33.188002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.544374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46287"} {"level":"info","ts":"2026-07-28T10:00:33.188057Z","caller":"traceutil/trace.go:171","msg":"trace[700823908] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4534; }","duration":"230.650756ms","start":"2026-07-28T10:00:32.957392Z","end":"2026-07-28T10:00:33.188043Z","steps":["trace[700823908] 'agreement among raft nodes before linearized reading' (duration: 230.378941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:33.199415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.550286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-28T10:00:33.199478Z","caller":"traceutil/trace.go:171","msg":"trace[1351081696] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4536; }","duration":"241.649389ms","start":"2026-07-28T10:00:32.957814Z","end":"2026-07-28T10:00:33.199463Z","steps":["trace[1351081696] 'agreement among raft nodes before linearized reading' (duration: 241.454225ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:33.199571Z","caller":"traceutil/trace.go:171","msg":"trace[684259181] transaction","detail":"{read_only:false; response_revision:4535; number_of_response:1; }","duration":"241.642538ms","start":"2026-07-28T10:00:32.95789Z","end":"2026-07-28T10:00:33.199532Z","steps":["trace[684259181] 'process raft request' (duration: 241.21118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:33.199626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.212008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:00:33.199701Z","caller":"traceutil/trace.go:171","msg":"trace[970060782] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:4536; }","duration":"179.30713ms","start":"2026-07-28T10:00:33.020378Z","end":"2026-07-28T10:00:33.199685Z","steps":["trace[970060782] 'agreement among raft nodes before linearized reading' (duration: 179.175127ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:39.467293Z","caller":"traceutil/trace.go:171","msg":"trace[1497603533] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"129.570428ms","start":"2026-07-28T10:00:39.337673Z","end":"2026-07-28T10:00:39.467243Z","steps":["trace[1497603533] 'process raft request' (duration: 43.192139ms)","trace[1497603533] 'compare' (duration: 86.090933ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:00:39.467548Z","caller":"traceutil/trace.go:171","msg":"trace[1256697942] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4676; }","duration":"128.171172ms","start":"2026-07-28T10:00:39.339362Z","end":"2026-07-28T10:00:39.467533Z","steps":["trace[1256697942] 'process raft request' (duration: 127.779155ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:39.467642Z","caller":"traceutil/trace.go:171","msg":"trace[431863865] linearizableReadLoop","detail":"{readStateIndex:4857; appliedIndex:4855; }","duration":"108.767458ms","start":"2026-07-28T10:00:39.358729Z","end":"2026-07-28T10:00:39.467497Z","steps":["trace[431863865] 'read index received' (duration: 22.029313ms)","trace[431863865] 'applied index is now lower than readState.Index' (duration: 86.733735ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:00:39.468168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.087957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-28T10:00:39.468225Z","caller":"traceutil/trace.go:171","msg":"trace[678815561] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4676; }","duration":"136.171098ms","start":"2026-07-28T10:00:39.332039Z","end":"2026-07-28T10:00:39.46821Z","steps":["trace[678815561] 'agreement among raft nodes before linearized reading' (duration: 135.893403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:39.468288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.720717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-28T10:00:39.468357Z","caller":"traceutil/trace.go:171","msg":"trace[350896681] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4676; }","duration":"102.83794ms","start":"2026-07-28T10:00:39.365496Z","end":"2026-07-28T10:00:39.468334Z","steps":["trace[350896681] 'agreement among raft nodes before linearized reading' (duration: 102.463663ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:39.592956Z","caller":"traceutil/trace.go:171","msg":"trace[270036879] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"111.383406ms","start":"2026-07-28T10:00:39.48151Z","end":"2026-07-28T10:00:39.592893Z","steps":["trace[270036879] 'process raft request' (duration: 107.415814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:39.593057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.014217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-07-28T10:00:39.593154Z","caller":"traceutil/trace.go:171","msg":"trace[255753530] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4678; }","duration":"112.146209ms","start":"2026-07-28T10:00:39.480984Z","end":"2026-07-28T10:00:39.59313Z","steps":["trace[255753530] 'agreement among raft nodes before linearized reading' (duration: 111.859514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:39.593195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.492021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-28T10:00:39.593418Z","caller":"traceutil/trace.go:171","msg":"trace[502563172] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4678; }","duration":"109.797566ms","start":"2026-07-28T10:00:39.483545Z","end":"2026-07-28T10:00:39.593343Z","steps":["trace[502563172] 'agreement among raft nodes before linearized reading' (duration: 109.357948ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:40.697133Z","caller":"traceutil/trace.go:171","msg":"trace[628207755] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"191.294682ms","start":"2026-07-28T10:00:40.50575Z","end":"2026-07-28T10:00:40.697044Z","steps":["trace[628207755] 'process raft request' (duration: 191.088858ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:40.702101Z","caller":"traceutil/trace.go:171","msg":"trace[1722760937] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4869; }","duration":"104.841344ms","start":"2026-07-28T10:00:40.597217Z","end":"2026-07-28T10:00:40.702058Z","steps":["trace[1722760937] 'read index received' (duration: 99.672699ms)","trace[1722760937] 'applied index is now lower than readState.Index' (duration: 5.167585ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:00:40.702122Z","caller":"traceutil/trace.go:171","msg":"trace[1416014898] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"195.993438ms","start":"2026-07-28T10:00:40.506093Z","end":"2026-07-28T10:00:40.702087Z","steps":["trace[1416014898] 'process raft request' (duration: 195.716833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:40.702544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.285092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T10:00:40.7026Z","caller":"traceutil/trace.go:171","msg":"trace[1490094891] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4691; }","duration":"105.420554ms","start":"2026-07-28T10:00:40.597163Z","end":"2026-07-28T10:00:40.702584Z","steps":["trace[1490094891] 'agreement among raft nodes before linearized reading' (duration: 105.035737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.38606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.887598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-28T10:00:46.386274Z","caller":"traceutil/trace.go:171","msg":"trace[1121503433] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"442.7205ms","start":"2026-07-28T10:00:45.943519Z","end":"2026-07-28T10:00:46.386239Z","steps":["trace[1121503433] 'process raft request' (duration: 50.554787ms)","trace[1121503433] 'compare' (duration: 391.231826ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:00:46.38639Z","caller":"traceutil/trace.go:171","msg":"trace[647804532] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"428.604955ms","start":"2026-07-28T10:00:45.957765Z","end":"2026-07-28T10:00:46.38637Z","steps":["trace[647804532] 'process raft request' (duration: 428.444062ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:46.386379Z","caller":"traceutil/trace.go:171","msg":"trace[861293588] linearizableReadLoop","detail":"{readStateIndex:4946; appliedIndex:4945; }","duration":"428.775027ms","start":"2026-07-28T10:00:45.957561Z","end":"2026-07-28T10:00:46.386336Z","steps":["trace[861293588] 'read index received' (duration: 36.444071ms)","trace[861293588] 'applied index is now lower than readState.Index' (duration: 392.326146ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:00:46.386508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:45.957754Z","time spent":"428.693536ms","remote":"127.0.0.1:34996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9303,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:00:46.386394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:45.943508Z","time spent":"442.839263ms","remote":"127.0.0.1:35014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6111,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T10:00:46.386807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.157195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-28T10:00:46.386865Z","caller":"traceutil/trace.go:171","msg":"trace[630760420] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4765; }","duration":"429.321717ms","start":"2026-07-28T10:00:45.957524Z","end":"2026-07-28T10:00:46.386845Z","steps":["trace[630760420] 'agreement among raft nodes before linearized reading' (duration: 429.011642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.386929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:45.957502Z","time spent":"429.412518ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"info","ts":"2026-07-28T10:00:46.388169Z","caller":"traceutil/trace.go:171","msg":"trace[1912852931] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"370.776695ms","start":"2026-07-28T10:00:46.017319Z","end":"2026-07-28T10:00:46.388095Z","steps":["trace[1912852931] 'process raft request' (duration: 369.904859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.388474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:46.017292Z","time spent":"371.028799ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T10:00:46.388616Z","caller":"traceutil/trace.go:171","msg":"trace[22985590] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"342.544273ms","start":"2026-07-28T10:00:46.046039Z","end":"2026-07-28T10:00:46.388583Z","steps":["trace[22985590] 'process raft request' (duration: 341.346651ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:46.388571Z","caller":"traceutil/trace.go:171","msg":"trace[799694597] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"366.057159ms","start":"2026-07-28T10:00:46.022407Z","end":"2026-07-28T10:00:46.388464Z","steps":["trace[799694597] 'process raft request' (duration: 364.905329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.388774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:46.046009Z","time spent":"342.680435ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:00:46.388807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:46.022367Z","time spent":"366.346204ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:00:46.673586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.116679ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T10:00:46.674118Z","caller":"traceutil/trace.go:171","msg":"trace[873006438] linearizableReadLoop","detail":"{readStateIndex:4952; appliedIndex:4947; }","duration":"287.566605ms","start":"2026-07-28T10:00:46.386493Z","end":"2026-07-28T10:00:46.67406Z","steps":["trace[873006438] 'read index received' (duration: 750.313µs)","trace[873006438] 'applied index is now lower than readState.Index' (duration: 286.814402ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:00:46.674148Z","caller":"traceutil/trace.go:171","msg":"trace[1248477059] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"498.573373ms","start":"2026-07-28T10:00:46.175534Z","end":"2026-07-28T10:00:46.674107Z","steps":["trace[1248477059] 'process raft request' (duration: 498.158185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.674351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:46.175513Z","time spent":"498.729835ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:00:46.849224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.22164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:00:46.84958Z","caller":"traceutil/trace.go:171","msg":"trace[966749546] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4769; }","duration":"888.597827ms","start":"2026-07-28T10:00:45.960955Z","end":"2026-07-28T10:00:46.849553Z","steps":["trace[966749546] 'agreement among raft nodes before linearized reading' (duration: 713.311468ms)","trace[966749546] 'range keys from in-memory index tree' (duration: 174.891862ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:00:46.849794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.560924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T10:00:46.849941Z","caller":"traceutil/trace.go:171","msg":"trace[195911752] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"451.61977ms","start":"2026-07-28T10:00:46.398296Z","end":"2026-07-28T10:00:46.849915Z","steps":["trace[195911752] 'process raft request' (duration: 275.751581ms)","trace[195911752] 'compare' (duration: 175.416161ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:00:46.850031Z","caller":"traceutil/trace.go:171","msg":"trace[813599521] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"123.5183ms","start":"2026-07-28T10:00:46.726494Z","end":"2026-07-28T10:00:46.850013Z","steps":["trace[813599521] 'process raft request' (duration: 123.423848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.850067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:46.398285Z","time spent":"451.715583ms","remote":"127.0.0.1:32914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:00:46.850071Z","caller":"traceutil/trace.go:171","msg":"trace[1032836611] linearizableReadLoop","detail":"{readStateIndex:4953; appliedIndex:4952; }","duration":"175.833979ms","start":"2026-07-28T10:00:46.674208Z","end":"2026-07-28T10:00:46.850042Z","steps":["trace[1032836611] 'read index received' (duration: 81.161µs)","trace[1032836611] 'applied index is now lower than readState.Index' (duration: 175.750248ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:00:46.850278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.500276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:00:46.850324Z","caller":"traceutil/trace.go:171","msg":"trace[1130221591] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:4771; }","duration":"452.583569ms","start":"2026-07-28T10:00:46.397727Z","end":"2026-07-28T10:00:46.85031Z","steps":["trace[1130221591] 'agreement among raft nodes before linearized reading' (duration: 452.452676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.850361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:46.397717Z","time spent":"452.634619ms","remote":"127.0.0.1:34622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" "} {"level":"warn","ts":"2026-07-28T10:00:46.85044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.95487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-28T10:00:46.850495Z","caller":"traceutil/trace.go:171","msg":"trace[1802534979] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4771; }","duration":"456.051931ms","start":"2026-07-28T10:00:46.394427Z","end":"2026-07-28T10:00:46.850479Z","steps":["trace[1802534979] 'agreement among raft nodes before linearized reading' (duration: 455.889808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.850531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:46.394405Z","time spent":"456.117412ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":731,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"warn","ts":"2026-07-28T10:00:46.851682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:45.960944Z","time spent":"888.896312ms","remote":"127.0.0.1:34980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-28T10:00:46.853409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.74034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-07-28T10:00:46.853906Z","caller":"traceutil/trace.go:171","msg":"trace[1099954607] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4769; }","duration":"722.30551ms","start":"2026-07-28T10:00:46.131574Z","end":"2026-07-28T10:00:46.85388Z","steps":["trace[1099954607] 'agreement among raft nodes before linearized reading' (duration: 542.53952ms)","trace[1099954607] 'range keys from in-memory index tree' (duration: 175.105016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:00:46.854105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:46.131556Z","time spent":"722.530324ms","remote":"127.0.0.1:34588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207434,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-07-28T10:00:46.867033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.026248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:00:46.867103Z","caller":"traceutil/trace.go:171","msg":"trace[451630381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4772; }","duration":"114.152821ms","start":"2026-07-28T10:00:46.752926Z","end":"2026-07-28T10:00:46.867079Z","steps":["trace[451630381] 'agreement among raft nodes before linearized reading' (duration: 114.005938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.86738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.38347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-28T10:00:46.868403Z","caller":"traceutil/trace.go:171","msg":"trace[1223070157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4772; }","duration":"123.463709ms","start":"2026-07-28T10:00:46.744927Z","end":"2026-07-28T10:00:46.86839Z","steps":["trace[1223070157] 'agreement among raft nodes before linearized reading' (duration: 122.084234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:46.867866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.003477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-28T10:00:46.868621Z","caller":"traceutil/trace.go:171","msg":"trace[851966910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4772; }","duration":"141.819312ms","start":"2026-07-28T10:00:46.726786Z","end":"2026-07-28T10:00:46.868605Z","steps":["trace[851966910] 'agreement among raft nodes before linearized reading' (duration: 140.474688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.572138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.346706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-28T10:00:47.57291Z","caller":"traceutil/trace.go:171","msg":"trace[1855941297] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"523.684408ms","start":"2026-07-28T10:00:47.049199Z","end":"2026-07-28T10:00:47.572884Z","steps":["trace[1855941297] 'process raft request' (duration: 523.606196ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:47.57303Z","caller":"traceutil/trace.go:171","msg":"trace[1766975511] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"524.834338ms","start":"2026-07-28T10:00:47.048176Z","end":"2026-07-28T10:00:47.57301Z","steps":["trace[1766975511] 'process raft request' (duration: 93.515314ms)","trace[1766975511] 'compare' (duration: 430.00233ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:00:47.5731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:47.048161Z","time spent":"524.90562ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T10:00:47.573103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:47.049187Z","time spent":"523.83321ms","remote":"127.0.0.1:34558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T10:00:47.573039Z","caller":"traceutil/trace.go:171","msg":"trace[406527701] linearizableReadLoop","detail":"{readStateIndex:4975; appliedIndex:4974; }","duration":"524.37439ms","start":"2026-07-28T10:00:47.04858Z","end":"2026-07-28T10:00:47.572954Z","steps":["trace[406527701] 'read index received' (duration: 93.170688ms)","trace[406527701] 'applied index is now lower than readState.Index' (duration: 431.200682ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:00:47.573539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.576145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4848"} {"level":"warn","ts":"2026-07-28T10:00:47.573533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.9279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-07-28T10:00:47.573577Z","caller":"traceutil/trace.go:171","msg":"trace[97302301] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4793; }","duration":"523.644187ms","start":"2026-07-28T10:00:47.049921Z","end":"2026-07-28T10:00:47.573566Z","steps":["trace[97302301] 'agreement among raft nodes before linearized reading' (duration: 523.412653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.573559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.43199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9575"} {"level":"warn","ts":"2026-07-28T10:00:47.573607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:47.049913Z","time spent":"523.686178ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4871,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"info","ts":"2026-07-28T10:00:47.573621Z","caller":"traceutil/trace.go:171","msg":"trace[1137764539] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4793; }","duration":"519.513522ms","start":"2026-07-28T10:00:47.054093Z","end":"2026-07-28T10:00:47.573606Z","steps":["trace[1137764539] 'agreement among raft nodes before linearized reading' (duration: 519.293518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.573666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:47.054088Z","time spent":"519.571483ms","remote":"127.0.0.1:34996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9598,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-07-28T10:00:47.573539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.304182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"warn","ts":"2026-07-28T10:00:47.5738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.134621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T10:00:47.573832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.8125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:10148"} {"level":"info","ts":"2026-07-28T10:00:47.57387Z","caller":"traceutil/trace.go:171","msg":"trace[2097799080] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4793; }","duration":"523.86711ms","start":"2026-07-28T10:00:47.049992Z","end":"2026-07-28T10:00:47.573859Z","steps":["trace[2097799080] 'agreement among raft nodes before linearized reading' (duration: 523.764728ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:47.573869Z","caller":"traceutil/trace.go:171","msg":"trace[1099862610] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4793; }","duration":"171.247702ms","start":"2026-07-28T10:00:47.402605Z","end":"2026-07-28T10:00:47.573852Z","steps":["trace[1099862610] 'agreement among raft nodes before linearized reading' (duration: 171.13166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.573904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:47.049984Z","time spent":"523.912602ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":10171,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-28T10:00:47.573886Z","caller":"traceutil/trace.go:171","msg":"trace[916564156] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4793; }","duration":"522.626478ms","start":"2026-07-28T10:00:47.05121Z","end":"2026-07-28T10:00:47.573836Z","steps":["trace[916564156] 'agreement among raft nodes before linearized reading' (duration: 522.220871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.574013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:47.051203Z","time spent":"522.791772ms","remote":"127.0.0.1:34578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-07-28T10:00:47.574142Z","caller":"traceutil/trace.go:171","msg":"trace[614725641] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4793; }","duration":"525.009391ms","start":"2026-07-28T10:00:47.048572Z","end":"2026-07-28T10:00:47.573581Z","steps":["trace[614725641] 'agreement among raft nodes before linearized reading' (duration: 524.570233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.574214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:00:47.048522Z","time spent":"525.675763ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1478,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-07-28T10:00:47.828732Z","caller":"traceutil/trace.go:171","msg":"trace[1699679515] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4979; }","duration":"186.702443ms","start":"2026-07-28T10:00:47.642006Z","end":"2026-07-28T10:00:47.828709Z","steps":["trace[1699679515] 'read index received' (duration: 186.489549ms)","trace[1699679515] 'applied index is now lower than readState.Index' (duration: 211.824µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:00:47.829063Z","caller":"traceutil/trace.go:171","msg":"trace[251185456] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"236.646188ms","start":"2026-07-28T10:00:47.592402Z","end":"2026-07-28T10:00:47.829049Z","steps":["trace[251185456] 'process raft request' (duration: 236.159609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.829347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.438476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ac8d8d3-76d4-40eb-bbae-32f33bb67e49\" ","response":"range_response_count:1 size:2580"} {"level":"info","ts":"2026-07-28T10:00:47.829448Z","caller":"traceutil/trace.go:171","msg":"trace[543816847] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ac8d8d3-76d4-40eb-bbae-32f33bb67e49; range_end:; response_count:1; response_revision:4797; }","duration":"182.586988ms","start":"2026-07-28T10:00:47.646844Z","end":"2026-07-28T10:00:47.829431Z","steps":["trace[543816847] 'agreement among raft nodes before linearized reading' (duration: 182.249902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.829905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.900388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:00:47.829953Z","caller":"traceutil/trace.go:171","msg":"trace[1696157944] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4797; }","duration":"111.970319ms","start":"2026-07-28T10:00:47.717969Z","end":"2026-07-28T10:00:47.82994Z","steps":["trace[1696157944] 'agreement among raft nodes before linearized reading' (duration: 111.893887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.829942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.820524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T10:00:47.829981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.215445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ac8d8d3-76d4-40eb-bbae-32f33bb67e49\" ","response":"range_response_count:1 size:2580"} {"level":"info","ts":"2026-07-28T10:00:47.830025Z","caller":"traceutil/trace.go:171","msg":"trace[245967462] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4797; }","duration":"218.954277ms","start":"2026-07-28T10:00:47.611056Z","end":"2026-07-28T10:00:47.83001Z","steps":["trace[245967462] 'agreement among raft nodes before linearized reading' (duration: 218.795544ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:47.830052Z","caller":"traceutil/trace.go:171","msg":"trace[1038564076] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ac8d8d3-76d4-40eb-bbae-32f33bb67e49; range_end:; response_count:1; response_revision:4797; }","duration":"180.288677ms","start":"2026-07-28T10:00:47.64975Z","end":"2026-07-28T10:00:47.830039Z","steps":["trace[1038564076] 'agreement among raft nodes before linearized reading' (duration: 180.111963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.830182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.761161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:00:47.830221Z","caller":"traceutil/trace.go:171","msg":"trace[1501622234] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4797; }","duration":"182.851343ms","start":"2026-07-28T10:00:47.647359Z","end":"2026-07-28T10:00:47.830211Z","steps":["trace[1501622234] 'agreement among raft nodes before linearized reading' (duration: 182.795362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:47.830425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.703793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-07-28T10:00:47.830459Z","caller":"traceutil/trace.go:171","msg":"trace[1122994626] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4797; }","duration":"239.759094ms","start":"2026-07-28T10:00:47.590692Z","end":"2026-07-28T10:00:47.830451Z","steps":["trace[1122994626] 'agreement among raft nodes before linearized reading' (duration: 239.665792ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:51.220052Z","caller":"traceutil/trace.go:171","msg":"trace[418739220] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"156.395941ms","start":"2026-07-28T10:00:51.063628Z","end":"2026-07-28T10:00:51.220024Z","steps":["trace[418739220] 'process raft request' (duration: 155.528006ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:51.221867Z","caller":"traceutil/trace.go:171","msg":"trace[1459360001] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"157.927389ms","start":"2026-07-28T10:00:51.063903Z","end":"2026-07-28T10:00:51.22183Z","steps":["trace[1459360001] 'process raft request' (duration: 157.731835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:01.488427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209151463503958436,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T10:01:01.763183Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.009767169s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-28T10:01:01.763711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:00.753219Z","time spent":"1.010487372s","remote":"127.0.0.1:34510","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-28T10:01:01.791965Z","caller":"traceutil/trace.go:171","msg":"trace[1477990360] linearizableReadLoop","detail":"{readStateIndex:5163; appliedIndex:5160; }","duration":"804.466788ms","start":"2026-07-28T10:01:00.987468Z","end":"2026-07-28T10:01:01.791935Z","steps":["trace[1477990360] 'read index received' (duration: 776.03599ms)","trace[1477990360] 'applied index is now lower than readState.Index' (duration: 28.429368ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:01:01.792057Z","caller":"traceutil/trace.go:171","msg":"trace[937868493] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"876.634278ms","start":"2026-07-28T10:01:00.915383Z","end":"2026-07-28T10:01:01.792017Z","steps":["trace[937868493] 'process raft request' (duration: 876.399594ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:01.792077Z","caller":"traceutil/trace.go:171","msg":"trace[1233917490] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"110.965982ms","start":"2026-07-28T10:01:01.681076Z","end":"2026-07-28T10:01:01.792042Z","steps":["trace[1233917490] 'process raft request' (duration: 110.795769ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:01.792117Z","caller":"traceutil/trace.go:171","msg":"trace[950799589] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"948.978992ms","start":"2026-07-28T10:01:00.843108Z","end":"2026-07-28T10:01:01.792087Z","steps":["trace[950799589] 'process raft request' (duration: 948.460142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:01.792215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:00.91536Z","time spent":"876.77372ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:01:01.792233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.750384ms","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-07-28T10:01:01.792277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:00.843074Z","time spent":"949.120214ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:01:01.792289Z","caller":"traceutil/trace.go:171","msg":"trace[1422379389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4975; }","duration":"804.900996ms","start":"2026-07-28T10:01:00.987372Z","end":"2026-07-28T10:01:01.792273Z","steps":["trace[1422379389] 'agreement among raft nodes before linearized reading' (duration: 804.715013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:01.792346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:00.987331Z","time spent":"805.001528ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T10:01:01.816027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.348056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-28T10:01:01.816099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.555265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-28T10:01:01.816119Z","caller":"traceutil/trace.go:171","msg":"trace[76670187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4976; }","duration":"551.489599ms","start":"2026-07-28T10:01:01.26461Z","end":"2026-07-28T10:01:01.8161Z","steps":["trace[76670187] 'agreement among raft nodes before linearized reading' (duration: 551.243955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:01.816045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.395796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-28T10:01:01.816168Z","caller":"traceutil/trace.go:171","msg":"trace[570461966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4976; }","duration":"811.648996ms","start":"2026-07-28T10:01:01.0045Z","end":"2026-07-28T10:01:01.816149Z","steps":["trace[570461966] 'agreement among raft nodes before linearized reading' (duration: 811.400812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:01.816192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:01.264587Z","time spent":"551.595341ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-28T10:01:01.816204Z","caller":"traceutil/trace.go:171","msg":"trace[1601662088] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4976; }","duration":"776.60199ms","start":"2026-07-28T10:01:01.039583Z","end":"2026-07-28T10:01:01.816185Z","steps":["trace[1601662088] 'agreement among raft nodes before linearized reading' (duration: 776.346105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:01.816211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:01.004493Z","time spent":"811.708997ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T10:01:01.816264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:01.039558Z","time spent":"776.681111ms","remote":"127.0.0.1:34646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-28T10:01:01.8164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.4043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-28T10:01:01.816465Z","caller":"traceutil/trace.go:171","msg":"trace[1625643240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4976; }","duration":"550.474832ms","start":"2026-07-28T10:01:01.265976Z","end":"2026-07-28T10:01:01.81645Z","steps":["trace[1625643240] 'agreement among raft nodes before linearized reading' (duration: 550.066364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:01.816541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.603386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16549"} {"level":"warn","ts":"2026-07-28T10:01:01.816597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:01.265865Z","time spent":"550.719256ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T10:01:01.816525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.708072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-28T10:01:01.816728Z","caller":"traceutil/trace.go:171","msg":"trace[116474918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4976; }","duration":"819.018838ms","start":"2026-07-28T10:01:00.99768Z","end":"2026-07-28T10:01:01.816699Z","steps":["trace[116474918] 'agreement among raft nodes before linearized reading' (duration: 818.315855ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:01.81665Z","caller":"traceutil/trace.go:171","msg":"trace[782735959] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4976; }","duration":"825.743739ms","start":"2026-07-28T10:01:00.990873Z","end":"2026-07-28T10:01:01.816616Z","steps":["trace[782735959] 'agreement among raft nodes before linearized reading' (duration: 824.951584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:01.816851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:00.997656Z","time spent":"819.17674ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T10:01:01.816877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:01:00.990839Z","time spent":"825.973082ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16572,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-07-28T10:01:01.937518Z","caller":"traceutil/trace.go:171","msg":"trace[819628254] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5165; }","duration":"119.131527ms","start":"2026-07-28T10:01:01.818354Z","end":"2026-07-28T10:01:01.937485Z","steps":["trace[819628254] 'read index received' (duration: 35.170838ms)","trace[819628254] 'applied index is now lower than readState.Index' (duration: 83.959139ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:01:01.937546Z","caller":"traceutil/trace.go:171","msg":"trace[374409838] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"139.98322ms","start":"2026-07-28T10:01:01.797532Z","end":"2026-07-28T10:01:01.937516Z","steps":["trace[374409838] 'process raft request' (duration: 55.910529ms)","trace[374409838] 'compare' (duration: 83.809636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:01:01.937657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.306051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:01:01.937695Z","caller":"traceutil/trace.go:171","msg":"trace[1371438105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4977; }","duration":"119.408143ms","start":"2026-07-28T10:01:01.818278Z","end":"2026-07-28T10:01:01.937686Z","steps":["trace[1371438105] 'agreement among raft nodes before linearized reading' (duration: 119.317911ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.011496Z","caller":"traceutil/trace.go:171","msg":"trace[1827144651] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"190.063875ms","start":"2026-07-28T10:01:01.821403Z","end":"2026-07-28T10:01:02.011467Z","steps":["trace[1827144651] 'process raft request' (duration: 189.557676ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.01178Z","caller":"traceutil/trace.go:171","msg":"trace[1178841154] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"189.993293ms","start":"2026-07-28T10:01:01.821766Z","end":"2026-07-28T10:01:02.011759Z","steps":["trace[1178841154] 'process raft request' (duration: 189.874821ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.011999Z","caller":"traceutil/trace.go:171","msg":"trace[1997764490] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"190.465062ms","start":"2026-07-28T10:01:01.821524Z","end":"2026-07-28T10:01:02.011989Z","steps":["trace[1997764490] 'process raft request' (duration: 189.893562ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.012026Z","caller":"traceutil/trace.go:171","msg":"trace[592105130] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"179.946893ms","start":"2026-07-28T10:01:01.832066Z","end":"2026-07-28T10:01:02.012013Z","steps":["trace[592105130] 'process raft request' (duration: 179.831191ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.01211Z","caller":"traceutil/trace.go:171","msg":"trace[1587737208] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"190.000303ms","start":"2026-07-28T10:01:01.822097Z","end":"2026-07-28T10:01:02.012098Z","steps":["trace[1587737208] 'process raft request' (duration: 189.629006ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.023446Z","caller":"traceutil/trace.go:171","msg":"trace[2030946098] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"118.14323ms","start":"2026-07-28T10:01:01.905276Z","end":"2026-07-28T10:01:02.02342Z","steps":["trace[2030946098] 'process raft request' (duration: 117.909316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.024403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.871088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:01:02.024488Z","caller":"traceutil/trace.go:171","msg":"trace[1100592991] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4983; }","duration":"163.016381ms","start":"2026-07-28T10:01:01.861448Z","end":"2026-07-28T10:01:02.024464Z","steps":["trace[1100592991] 'agreement among raft nodes before linearized reading' (duration: 162.853068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.024566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.382128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-28T10:01:02.024617Z","caller":"traceutil/trace.go:171","msg":"trace[2106304991] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4983; }","duration":"205.48504ms","start":"2026-07-28T10:01:01.819121Z","end":"2026-07-28T10:01:02.024606Z","steps":["trace[2106304991] 'agreement among raft nodes before linearized reading' (duration: 205.168574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.024973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.651985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8622bdc2-cdf9-4987-8553-84a3d38828f3\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-07-28T10:01:02.025043Z","caller":"traceutil/trace.go:171","msg":"trace[554507398] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8622bdc2-cdf9-4987-8553-84a3d38828f3; range_end:; response_count:1; response_revision:4983; }","duration":"204.766656ms","start":"2026-07-28T10:01:01.820262Z","end":"2026-07-28T10:01:02.025029Z","steps":["trace[554507398] 'agreement among raft nodes before linearized reading' (duration: 204.437021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.024261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.417713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-07-28T10:01:02.026358Z","caller":"traceutil/trace.go:171","msg":"trace[1515065050] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4983; }","duration":"163.549921ms","start":"2026-07-28T10:01:01.862786Z","end":"2026-07-28T10:01:02.026336Z","steps":["trace[1515065050] 'agreement among raft nodes before linearized reading' (duration: 161.328021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.317913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.868347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T10:01:02.318001Z","caller":"traceutil/trace.go:171","msg":"trace[1481919914] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5172; }","duration":"279.465487ms","start":"2026-07-28T10:01:02.038521Z","end":"2026-07-28T10:01:02.317987Z","steps":["trace[1481919914] 'read index received' (duration: 56.400107ms)","trace[1481919914] 'applied index is now lower than readState.Index' (duration: 223.06422ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:01:02.318059Z","caller":"traceutil/trace.go:171","msg":"trace[593252163] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"280.141089ms","start":"2026-07-28T10:01:02.037909Z","end":"2026-07-28T10:01:02.31805Z","steps":["trace[593252163] 'process raft request' (duration: 57.059059ms)","trace[593252163] 'compare' (duration: 222.608991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:01:02.318217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.708531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-07-28T10:01:02.318236Z","caller":"traceutil/trace.go:171","msg":"trace[1890652155] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4984; }","duration":"279.776212ms","start":"2026-07-28T10:01:02.038453Z","end":"2026-07-28T10:01:02.31823Z","steps":["trace[1890652155] 'agreement among raft nodes before linearized reading' (duration: 279.687491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.31842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.070475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T10:01:02.318446Z","caller":"traceutil/trace.go:171","msg":"trace[1908082838] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4984; }","duration":"128.129676ms","start":"2026-07-28T10:01:02.19031Z","end":"2026-07-28T10:01:02.31844Z","steps":["trace[1908082838] 'agreement among raft nodes before linearized reading' (duration: 128.053025ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.596923Z","caller":"traceutil/trace.go:171","msg":"trace[1285533508] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"185.686813ms","start":"2026-07-28T10:01:02.4112Z","end":"2026-07-28T10:01:02.596887Z","steps":["trace[1285533508] 'process raft request' (duration: 185.443879ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.653441Z","caller":"traceutil/trace.go:171","msg":"trace[960870436] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"241.980518ms","start":"2026-07-28T10:01:02.411368Z","end":"2026-07-28T10:01:02.653349Z","steps":["trace[960870436] 'process raft request' (duration: 241.683132ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.653828Z","caller":"traceutil/trace.go:171","msg":"trace[1284977014] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"242.269093ms","start":"2026-07-28T10:01:02.411544Z","end":"2026-07-28T10:01:02.653813Z","steps":["trace[1284977014] 'process raft request' (duration: 241.711083ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.65395Z","caller":"traceutil/trace.go:171","msg":"trace[616551330] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"242.325064ms","start":"2026-07-28T10:01:02.411594Z","end":"2026-07-28T10:01:02.653919Z","steps":["trace[616551330] 'process raft request' (duration: 241.973117ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.653965Z","caller":"traceutil/trace.go:171","msg":"trace[1534624627] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"242.251053ms","start":"2026-07-28T10:01:02.411684Z","end":"2026-07-28T10:01:02.653935Z","steps":["trace[1534624627] 'process raft request' (duration: 242.026079ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.656536Z","caller":"traceutil/trace.go:171","msg":"trace[970689383] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"216.626185ms","start":"2026-07-28T10:01:02.439882Z","end":"2026-07-28T10:01:02.656508Z","steps":["trace[970689383] 'process raft request' (duration: 213.899746ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.659022Z","caller":"traceutil/trace.go:171","msg":"trace[812409887] linearizableReadLoop","detail":"{readStateIndex:5177; appliedIndex:5175; }","duration":"247.593678ms","start":"2026-07-28T10:01:02.411397Z","end":"2026-07-28T10:01:02.65899Z","steps":["trace[812409887] 'read index received' (duration: 185.304827ms)","trace[812409887] 'applied index is now lower than readState.Index' (duration: 62.287291ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:01:02.659254Z","caller":"traceutil/trace.go:171","msg":"trace[1099281638] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"205.002588ms","start":"2026-07-28T10:01:02.454239Z","end":"2026-07-28T10:01:02.659242Z","steps":["trace[1099281638] 'process raft request' (duration: 204.647971ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:02.659182Z","caller":"traceutil/trace.go:171","msg":"trace[712908792] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"207.859819ms","start":"2026-07-28T10:01:02.451273Z","end":"2026-07-28T10:01:02.659133Z","steps":["trace[712908792] 'process raft request' (duration: 204.690482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.659918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.157823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T10:01:02.659964Z","caller":"traceutil/trace.go:171","msg":"trace[1708696968] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4994; }","duration":"246.207823ms","start":"2026-07-28T10:01:02.413745Z","end":"2026-07-28T10:01:02.659953Z","steps":["trace[1708696968] 'agreement among raft nodes before linearized reading' (duration: 246.099051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.65979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.555005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10681"} {"level":"info","ts":"2026-07-28T10:01:02.66024Z","caller":"traceutil/trace.go:171","msg":"trace[1610183064] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4994; }","duration":"249.030424ms","start":"2026-07-28T10:01:02.411182Z","end":"2026-07-28T10:01:02.660213Z","steps":["trace[1610183064] 'agreement among raft nodes before linearized reading' (duration: 248.411533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.660399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-28T10:01:02.660517Z","caller":"traceutil/trace.go:171","msg":"trace[1606407700] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4994; }","duration":"249.138766ms","start":"2026-07-28T10:01:02.411349Z","end":"2026-07-28T10:01:02.660488Z","steps":["trace[1606407700] 'agreement among raft nodes before linearized reading' (duration: 248.416023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:02.862056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.86905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T10:01:02.862209Z","caller":"traceutil/trace.go:171","msg":"trace[1591665566] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5183; }","duration":"108.549757ms","start":"2026-07-28T10:01:02.753625Z","end":"2026-07-28T10:01:02.862175Z","steps":["trace[1591665566] 'read index received' (duration: 39.89µs)","trace[1591665566] 'applied index is now lower than readState.Index' (duration: 108.508306ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:01:02.862393Z","caller":"traceutil/trace.go:171","msg":"trace[1414904221] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"184.094395ms","start":"2026-07-28T10:01:02.678267Z","end":"2026-07-28T10:01:02.862361Z","steps":["trace[1414904221] 'process raft request' (duration: 17.786297ms)","trace[1414904221] 'compare' (duration: 165.486633ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:01:02.862434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.806801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:01:02.862547Z","caller":"traceutil/trace.go:171","msg":"trace[641197155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4995; }","duration":"108.943154ms","start":"2026-07-28T10:01:02.753593Z","end":"2026-07-28T10:01:02.862537Z","steps":["trace[641197155] 'agreement among raft nodes before linearized reading' (duration: 108.647709ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:57.788613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2828} {"level":"info","ts":"2026-07-28T10:01:57.888043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2828,"took":"97.351922ms","hash":2190504527,"current-db-size-bytes":53686272,"current-db-size":"54 MB","current-db-size-in-use-bytes":28696576,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-28T10:01:57.888142Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2190504527,"revision":2828,"compact-revision":-1} {"level":"info","ts":"2026-07-28T10:05:05.768696Z","caller":"traceutil/trace.go:171","msg":"trace[893087285] transaction","detail":"{read_only:false; response_revision:6702; number_of_response:1; }","duration":"118.630748ms","start":"2026-07-28T10:05:05.650007Z","end":"2026-07-28T10:05:05.768638Z","steps":["trace[893087285] 'process raft request' (duration: 118.475062ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:05:05.770689Z","caller":"traceutil/trace.go:171","msg":"trace[174806907] transaction","detail":"{read_only:false; response_revision:6703; number_of_response:1; }","duration":"116.292487ms","start":"2026-07-28T10:05:05.654341Z","end":"2026-07-28T10:05:05.770633Z","steps":["trace[174806907] 'process raft request' (duration: 116.063679ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:06:57.796621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5430} {"level":"info","ts":"2026-07-28T10:06:57.916514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5430,"took":"117.525475ms","hash":3509941875,"current-db-size-bytes":53686272,"current-db-size":"54 MB","current-db-size-in-use-bytes":18427904,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-28T10:06:57.916593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3509941875,"revision":5430,"compact-revision":2828} {"level":"info","ts":"2026-07-28T10:08:38.177972Z","caller":"traceutil/trace.go:171","msg":"trace[1640398767] transaction","detail":"{read_only:false; response_revision:7851; number_of_response:1; }","duration":"127.053741ms","start":"2026-07-28T10:08:38.050887Z","end":"2026-07-28T10:08:38.177941Z","steps":["trace[1640398767] 'process raft request' (duration: 126.848486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:08:41.066329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.388658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T10:08:41.066503Z","caller":"traceutil/trace.go:171","msg":"trace[221674485] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7870; }","duration":"120.721247ms","start":"2026-07-28T10:08:40.945729Z","end":"2026-07-28T10:08:41.06645Z","steps":["trace[221674485] 'count revisions from in-memory index tree' (duration: 120.257525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:08:42.999732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209151463503965898,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T10:08:43.018815Z","caller":"traceutil/trace.go:171","msg":"trace[1070688371] transaction","detail":"{read_only:false; response_revision:7871; number_of_response:1; }","duration":"823.349431ms","start":"2026-07-28T10:08:42.195429Z","end":"2026-07-28T10:08:43.018778Z","steps":["trace[1070688371] 'process raft request' (duration: 823.173266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:08:43.019007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:08:42.195394Z","time spent":"823.511795ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:08:43.020452Z","caller":"traceutil/trace.go:171","msg":"trace[122437162] linearizableReadLoop","detail":"{readStateIndex:8168; appliedIndex:8165; }","duration":"521.088361ms","start":"2026-07-28T10:08:42.499334Z","end":"2026-07-28T10:08:43.020422Z","steps":["trace[122437162] 'read index received' (duration: 519.911859ms)","trace[122437162] 'applied index is now lower than readState.Index' (duration: 1.175262ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:08:43.020492Z","caller":"traceutil/trace.go:171","msg":"trace[1591238016] transaction","detail":"{read_only:false; response_revision:7872; number_of_response:1; }","duration":"824.124732ms","start":"2026-07-28T10:08:42.196346Z","end":"2026-07-28T10:08:43.020471Z","steps":["trace[1591238016] 'process raft request' (duration: 823.845955ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:08:43.020575Z","caller":"traceutil/trace.go:171","msg":"trace[1957855951] transaction","detail":"{read_only:false; response_revision:7873; number_of_response:1; }","duration":"370.692617ms","start":"2026-07-28T10:08:42.649871Z","end":"2026-07-28T10:08:43.020564Z","steps":["trace[1957855951] 'process raft request' (duration: 370.467251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:08:43.02066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:08:42.196316Z","time spent":"824.236705ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:08:43.020694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:08:42.649835Z","time spent":"370.769469ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:08:43.020819Z","caller":"traceutil/trace.go:171","msg":"trace[1390566974] transaction","detail":"{read_only:false; response_revision:7874; number_of_response:1; }","duration":"369.670748ms","start":"2026-07-28T10:08:42.651134Z","end":"2026-07-28T10:08:43.020804Z","steps":["trace[1390566974] 'process raft request' (duration: 369.250387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:08:43.020885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:08:42.651102Z","time spent":"369.748091ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:08:43.021028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.340251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-28T10:08:43.021084Z","caller":"traceutil/trace.go:171","msg":"trace[807281840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7874; }","duration":"275.471645ms","start":"2026-07-28T10:08:42.745597Z","end":"2026-07-28T10:08:43.021069Z","steps":["trace[807281840] 'agreement among raft nodes before linearized reading' (duration: 275.282859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:08:43.021161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.914522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-28T10:08:43.021238Z","caller":"traceutil/trace.go:171","msg":"trace[1738475773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7874; }","duration":"273.998874ms","start":"2026-07-28T10:08:42.747225Z","end":"2026-07-28T10:08:43.021224Z","steps":["trace[1738475773] 'agreement among raft nodes before linearized reading' (duration: 273.812099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:08:43.021277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.595391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-28T10:08:43.021288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.460297ms","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-07-28T10:08:43.021305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.975406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-28T10:08:43.021314Z","caller":"traceutil/trace.go:171","msg":"trace[1846741374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7874; }","duration":"274.677753ms","start":"2026-07-28T10:08:42.746626Z","end":"2026-07-28T10:08:43.021303Z","steps":["trace[1846741374] 'agreement among raft nodes before linearized reading' (duration: 274.529479ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:08:43.021337Z","caller":"traceutil/trace.go:171","msg":"trace[850367383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7874; }","duration":"274.521149ms","start":"2026-07-28T10:08:42.7468Z","end":"2026-07-28T10:08:43.021321Z","steps":["trace[850367383] 'agreement among raft nodes before linearized reading' (duration: 274.335133ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:08:43.021351Z","caller":"traceutil/trace.go:171","msg":"trace[1475934228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7874; }","duration":"522.064678ms","start":"2026-07-28T10:08:42.499275Z","end":"2026-07-28T10:08:43.02134Z","steps":["trace[1475934228] 'agreement among raft nodes before linearized reading' (duration: 521.956695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:08:43.021403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:08:42.499249Z","time spent":"522.14545ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T10:09:46.520824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5209151463503966666,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-28T10:09:47.654033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072427792s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-28T10:09:47.654383Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074031503s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-28T10:09:47.65471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:47.228217Z","time spent":"426.484891ms","remote":"127.0.0.1:53076","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-28T10:09:47.655249Z","caller":"traceutil/trace.go:171","msg":"trace[220049799] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"1.635431195s","start":"2026-07-28T10:09:46.019797Z","end":"2026-07-28T10:09:47.655228Z","steps":["trace[220049799] 'process raft request' (duration: 1.635205859s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.655393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.019772Z","time spent":"1.635532317s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:09:47.655572Z","caller":"traceutil/trace.go:171","msg":"trace[1506985052] linearizableReadLoop","detail":"{readStateIndex:8495; appliedIndex:8489; }","duration":"1.635583929s","start":"2026-07-28T10:09:46.019965Z","end":"2026-07-28T10:09:47.655549Z","steps":["trace[1506985052] 'read index received' (duration: 561.564096ms)","trace[1506985052] 'applied index is now lower than readState.Index' (duration: 1.074018773s)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:09:47.655688Z","caller":"traceutil/trace.go:171","msg":"trace[1316152557] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"1.635520797s","start":"2026-07-28T10:09:46.020156Z","end":"2026-07-28T10:09:47.655677Z","steps":["trace[1316152557] 'process raft request' (duration: 1.635330622s)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:09:47.655739Z","caller":"traceutil/trace.go:171","msg":"trace[695852553] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"1.635742802s","start":"2026-07-28T10:09:46.019985Z","end":"2026-07-28T10:09:47.655727Z","steps":["trace[695852553] 'process raft request' (duration: 1.635188478s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.655852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.02014Z","time spent":"1.635589778s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:09:47.656019Z","caller":"traceutil/trace.go:171","msg":"trace[169731034] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"1.612153271s","start":"2026-07-28T10:09:46.043857Z","end":"2026-07-28T10:09:47.65601Z","steps":["trace[169731034] 'process raft request' (duration: 1.611660509s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.656078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.043842Z","time spent":"1.612211563s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:09:47.65567Z","caller":"traceutil/trace.go:171","msg":"trace[404418402] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"1.635547947s","start":"2026-07-28T10:09:46.020094Z","end":"2026-07-28T10:09:47.655642Z","steps":["trace[404418402] 'process raft request' (duration: 1.6352804s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.655869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.01996Z","time spent":"1.635818675s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:09:47.656288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.020072Z","time spent":"1.636188224s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:09:47.656252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636286386s","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-07-28T10:09:47.65642Z","caller":"traceutil/trace.go:171","msg":"trace[177368411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8188; }","duration":"1.636503732s","start":"2026-07-28T10:09:46.019909Z","end":"2026-07-28T10:09:47.656412Z","steps":["trace[177368411] 'agreement among raft nodes before linearized reading' (duration: 1.636257685s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.656563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.019882Z","time spent":"1.636670156s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-28T10:09:47.656512Z","caller":"traceutil/trace.go:171","msg":"trace[681447246] transaction","detail":"{read_only:false; response_revision:8189; number_of_response:1; }","duration":"571.818542ms","start":"2026-07-28T10:09:47.084683Z","end":"2026-07-28T10:09:47.656502Z","steps":["trace[681447246] 'process raft request' (duration: 571.655048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.656912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:47.084601Z","time spent":"572.283554ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:09:47.656678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636627945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-28T10:09:47.657062Z","caller":"traceutil/trace.go:171","msg":"trace[872716424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8190; }","duration":"1.637060186s","start":"2026-07-28T10:09:46.019993Z","end":"2026-07-28T10:09:47.657054Z","steps":["trace[872716424] 'agreement among raft nodes before linearized reading' (duration: 1.636570983s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.6571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.019968Z","time spent":"1.637125668s","remote":"127.0.0.1:34772","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-07-28T10:09:47.656715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614135072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-28T10:09:47.657218Z","caller":"traceutil/trace.go:171","msg":"trace[1303304183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8190; }","duration":"1.614683266s","start":"2026-07-28T10:09:46.042527Z","end":"2026-07-28T10:09:47.65721Z","steps":["trace[1303304183] 'agreement among raft nodes before linearized reading' (duration: 1.614085541s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.657262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.042507Z","time spent":"1.614746097s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T10:09:47.656745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636643185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-28T10:09:47.657426Z","caller":"traceutil/trace.go:171","msg":"trace[1057889333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8190; }","duration":"1.637322972s","start":"2026-07-28T10:09:46.020097Z","end":"2026-07-28T10:09:47.657419Z","steps":["trace[1057889333] 'agreement among raft nodes before linearized reading' (duration: 1.636599834s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.657464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.020064Z","time spent":"1.637391394s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T10:09:47.656757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610679513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:09:47.657672Z","caller":"traceutil/trace.go:171","msg":"trace[1742695386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8190; }","duration":"1.611612487s","start":"2026-07-28T10:09:46.04605Z","end":"2026-07-28T10:09:47.657663Z","steps":["trace[1742695386] 'agreement among raft nodes before linearized reading' (duration: 1.610687604s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.657714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.046036Z","time spent":"1.611669308s","remote":"127.0.0.1:34488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T10:09:47.656769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636686937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-28T10:09:47.658092Z","caller":"traceutil/trace.go:171","msg":"trace[1978766455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8190; }","duration":"1.63801558s","start":"2026-07-28T10:09:46.020068Z","end":"2026-07-28T10:09:47.658083Z","steps":["trace[1978766455] 'agreement among raft nodes before linearized reading' (duration: 1.636651825s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.658134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.020061Z","time spent":"1.638064271s","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T10:09:47.65676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311586019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T10:09:47.658278Z","caller":"traceutil/trace.go:171","msg":"trace[1692464776] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8190; }","duration":"1.31318201s","start":"2026-07-28T10:09:46.345084Z","end":"2026-07-28T10:09:47.658266Z","steps":["trace[1692464776] 'agreement among raft nodes before linearized reading' (duration: 1.311532288s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.658331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.345063Z","time spent":"1.313252921s","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":71,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-28T10:09:47.656802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.012844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T10:09:47.658494Z","caller":"traceutil/trace.go:171","msg":"trace[8698723] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8190; }","duration":"245.748468ms","start":"2026-07-28T10:09:47.412734Z","end":"2026-07-28T10:09:47.658482Z","steps":["trace[8698723] 'agreement among raft nodes before linearized reading' (duration: 244.045635ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:09:47.656811Z","caller":"traceutil/trace.go:171","msg":"trace[212136352] transaction","detail":"{read_only:false; response_revision:8190; number_of_response:1; }","duration":"212.268275ms","start":"2026-07-28T10:09:47.444531Z","end":"2026-07-28T10:09:47.6568Z","steps":["trace[212136352] 'process raft request' (duration: 211.943687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.656826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.871749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:09:47.659561Z","caller":"traceutil/trace.go:171","msg":"trace[1504468328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8190; }","duration":"222.577309ms","start":"2026-07-28T10:09:47.436939Z","end":"2026-07-28T10:09:47.659517Z","steps":["trace[1504468328] 'agreement among raft nodes before linearized reading' (duration: 219.87569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.656838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.241739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:09:47.660109Z","caller":"traceutil/trace.go:171","msg":"trace[1639223329] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:8190; }","duration":"492.535132ms","start":"2026-07-28T10:09:47.167556Z","end":"2026-07-28T10:09:47.660091Z","steps":["trace[1639223329] 'agreement among raft nodes before linearized reading' (duration: 489.256749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.660189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:47.167547Z","time spent":"492.597925ms","remote":"127.0.0.1:32974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-07-28T10:09:47.656847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.093534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:09:47.660299Z","caller":"traceutil/trace.go:171","msg":"trace[2106544332] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8190; }","duration":"722.564142ms","start":"2026-07-28T10:09:46.937712Z","end":"2026-07-28T10:09:47.660276Z","steps":["trace[2106544332] 'agreement among raft nodes before linearized reading' (duration: 719.117584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.656825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466780085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-28T10:09:47.660359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.937688Z","time spent":"722.647855ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-28T10:09:47.660436Z","caller":"traceutil/trace.go:171","msg":"trace[2051076712] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8190; }","duration":"1.470443429s","start":"2026-07-28T10:09:46.189968Z","end":"2026-07-28T10:09:47.660412Z","steps":["trace[2051076712] 'agreement among raft nodes before linearized reading' (duration: 1.466755895s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:09:47.66056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:09:46.189939Z","time spent":"1.470570252s","remote":"127.0.0.1:47438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":11,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"info","ts":"2026-07-28T10:11:57.806116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7345} {"level":"info","ts":"2026-07-28T10:11:57.868063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7345,"took":"60.17778ms","hash":1098769218,"current-db-size-bytes":53686272,"current-db-size":"54 MB","current-db-size-in-use-bytes":15052800,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-07-28T10:11:57.86816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1098769218,"revision":7345,"compact-revision":5430}