{"level":"warn","ts":"2026-04-07T15:06:01.461114Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:06:01.46123Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T15:06:01.461308Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:06:01.461321Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-07T15:06:01.461342Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T15:06:01.462154Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-04-07T15:06:01.462236Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T15:06:01.468436Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.909514ms"} {"level":"info","ts":"2026-04-07T15:06:01.475584Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-04-07T15:06:01.475685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:06:01.475716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-04-07T15:06:01.47574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:06:01.475752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-04-07T15:06:01.475794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-04-07T15:06:01.48463Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:06:01.486174Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:06:01.487162Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T15:06:01.490061Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:06:01.490194Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:06:01.490638Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:06:01.490762Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:06:01.490786Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:06:01.493976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-04-07T15:06:01.49442Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-07T15:06:01.49665Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T15:06:01.496845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:06:01.496876Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:06:01.497024Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-07T15:06:01.497065Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-07T15:06:02.47631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:06:02.476444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:06:02.476485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-04-07T15:06:02.476506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:06:02.476518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-07T15:06:02.476598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-04-07T15:06:02.476612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-07T15:06:02.477928Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:06:02.479025Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:06:02.479042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:06:02.479137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:06:02.479498Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:06:02.479551Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:06:02.479681Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:06:02.479834Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:06:02.479884Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:06:02.482256Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-04-07T15:06:02.483176Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-07T15:06:05.218699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625708800377817,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:06:05.28239Z","caller":"traceutil/trace.go:171","msg":"trace[1631432261] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"565.312841ms","start":"2026-04-07T15:06:04.717053Z","end":"2026-04-07T15:06:05.282366Z","steps":["trace[1631432261] 'process raft request' (duration: 565.157055ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:05.282825Z","caller":"traceutil/trace.go:171","msg":"trace[837058882] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"564.535474ms","start":"2026-04-07T15:06:04.718253Z","end":"2026-04-07T15:06:05.282788Z","steps":["trace[837058882] 'read index received' (duration: 563.872984ms)","trace[837058882] 'applied index is now lower than readState.Index' (duration: 657.73µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:06:05.283071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.791624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T15:06:05.283062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.717036Z","time spent":"565.468926ms","remote":"127.0.0.1:54764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:06:05.283158Z","caller":"traceutil/trace.go:171","msg":"trace[1606925658] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:72; }","duration":"564.945668ms","start":"2026-04-07T15:06:04.718191Z","end":"2026-04-07T15:06:05.283137Z","steps":["trace[1606925658] 'agreement among raft nodes before linearized reading' (duration: 564.757102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.283143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.047701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T15:06:05.283209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.718176Z","time spent":"565.024171ms","remote":"127.0.0.1:54754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"info","ts":"2026-04-07T15:06:05.283276Z","caller":"traceutil/trace.go:171","msg":"trace[405803654] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:72; }","duration":"306.234358ms","start":"2026-04-07T15:06:04.976996Z","end":"2026-04-07T15:06:05.283231Z","steps":["trace[405803654] 'agreement among raft nodes before linearized reading' (duration: 306.060173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.283352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.97697Z","time spent":"306.34776ms","remote":"127.0.0.1:54538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-07T15:06:05.283706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.265255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:06:05.283934Z","caller":"traceutil/trace.go:171","msg":"trace[1390361329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"539.355268ms","start":"2026-04-07T15:06:04.744383Z","end":"2026-04-07T15:06:05.283738Z","steps":["trace[1390361329] 'agreement among raft nodes before linearized reading' (duration: 538.589194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.283983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:04.744368Z","time spent":"539.601996ms","remote":"127.0.0.1:54408","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-04-07T15:06:05.421764Z","caller":"traceutil/trace.go:171","msg":"trace[578988468] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"134.26689ms","start":"2026-04-07T15:06:05.287473Z","end":"2026-04-07T15:06:05.42174Z","steps":["trace[578988468] 'read index received' (duration: 134.121505ms)","trace[578988468] 'applied index is now lower than readState.Index' (duration: 144.355µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:06:05.421825Z","caller":"traceutil/trace.go:171","msg":"trace[1966123535] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"135.530019ms","start":"2026-04-07T15:06:05.286257Z","end":"2026-04-07T15:06:05.421787Z","steps":["trace[1966123535] 'process raft request' (duration: 135.370425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:05.421938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.446745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T15:06:05.421977Z","caller":"traceutil/trace.go:171","msg":"trace[1137937090] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:73; }","duration":"134.538538ms","start":"2026-04-07T15:06:05.287429Z","end":"2026-04-07T15:06:05.421968Z","steps":["trace[1137937090] 'agreement among raft nodes before linearized reading' (duration: 134.405784ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:36.163738Z","caller":"traceutil/trace.go:171","msg":"trace[432179764] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"124.743093ms","start":"2026-04-07T15:06:36.038967Z","end":"2026-04-07T15:06:36.16371Z","steps":["trace[432179764] 'process raft request' (duration: 122.506352ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:38.303793Z","caller":"traceutil/trace.go:171","msg":"trace[174166705] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"219.787661ms","start":"2026-04-07T15:06:38.083972Z","end":"2026-04-07T15:06:38.30376Z","steps":["trace[174166705] 'read index received' (duration: 218.456996ms)","trace[174166705] 'applied index is now lower than readState.Index' (duration: 1.329585ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:06:38.303858Z","caller":"traceutil/trace.go:171","msg":"trace[574735998] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"243.651976ms","start":"2026-04-07T15:06:38.060159Z","end":"2026-04-07T15:06:38.303811Z","steps":["trace[574735998] 'process raft request' (duration: 242.974607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:38.303996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.999647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9nzlw\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-07T15:06:38.304098Z","caller":"traceutil/trace.go:171","msg":"trace[1372681817] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9nzlw; range_end:; response_count:1; response_revision:614; }","duration":"220.13196ms","start":"2026-04-07T15:06:38.083942Z","end":"2026-04-07T15:06:38.304074Z","steps":["trace[1372681817] 'agreement among raft nodes before linearized reading' (duration: 219.944904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:38.79245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.284143ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:38.792793Z","caller":"traceutil/trace.go:171","msg":"trace[1795237184] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:614; }","duration":"375.636302ms","start":"2026-04-07T15:06:38.417136Z","end":"2026-04-07T15:06:38.792773Z","steps":["trace[1795237184] 'range keys from in-memory index tree' (duration: 375.271492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:38.793401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.659397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:06:38.793538Z","caller":"traceutil/trace.go:171","msg":"trace[2011624484] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"484.026672ms","start":"2026-04-07T15:06:38.309495Z","end":"2026-04-07T15:06:38.793522Z","steps":["trace[2011624484] 'process raft request' (duration: 149.339297ms)","trace[2011624484] 'compare' (duration: 333.38784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:06:38.793603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.309468Z","time spent":"484.105834ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:06:39.717145Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.258288842s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T15:06:39.717712Z","caller":"traceutil/trace.go:171","msg":"trace[780707706] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:640; }","duration":"1.260619364s","start":"2026-04-07T15:06:38.457065Z","end":"2026-04-07T15:06:39.717684Z","steps":["trace[780707706] 'read index received' (duration: 1.786078ms)","trace[780707706] 'applied index is now lower than readState.Index' (duration: 1.258832306s)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:06:39.71782Z","caller":"traceutil/trace.go:171","msg":"trace[477382923] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.360767891s","start":"2026-04-07T15:06:38.357021Z","end":"2026-04-07T15:06:39.717788Z","steps":["trace[477382923] 'process raft request' (duration: 1.360613337s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.71789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260801189s","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-04-07T15:06:39.717897Z","caller":"traceutil/trace.go:171","msg":"trace[803813123] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.398246715s","start":"2026-04-07T15:06:38.3196Z","end":"2026-04-07T15:06:39.717846Z","steps":["trace[803813123] 'process raft request' (duration: 1.397795602s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.717918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.356995Z","time spent":"1.360860854s","remote":"127.0.0.1:54756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:06:39.717926Z","caller":"traceutil/trace.go:171","msg":"trace[1059781972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"1.260900752s","start":"2026-04-07T15:06:38.457013Z","end":"2026-04-07T15:06:39.717914Z","steps":["trace[1059781972] 'agreement among raft nodes before linearized reading' (duration: 1.2608049s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.717966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.456988Z","time spent":"1.260969664s","remote":"127.0.0.1:54654","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-04-07T15:06:39.718005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.319562Z","time spent":"1.398385839s","remote":"127.0.0.1:54578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:06:39.82756Z","caller":"traceutil/trace.go:171","msg":"trace[948708020] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.353949109s","start":"2026-04-07T15:06:38.473587Z","end":"2026-04-07T15:06:39.827536Z","steps":["trace[948708020] 'process raft request' (duration: 1.34432698s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.827695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.47354Z","time spent":"1.354086802s","remote":"127.0.0.1:54564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:06:39.827775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332264092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T15:06:39.827559Z","caller":"traceutil/trace.go:171","msg":"trace[559352572] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"109.741328ms","start":"2026-04-07T15:06:39.717789Z","end":"2026-04-07T15:06:39.82753Z","steps":["trace[559352572] 'read index received' (duration: 100.141399ms)","trace[559352572] 'applied index is now lower than readState.Index' (duration: 9.598959ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:06:39.827993Z","caller":"traceutil/trace.go:171","msg":"trace[2092022185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.332380646s","start":"2026-04-07T15:06:38.495437Z","end":"2026-04-07T15:06:39.827818Z","steps":["trace[2092022185] 'agreement among raft nodes before linearized reading' (duration: 1.33217958s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.828123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.495425Z","time spent":"1.332661092s","remote":"127.0.0.1:54654","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-04-07T15:06:39.828133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.479451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:06:39.828162Z","caller":"traceutil/trace.go:171","msg":"trace[853274347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"864.548243ms","start":"2026-04-07T15:06:38.963605Z","end":"2026-04-07T15:06:39.828153Z","steps":["trace[853274347] 'agreement among raft nodes before linearized reading' (duration: 864.46105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.828194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.963574Z","time spent":"864.612344ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:06:39.828194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129298557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:39.828225Z","caller":"traceutil/trace.go:171","msg":"trace[1988128123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"1.12940975s","start":"2026-04-07T15:06:38.698807Z","end":"2026-04-07T15:06:39.828217Z","steps":["trace[1988128123] 'agreement among raft nodes before linearized reading' (duration: 1.129349159s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.828246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.698773Z","time spent":"1.129469212s","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T15:06:39.828044Z","caller":"traceutil/trace.go:171","msg":"trace[841200010] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.227882242s","start":"2026-04-07T15:06:38.600151Z","end":"2026-04-07T15:06:39.828033Z","steps":["trace[841200010] 'process raft request' (duration: 1.227322507s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.828431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:38.600134Z","time spent":"1.22821717s","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:06:39.869092Z","caller":"traceutil/trace.go:171","msg":"trace[415533290] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"146.145129ms","start":"2026-04-07T15:06:39.72292Z","end":"2026-04-07T15:06:39.869066Z","steps":["trace[415533290] 'process raft request' (duration: 145.85049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:39.869177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.734776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:39.869488Z","caller":"traceutil/trace.go:171","msg":"trace[869025192] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:620; }","duration":"140.061655ms","start":"2026-04-07T15:06:39.729397Z","end":"2026-04-07T15:06:39.869446Z","steps":["trace[869025192] 'agreement among raft nodes before linearized reading' (duration: 139.730546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:40.369486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625708800378904,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T15:06:41.304405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.483636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:06:41.304639Z","caller":"traceutil/trace.go:171","msg":"trace[942074013] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.470438811s","start":"2026-04-07T15:06:39.834178Z","end":"2026-04-07T15:06:41.304617Z","steps":["trace[942074013] 'process raft request' (duration: 1.470337428s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.304708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:39.834168Z","time spent":"1.470510002s","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:06:41.30486Z","caller":"traceutil/trace.go:171","msg":"trace[59736593] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.471849637s","start":"2026-04-07T15:06:39.832968Z","end":"2026-04-07T15:06:41.304818Z","steps":["trace[59736593] 'process raft request' (duration: 964.848948ms)","trace[59736593] 'compare' (duration: 506.112546ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:06:41.305077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:39.832937Z","time spent":"1.472019024s","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:06:41.420551Z","caller":"traceutil/trace.go:171","msg":"trace[1838819294] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:646; }","duration":"1.551590309s","start":"2026-04-07T15:06:39.868936Z","end":"2026-04-07T15:06:41.420527Z","steps":["trace[1838819294] 'read index received' (duration: 928.836938ms)","trace[1838819294] 'applied index is now lower than readState.Index' (duration: 622.752301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:06:41.429015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.734064ms","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-04-07T15:06:41.429231Z","caller":"traceutil/trace.go:171","msg":"trace[1691829966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"630.977872ms","start":"2026-04-07T15:06:40.798223Z","end":"2026-04-07T15:06:41.429201Z","steps":["trace[1691829966] 'agreement among raft nodes before linearized reading' (duration: 630.610713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.429323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:40.7982Z","time spent":"631.111795ms","remote":"127.0.0.1:54654","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-04-07T15:06:41.429808Z","caller":"traceutil/trace.go:171","msg":"trace[748831244] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.162212516s","start":"2026-04-07T15:06:40.267377Z","end":"2026-04-07T15:06:41.42959Z","steps":["trace[748831244] 'process raft request' (duration: 1.147731559s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.430399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.092667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.430752Z","caller":"traceutil/trace.go:171","msg":"trace[1495174032] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"117.491578ms","start":"2026-04-07T15:06:41.312996Z","end":"2026-04-07T15:06:41.430488Z","steps":["trace[1495174032] 'agreement among raft nodes before linearized reading' (duration: 116.733737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.431831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595768419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.431964Z","caller":"traceutil/trace.go:171","msg":"trace[1541988744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"1.59586137s","start":"2026-04-07T15:06:39.836026Z","end":"2026-04-07T15:06:41.431888Z","steps":["trace[1541988744] 'agreement among raft nodes before linearized reading' (duration: 1.595515541s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.432196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:39.836013Z","time spent":"1.596115818s","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:06:41.434788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.563474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.434884Z","caller":"traceutil/trace.go:171","msg":"trace[1428986567] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"100.678628ms","start":"2026-04-07T15:06:41.33417Z","end":"2026-04-07T15:06:41.434849Z","steps":["trace[1428986567] 'agreement among raft nodes before linearized reading' (duration: 100.515073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.434885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:06:40.267316Z","time spent":"1.162516833s","remote":"127.0.0.1:54504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:06:41.434901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.337823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:06:41.435122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.653367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.435169Z","caller":"traceutil/trace.go:171","msg":"trace[1752690258] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"122.688628ms","start":"2026-04-07T15:06:41.312459Z","end":"2026-04-07T15:06:41.435147Z","steps":["trace[1752690258] 'agreement among raft nodes before linearized reading' (duration: 122.631226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.434897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.009595ms","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-04-07T15:06:41.43731Z","caller":"traceutil/trace.go:171","msg":"trace[1381351113] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"172.438141ms","start":"2026-04-07T15:06:41.264837Z","end":"2026-04-07T15:06:41.437275Z","steps":["trace[1381351113] 'agreement among raft nodes before linearized reading' (duration: 170.015606ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:41.438769Z","caller":"traceutil/trace.go:171","msg":"trace[906496229] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"274.172927ms","start":"2026-04-07T15:06:41.164538Z","end":"2026-04-07T15:06:41.438711Z","steps":["trace[906496229] 'agreement among raft nodes before linearized reading' (duration: 270.320273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.443667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.231737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.443704Z","caller":"traceutil/trace.go:171","msg":"trace[309812391] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:623; }","duration":"131.283918ms","start":"2026-04-07T15:06:41.31241Z","end":"2026-04-07T15:06:41.443694Z","steps":["trace[309812391] 'agreement among raft nodes before linearized reading' (duration: 122.803461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.443772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.250458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.443814Z","caller":"traceutil/trace.go:171","msg":"trace[194806644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"109.293619ms","start":"2026-04-07T15:06:41.334515Z","end":"2026-04-07T15:06:41.443809Z","steps":["trace[194806644] 'agreement among raft nodes before linearized reading' (duration: 100.657438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.443923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.786793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.443941Z","caller":"traceutil/trace.go:171","msg":"trace[1324227665] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"277.817283ms","start":"2026-04-07T15:06:41.166119Z","end":"2026-04-07T15:06:41.443937Z","steps":["trace[1324227665] 'agreement among raft nodes before linearized reading' (duration: 269.150152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.444106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.061389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.444125Z","caller":"traceutil/trace.go:171","msg":"trace[1092980026] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:623; }","duration":"110.09394ms","start":"2026-04-07T15:06:41.334026Z","end":"2026-04-07T15:06:41.44412Z","steps":["trace[1092980026] 'agreement among raft nodes before linearized reading' (duration: 101.197291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.444483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.258576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.445426Z","caller":"traceutil/trace.go:171","msg":"trace[945994904] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"178.359519ms","start":"2026-04-07T15:06:41.26619Z","end":"2026-04-07T15:06:41.44455Z","steps":["trace[945994904] 'agreement among raft nodes before linearized reading' (duration: 169.017078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.445423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.872176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.445527Z","caller":"traceutil/trace.go:171","msg":"trace[485042954] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"281.010509ms","start":"2026-04-07T15:06:41.164499Z","end":"2026-04-07T15:06:41.44551Z","steps":["trace[485042954] 'agreement among raft nodes before linearized reading' (duration: 270.689332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:06:41.44568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.61211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:06:41.445723Z","caller":"traceutil/trace.go:171","msg":"trace[1950433647] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:623; }","duration":"180.702453ms","start":"2026-04-07T15:06:41.265013Z","end":"2026-04-07T15:06:41.445716Z","steps":["trace[1950433647] 'agreement among raft nodes before linearized reading' (duration: 170.199791ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:43.382863Z","caller":"traceutil/trace.go:171","msg":"trace[500217747] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"140.431543ms","start":"2026-04-07T15:06:43.242405Z","end":"2026-04-07T15:06:43.382837Z","steps":["trace[500217747] 'process raft request' (duration: 139.806726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:06:46.37415Z","caller":"traceutil/trace.go:171","msg":"trace[797122645] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"118.651119ms","start":"2026-04-07T15:06:46.255413Z","end":"2026-04-07T15:06:46.374064Z","steps":["trace[797122645] 'process raft request' (duration: 118.188457ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:00.150462Z","caller":"traceutil/trace.go:171","msg":"trace[1027810613] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"177.888979ms","start":"2026-04-07T15:06:59.972539Z","end":"2026-04-07T15:07:00.150428Z","steps":["trace[1027810613] 'process raft request' (duration: 177.688653ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:02.820912Z","caller":"traceutil/trace.go:171","msg":"trace[971921638] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"121.069665ms","start":"2026-04-07T15:07:02.699815Z","end":"2026-04-07T15:07:02.820884Z","steps":["trace[971921638] 'read index received' (duration: 120.85959ms)","trace[971921638] 'applied index is now lower than readState.Index' (duration: 209.025µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:07:02.821016Z","caller":"traceutil/trace.go:171","msg":"trace[608400986] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"607.415001ms","start":"2026-04-07T15:07:02.213535Z","end":"2026-04-07T15:07:02.82095Z","steps":["trace[608400986] 'process raft request' (duration: 607.194926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:07:02.821105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.26855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:07:02.82115Z","caller":"traceutil/trace.go:171","msg":"trace[774940858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"121.375273ms","start":"2026-04-07T15:07:02.699765Z","end":"2026-04-07T15:07:02.82114Z","steps":["trace[774940858] 'agreement among raft nodes before linearized reading' (duration: 121.22676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:07:02.821193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:07:02.213524Z","time spent":"607.576016ms","remote":"127.0.0.1:54578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:07:23.454333Z","caller":"traceutil/trace.go:171","msg":"trace[189595642] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"135.694786ms","start":"2026-04-07T15:07:23.318611Z","end":"2026-04-07T15:07:23.454306Z","steps":["trace[189595642] 'process raft request' (duration: 135.495663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:23.57054Z","caller":"traceutil/trace.go:171","msg":"trace[833297275] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1177; }","duration":"178.71258ms","start":"2026-04-07T15:07:23.391786Z","end":"2026-04-07T15:07:23.570498Z","steps":["trace[833297275] 'read index received' (duration: 62.313326ms)","trace[833297275] 'applied index is now lower than readState.Index' (duration: 116.397064ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:07:23.57071Z","caller":"traceutil/trace.go:171","msg":"trace[272380468] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"242.737016ms","start":"2026-04-07T15:07:23.327943Z","end":"2026-04-07T15:07:23.57068Z","steps":["trace[272380468] 'process raft request' (duration: 242.299001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:07:23.570895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.086583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:07:23.570981Z","caller":"traceutil/trace.go:171","msg":"trace[1750218367] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1142; }","duration":"179.252496ms","start":"2026-04-07T15:07:23.391706Z","end":"2026-04-07T15:07:23.570959Z","steps":["trace[1750218367] 'agreement among raft nodes before linearized reading' (duration: 179.083464ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:23.575934Z","caller":"traceutil/trace.go:171","msg":"trace[1720647239] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"112.701212ms","start":"2026-04-07T15:07:23.463203Z","end":"2026-04-07T15:07:23.575904Z","steps":["trace[1720647239] 'process raft request' (duration: 112.504451ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:31.827866Z","caller":"traceutil/trace.go:171","msg":"trace[604515038] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"136.892993ms","start":"2026-04-07T15:07:31.690945Z","end":"2026-04-07T15:07:31.827838Z","steps":["trace[604515038] 'process raft request' (duration: 136.73659ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:31.828049Z","caller":"traceutil/trace.go:171","msg":"trace[1002565278] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1240; }","duration":"128.966614ms","start":"2026-04-07T15:07:31.699052Z","end":"2026-04-07T15:07:31.828019Z","steps":["trace[1002565278] 'read index received' (duration: 128.952133ms)","trace[1002565278] 'applied index is now lower than readState.Index' (duration: 12.021µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:07:31.828212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.138196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:07:31.828252Z","caller":"traceutil/trace.go:171","msg":"trace[568370962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"129.223647ms","start":"2026-04-07T15:07:31.699018Z","end":"2026-04-07T15:07:31.828241Z","steps":["trace[568370962] 'agreement among raft nodes before linearized reading' (duration: 129.118505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:07:32.459565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.288901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:07:32.459666Z","caller":"traceutil/trace.go:171","msg":"trace[1580790164] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"631.529828ms","start":"2026-04-07T15:07:31.828121Z","end":"2026-04-07T15:07:32.459651Z","steps":["trace[1580790164] 'read index received' (duration: 34.010283ms)","trace[1580790164] 'applied index is now lower than readState.Index' (duration: 597.518215ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:07:32.459833Z","caller":"traceutil/trace.go:171","msg":"trace[454721778] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"737.016599ms","start":"2026-04-07T15:07:31.722791Z","end":"2026-04-07T15:07:32.459807Z","steps":["trace[454721778] 'process raft request' (duration: 139.403384ms)","trace[454721778] 'compare' (duration: 597.073948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:07:32.459966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:07:31.722775Z","time spent":"737.12709ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:07:32.460096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.619106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:07:32.460155Z","caller":"traceutil/trace.go:171","msg":"trace[192101552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"630.714777ms","start":"2026-04-07T15:07:31.829427Z","end":"2026-04-07T15:07:32.460142Z","steps":["trace[192101552] 'agreement among raft nodes before linearized reading' (duration: 630.626116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:07:32.46014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.913336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T15:07:32.460192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:07:31.829411Z","time spent":"630.773818ms","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:07:32.460234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.582466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T15:07:32.460257Z","caller":"traceutil/trace.go:171","msg":"trace[1538794945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"716.626236ms","start":"2026-04-07T15:07:31.743624Z","end":"2026-04-07T15:07:32.46025Z","steps":["trace[1538794945] 'agreement among raft nodes before linearized reading' (duration: 716.533994ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:32.460231Z","caller":"traceutil/trace.go:171","msg":"trace[879785128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"687.021898ms","start":"2026-04-07T15:07:31.773191Z","end":"2026-04-07T15:07:32.460212Z","steps":["trace[879785128] 'agreement among raft nodes before linearized reading' (duration: 686.762165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:07:32.460275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:07:31.743612Z","time spent":"716.659496ms","remote":"127.0.0.1:54654","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-04-07T15:07:32.460239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.85166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T15:07:32.460316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:07:31.77318Z","time spent":"687.12132ms","remote":"127.0.0.1:54654","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-04-07T15:07:32.460317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.536683ms","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-04-07T15:07:32.460425Z","caller":"traceutil/trace.go:171","msg":"trace[1964049317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"152.682444ms","start":"2026-04-07T15:07:32.307714Z","end":"2026-04-07T15:07:32.460397Z","steps":["trace[1964049317] 'agreement among raft nodes before linearized reading' (duration: 152.38185ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:32.460323Z","caller":"traceutil/trace.go:171","msg":"trace[23534540] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1202; }","duration":"201.000113ms","start":"2026-04-07T15:07:32.259303Z","end":"2026-04-07T15:07:32.460304Z","steps":["trace[23534540] 'agreement among raft nodes before linearized reading' (duration: 200.8224ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:07:55.808923Z","caller":"traceutil/trace.go:171","msg":"trace[888559831] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"123.201439ms","start":"2026-04-07T15:07:55.685685Z","end":"2026-04-07T15:07:55.808887Z","steps":["trace[888559831] 'process raft request' (duration: 123.004026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:20.570096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.485557ms","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-04-07T15:08:20.570168Z","caller":"traceutil/trace.go:171","msg":"trace[2136964722] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1883; }","duration":"272.602039ms","start":"2026-04-07T15:08:20.297549Z","end":"2026-04-07T15:08:20.570151Z","steps":["trace[2136964722] 'range keys from in-memory index tree' (duration: 272.321964ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:20.668922Z","caller":"traceutil/trace.go:171","msg":"trace[798822550] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"105.730422ms","start":"2026-04-07T15:08:20.563169Z","end":"2026-04-07T15:08:20.668899Z","steps":["trace[798822550] 'process raft request' (duration: 105.268633ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:20.787283Z","caller":"traceutil/trace.go:171","msg":"trace[1895886214] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"110.894396ms","start":"2026-04-07T15:08:20.676364Z","end":"2026-04-07T15:08:20.787259Z","steps":["trace[1895886214] 'process raft request' (duration: 110.754003ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:20.796544Z","caller":"traceutil/trace.go:171","msg":"trace[1337655874] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"101.627808ms","start":"2026-04-07T15:08:20.694895Z","end":"2026-04-07T15:08:20.796523Z","steps":["trace[1337655874] 'process raft request' (duration: 101.410504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:20.796777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.685128ms","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-04-07T15:08:20.796864Z","caller":"traceutil/trace.go:171","msg":"trace[1368377335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1886; }","duration":"101.78617ms","start":"2026-04-07T15:08:20.69502Z","end":"2026-04-07T15:08:20.796806Z","steps":["trace[1368377335] 'agreement among raft nodes before linearized reading' (duration: 101.604348ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:20.797123Z","caller":"traceutil/trace.go:171","msg":"trace[2114327469] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1960; }","duration":"101.348752ms","start":"2026-04-07T15:08:20.695071Z","end":"2026-04-07T15:08:20.79642Z","steps":["trace[2114327469] 'read index received' (duration: 92.136475ms)","trace[2114327469] 'applied index is now lower than readState.Index' (duration: 9.211557ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:08:21.202074Z","caller":"traceutil/trace.go:171","msg":"trace[1549581790] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"399.713892ms","start":"2026-04-07T15:08:20.802328Z","end":"2026-04-07T15:08:21.202042Z","steps":["trace[1549581790] 'process raft request' (duration: 399.637371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:21.202263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:08:20.802315Z","time spent":"399.848124ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:08:21.202581Z","caller":"traceutil/trace.go:171","msg":"trace[148366101] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"401.594826ms","start":"2026-04-07T15:08:20.800976Z","end":"2026-04-07T15:08:21.20257Z","steps":["trace[148366101] 'process raft request' (duration: 400.809253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:21.202645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:08:20.800955Z","time spent":"401.658378ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:08:21.250897Z","caller":"traceutil/trace.go:171","msg":"trace[1837619832] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"281.529909ms","start":"2026-04-07T15:08:20.969341Z","end":"2026-04-07T15:08:21.250871Z","steps":["trace[1837619832] 'process raft request' (duration: 281.296505ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:26.177572Z","caller":"traceutil/trace.go:171","msg":"trace[2043784453] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"222.038589ms","start":"2026-04-07T15:08:25.955495Z","end":"2026-04-07T15:08:26.177534Z","steps":["trace[2043784453] 'process raft request' (duration: 130.928592ms)","trace[2043784453] 'compare' (duration: 90.516736ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:08:37.721925Z","caller":"traceutil/trace.go:171","msg":"trace[1877792506] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"768.688623ms","start":"2026-04-07T15:08:36.953213Z","end":"2026-04-07T15:08:37.721902Z","steps":["trace[1877792506] 'process raft request' (duration: 768.557521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:37.722087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:08:36.953186Z","time spent":"768.814355ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:08:37.72214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.486788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:08:37.721934Z","caller":"traceutil/trace.go:171","msg":"trace[1641976922] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2103; }","duration":"464.308095ms","start":"2026-04-07T15:08:37.257605Z","end":"2026-04-07T15:08:37.721913Z","steps":["trace[1641976922] 'read index received' (duration: 464.301265ms)","trace[1641976922] 'applied index is now lower than readState.Index' (duration: 5.48µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:08:37.722258Z","caller":"traceutil/trace.go:171","msg":"trace[447560506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2022; }","duration":"464.695411ms","start":"2026-04-07T15:08:37.257554Z","end":"2026-04-07T15:08:37.722249Z","steps":["trace[447560506] 'agreement among raft nodes before linearized reading' (duration: 464.425426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:37.72249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:08:37.257534Z","time spent":"464.891736ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:08:37.726124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.819705ms","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-04-07T15:08:37.726405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.876213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T15:08:37.726472Z","caller":"traceutil/trace.go:171","msg":"trace[1128169472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2023; }","duration":"403.971445ms","start":"2026-04-07T15:08:37.322485Z","end":"2026-04-07T15:08:37.726456Z","steps":["trace[1128169472] 'agreement among raft nodes before linearized reading' (duration: 403.785271ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:37.726111Z","caller":"traceutil/trace.go:171","msg":"trace[159605931] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"404.029486ms","start":"2026-04-07T15:08:37.322057Z","end":"2026-04-07T15:08:37.726087Z","steps":["trace[159605931] 'process raft request' (duration: 403.74159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:37.72677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:08:37.322474Z","time spent":"404.054936ms","remote":"127.0.0.1:54654","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-04-07T15:08:37.726734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:08:37.322029Z","time spent":"404.567086ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:08:37.726439Z","caller":"traceutil/trace.go:171","msg":"trace[1656309499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2023; }","duration":"208.171301ms","start":"2026-04-07T15:08:37.518252Z","end":"2026-04-07T15:08:37.726423Z","steps":["trace[1656309499] 'agreement among raft nodes before linearized reading' (duration: 207.697001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:37.727342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.132645ms","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-04-07T15:08:37.727414Z","caller":"traceutil/trace.go:171","msg":"trace[1970483101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2023; }","duration":"408.251327ms","start":"2026-04-07T15:08:37.319148Z","end":"2026-04-07T15:08:37.727399Z","steps":["trace[1970483101] 'agreement among raft nodes before linearized reading' (duration: 407.995191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:37.727455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:08:37.319128Z","time spent":"408.318958ms","remote":"127.0.0.1:54654","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-04-07T15:08:37.726831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.737548ms","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":"info","ts":"2026-04-07T15:08:37.727514Z","caller":"traceutil/trace.go:171","msg":"trace[185118390] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2023; }","duration":"267.473212ms","start":"2026-04-07T15:08:37.460019Z","end":"2026-04-07T15:08:37.727492Z","steps":["trace[185118390] 'agreement among raft nodes before linearized reading' (duration: 266.002963ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:48.512215Z","caller":"traceutil/trace.go:171","msg":"trace[2052650924] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2170; }","duration":"153.976531ms","start":"2026-04-07T15:08:48.358215Z","end":"2026-04-07T15:08:48.512191Z","steps":["trace[2052650924] 'read index received' (duration: 153.767847ms)","trace[2052650924] 'applied index is now lower than readState.Index' (duration: 207.734µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:08:48.512363Z","caller":"traceutil/trace.go:171","msg":"trace[846771822] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"171.484997ms","start":"2026-04-07T15:08:48.340853Z","end":"2026-04-07T15:08:48.512338Z","steps":["trace[846771822] 'process raft request' (duration: 171.18338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:08:48.512825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.592452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:08:48.512895Z","caller":"traceutil/trace.go:171","msg":"trace[831998748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2088; }","duration":"154.707784ms","start":"2026-04-07T15:08:48.358171Z","end":"2026-04-07T15:08:48.512879Z","steps":["trace[831998748] 'agreement among raft nodes before linearized reading' (duration: 154.157124ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:59.981815Z","caller":"traceutil/trace.go:171","msg":"trace[854979405] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"128.191053ms","start":"2026-04-07T15:08:59.853595Z","end":"2026-04-07T15:08:59.981786Z","steps":["trace[854979405] 'process raft request' (duration: 107.120566ms)","trace[854979405] 'compare' (duration: 20.938705ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:08:59.991439Z","caller":"traceutil/trace.go:171","msg":"trace[992613015] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"136.832419ms","start":"2026-04-07T15:08:59.854578Z","end":"2026-04-07T15:08:59.991411Z","steps":["trace[992613015] 'process raft request' (duration: 136.654975ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:08:59.99144Z","caller":"traceutil/trace.go:171","msg":"trace[1497565722] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"137.076064ms","start":"2026-04-07T15:08:59.85433Z","end":"2026-04-07T15:08:59.991406Z","steps":["trace[1497565722] 'process raft request' (duration: 136.728717ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:09:00.780409Z","caller":"traceutil/trace.go:171","msg":"trace[1797613366] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"186.594608ms","start":"2026-04-07T15:09:00.593791Z","end":"2026-04-07T15:09:00.780385Z","steps":["trace[1797613366] 'read index received' (duration: 186.286341ms)","trace[1797613366] 'applied index is now lower than readState.Index' (duration: 307.247µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:09:00.780776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.968805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:09:00.780783Z","caller":"traceutil/trace.go:171","msg":"trace[1988061399] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"228.03853ms","start":"2026-04-07T15:09:00.552725Z","end":"2026-04-07T15:09:00.780763Z","steps":["trace[1988061399] 'process raft request' (duration: 227.424768ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:09:00.780842Z","caller":"traceutil/trace.go:171","msg":"trace[2145039037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2152; }","duration":"187.083727ms","start":"2026-04-07T15:09:00.593738Z","end":"2026-04-07T15:09:00.780822Z","steps":["trace[2145039037] 'agreement among raft nodes before linearized reading' (duration: 186.873632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:09:00.912907Z","caller":"traceutil/trace.go:171","msg":"trace[1637088340] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"108.61256ms","start":"2026-04-07T15:09:00.804263Z","end":"2026-04-07T15:09:00.912876Z","steps":["trace[1637088340] 'process raft request' (duration: 108.403925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:01.488044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.146983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T15:09:01.488117Z","caller":"traceutil/trace.go:171","msg":"trace[420946613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2155; }","duration":"461.267405ms","start":"2026-04-07T15:09:01.026832Z","end":"2026-04-07T15:09:01.488099Z","steps":["trace[420946613] 'range keys from in-memory index tree' (duration: 460.96786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:01.488156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:01.026809Z","time spent":"461.338496ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T15:09:01.802014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.043694ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T15:09:01.802648Z","caller":"traceutil/trace.go:171","msg":"trace[1454393693] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2240; }","duration":"545.644896ms","start":"2026-04-07T15:09:01.256882Z","end":"2026-04-07T15:09:01.802527Z","steps":["trace[1454393693] 'read index received' (duration: 229.109242ms)","trace[1454393693] 'applied index is now lower than readState.Index' (duration: 316.532504ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:09:01.802752Z","caller":"traceutil/trace.go:171","msg":"trace[2081564670] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"310.118873ms","start":"2026-04-07T15:09:01.492448Z","end":"2026-04-07T15:09:01.802566Z","steps":["trace[2081564670] 'process raft request' (duration: 309.735825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:01.802859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.963583ms","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-04-07T15:09:01.803474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:01.492428Z","time spent":"310.93837ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:09:01.803546Z","caller":"traceutil/trace.go:171","msg":"trace[1750207531] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2156; }","duration":"546.690976ms","start":"2026-04-07T15:09:01.256832Z","end":"2026-04-07T15:09:01.803523Z","steps":["trace[1750207531] 'agreement among raft nodes before linearized reading' (duration: 545.964602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:01.803759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:01.256812Z","time spent":"546.920482ms","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:09:01.802924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.252456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:09:01.804061Z","caller":"traceutil/trace.go:171","msg":"trace[2002991487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2156; }","duration":"105.43441ms","start":"2026-04-07T15:09:01.698611Z","end":"2026-04-07T15:09:01.804046Z","steps":["trace[2002991487] 'agreement among raft nodes before linearized reading' (duration: 104.235636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:02.361406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.87841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:09:02.36153Z","caller":"traceutil/trace.go:171","msg":"trace[514870885] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2242; }","duration":"375.642864ms","start":"2026-04-07T15:09:01.985872Z","end":"2026-04-07T15:09:02.361515Z","steps":["trace[514870885] 'read index received' (duration: 113.54735ms)","trace[514870885] 'applied index is now lower than readState.Index' (duration: 262.094174ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:09:02.36173Z","caller":"traceutil/trace.go:171","msg":"trace[380228338] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"507.104003ms","start":"2026-04-07T15:09:01.854616Z","end":"2026-04-07T15:09:02.36172Z","steps":["trace[380228338] 'process raft request' (duration: 244.845975ms)","trace[380228338] 'compare' (duration: 261.563974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:09:02.361774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:01.85459Z","time spent":"507.163506ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:09:02.361939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.081862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T15:09:02.36196Z","caller":"traceutil/trace.go:171","msg":"trace[385928837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2157; }","duration":"376.147624ms","start":"2026-04-07T15:09:01.985806Z","end":"2026-04-07T15:09:02.361953Z","steps":["trace[385928837] 'agreement among raft nodes before linearized reading' (duration: 376.074012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:02.361978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:01.985785Z","time spent":"376.190224ms","remote":"127.0.0.1:54654","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-04-07T15:09:02.36209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.255412ms","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-04-07T15:09:02.362112Z","caller":"traceutil/trace.go:171","msg":"trace[526645084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2157; }","duration":"365.305403ms","start":"2026-04-07T15:09:01.996802Z","end":"2026-04-07T15:09:02.362107Z","steps":["trace[526645084] 'agreement among raft nodes before linearized reading' (duration: 365.250412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:02.362129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:01.996787Z","time spent":"365.338133ms","remote":"127.0.0.1:54654","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":"warn","ts":"2026-04-07T15:09:02.362358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.948272ms","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-04-07T15:09:02.362426Z","caller":"traceutil/trace.go:171","msg":"trace[1942006881] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2157; }","duration":"356.044043ms","start":"2026-04-07T15:09:02.006366Z","end":"2026-04-07T15:09:02.36241Z","steps":["trace[1942006881] 'agreement among raft nodes before linearized reading' (duration: 355.843549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:02.362457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:02.006355Z","time spent":"356.094884ms","remote":"127.0.0.1:50240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T15:09:02.363034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.358233ms","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-04-07T15:09:02.363077Z","caller":"traceutil/trace.go:171","msg":"trace[568529675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2157; }","duration":"363.419535ms","start":"2026-04-07T15:09:01.999651Z","end":"2026-04-07T15:09:02.36307Z","steps":["trace[568529675] 'agreement among raft nodes before linearized reading' (duration: 362.610268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:02.363092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:01.999643Z","time spent":"363.445845ms","remote":"127.0.0.1:54654","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-04-07T15:09:02.560524Z","caller":"traceutil/trace.go:171","msg":"trace[128808907] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"195.395242ms","start":"2026-04-07T15:09:02.365101Z","end":"2026-04-07T15:09:02.560497Z","steps":["trace[128808907] 'process raft request' (duration: 135.307544ms)","trace[128808907] 'compare' (duration: 59.972706ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:09:02.713824Z","caller":"traceutil/trace.go:171","msg":"trace[1135051525] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"347.366988ms","start":"2026-04-07T15:09:02.366431Z","end":"2026-04-07T15:09:02.713798Z","steps":["trace[1135051525] 'process raft request' (duration: 347.265555ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:09:02.713827Z","caller":"traceutil/trace.go:171","msg":"trace[1303778342] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"347.871868ms","start":"2026-04-07T15:09:02.365908Z","end":"2026-04-07T15:09:02.71378Z","steps":["trace[1303778342] 'process raft request' (duration: 347.636383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:02.714002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:02.36642Z","time spent":"347.47847ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:09:02.714018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:09:02.365888Z","time spent":"348.026041ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:09:03.074001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.151914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:09:03.074212Z","caller":"traceutil/trace.go:171","msg":"trace[334919192] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2246; }","duration":"252.324727ms","start":"2026-04-07T15:09:02.82186Z","end":"2026-04-07T15:09:03.074184Z","steps":["trace[334919192] 'read index received' (duration: 122.722µs)","trace[334919192] 'applied index is now lower than readState.Index' (duration: 252.199795ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:09:03.074419Z","caller":"traceutil/trace.go:171","msg":"trace[715707539] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"104.043588ms","start":"2026-04-07T15:09:02.970355Z","end":"2026-04-07T15:09:03.074399Z","steps":["trace[715707539] 'process raft request' (duration: 103.814893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:03.074559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.739833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a41ba02b213f51\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-07T15:09:03.074599Z","caller":"traceutil/trace.go:171","msg":"trace[408913179] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a41ba02b213f51; range_end:; response_count:1; response_revision:2163; }","duration":"245.813005ms","start":"2026-04-07T15:09:02.828773Z","end":"2026-04-07T15:09:03.074586Z","steps":["trace[408913179] 'agreement among raft nodes before linearized reading' (duration: 245.729933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:03.074472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.600573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:09:03.07473Z","caller":"traceutil/trace.go:171","msg":"trace[439274264] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"287.845283ms","start":"2026-04-07T15:09:02.786473Z","end":"2026-04-07T15:09:03.074319Z","steps":["trace[439274264] 'process raft request' (duration: 34.26895ms)","trace[439274264] 'compare' (duration: 253.016952ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:09:03.074725Z","caller":"traceutil/trace.go:171","msg":"trace[1396053332] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"156.851006ms","start":"2026-04-07T15:09:02.917794Z","end":"2026-04-07T15:09:03.074645Z","steps":["trace[1396053332] 'process raft request' (duration: 156.322726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:09:03.074848Z","caller":"traceutil/trace.go:171","msg":"trace[1733546277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2163; }","duration":"252.990151ms","start":"2026-04-07T15:09:02.821818Z","end":"2026-04-07T15:09:03.074809Z","steps":["trace[1733546277] 'agreement among raft nodes before linearized reading' (duration: 252.506351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:09:59.251101Z","caller":"traceutil/trace.go:171","msg":"trace[268973839] linearizableReadLoop","detail":"{readStateIndex:2667; appliedIndex:2666; }","duration":"201.873954ms","start":"2026-04-07T15:09:59.049205Z","end":"2026-04-07T15:09:59.251079Z","steps":["trace[268973839] 'read index received' (duration: 201.689859ms)","trace[268973839] 'applied index is now lower than readState.Index' (duration: 183.115µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:09:59.251204Z","caller":"traceutil/trace.go:171","msg":"trace[632419755] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"206.122797ms","start":"2026-04-07T15:09:59.04505Z","end":"2026-04-07T15:09:59.251173Z","steps":["trace[632419755] 'process raft request' (duration: 205.858161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:09:59.251295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.076557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T15:09:59.251336Z","caller":"traceutil/trace.go:171","msg":"trace[357176559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2568; }","duration":"202.15794ms","start":"2026-04-07T15:09:59.049168Z","end":"2026-04-07T15:09:59.251326Z","steps":["trace[357176559] 'agreement among raft nodes before linearized reading' (duration: 202.005647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:10:33.945775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625708800383468,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T15:10:34.154965Z","caller":"traceutil/trace.go:171","msg":"trace[288027880] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"738.115269ms","start":"2026-04-07T15:10:33.416824Z","end":"2026-04-07T15:10:34.154939Z","steps":["trace[288027880] 'process raft request' (duration: 737.982846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:10:34.155165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:10:33.416793Z","time spent":"738.299552ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:10:34.3688Z","caller":"traceutil/trace.go:171","msg":"trace[1796576154] linearizableReadLoop","detail":"{readStateIndex:2864; appliedIndex:2861; }","duration":"923.071513ms","start":"2026-04-07T15:10:33.445696Z","end":"2026-04-07T15:10:34.368768Z","steps":["trace[1796576154] 'read index received' (duration: 709.205074ms)","trace[1796576154] 'applied index is now lower than readState.Index' (duration: 213.865069ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:10:34.368925Z","caller":"traceutil/trace.go:171","msg":"trace[180060019] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"918.474149ms","start":"2026-04-07T15:10:33.450402Z","end":"2026-04-07T15:10:34.368877Z","steps":["trace[180060019] 'process raft request' (duration: 918.319135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:10:34.369162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:10:33.450386Z","time spent":"918.666574ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:10:34.369263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.804332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T15:10:34.369309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.035778ms","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-04-07T15:10:34.369388Z","caller":"traceutil/trace.go:171","msg":"trace[506777179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2757; }","duration":"917.14059ms","start":"2026-04-07T15:10:33.452226Z","end":"2026-04-07T15:10:34.369367Z","steps":["trace[506777179] 'agreement among raft nodes before linearized reading' (duration: 916.886025ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:10:34.369331Z","caller":"traceutil/trace.go:171","msg":"trace[1087479897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2757; }","duration":"117.915565ms","start":"2026-04-07T15:10:34.2514Z","end":"2026-04-07T15:10:34.369316Z","steps":["trace[1087479897] 'agreement among raft nodes before linearized reading' (duration: 117.714221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:10:34.369365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.630575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T15:10:34.369453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:10:33.45222Z","time spent":"917.220452ms","remote":"127.0.0.1:54654","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":"info","ts":"2026-04-07T15:10:34.369414Z","caller":"traceutil/trace.go:171","msg":"trace[865129442] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"922.236563ms","start":"2026-04-07T15:10:33.447163Z","end":"2026-04-07T15:10:34.369399Z","steps":["trace[865129442] 'process raft request' (duration: 921.391345ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:10:34.369564Z","caller":"traceutil/trace.go:171","msg":"trace[1612022595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2757; }","duration":"923.924351ms","start":"2026-04-07T15:10:33.445615Z","end":"2026-04-07T15:10:34.369539Z","steps":["trace[1612022595] 'agreement among raft nodes before linearized reading' (duration: 923.307147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:10:34.369522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.246635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-07T15:10:34.369624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:10:33.445576Z","time spent":"924.039084ms","remote":"127.0.0.1:54654","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":"info","ts":"2026-04-07T15:10:34.369671Z","caller":"traceutil/trace.go:171","msg":"trace[1414272199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2757; }","duration":"730.46092ms","start":"2026-04-07T15:10:33.639172Z","end":"2026-04-07T15:10:34.369633Z","steps":["trace[1414272199] 'agreement among raft nodes before linearized reading' (duration: 730.091222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:10:34.369729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:10:33.63914Z","time spent":"730.580692ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T15:10:34.369802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:10:33.447126Z","time spent":"922.458778ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:11:06.637529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.849823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:11:06.637672Z","caller":"traceutil/trace.go:171","msg":"trace[1257753236] linearizableReadLoop","detail":"{readStateIndex:3040; appliedIndex:3039; }","duration":"137.210738ms","start":"2026-04-07T15:11:06.500442Z","end":"2026-04-07T15:11:06.637652Z","steps":["trace[1257753236] 'read index received' (duration: 18.102829ms)","trace[1257753236] 'applied index is now lower than readState.Index' (duration: 119.106149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:11:06.638048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.607056ms","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-04-07T15:11:06.637905Z","caller":"traceutil/trace.go:171","msg":"trace[1144157782] transaction","detail":"{read_only:false; response_revision:2926; number_of_response:1; }","duration":"141.872624ms","start":"2026-04-07T15:11:06.495997Z","end":"2026-04-07T15:11:06.63787Z","steps":["trace[1144157782] 'process raft request' (duration: 22.588131ms)","trace[1144157782] 'compare' (duration: 118.693589ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:11:06.638111Z","caller":"traceutil/trace.go:171","msg":"trace[1568123639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2926; }","duration":"137.694069ms","start":"2026-04-07T15:11:06.500399Z","end":"2026-04-07T15:11:06.638093Z","steps":["trace[1568123639] 'agreement among raft nodes before linearized reading' (duration: 137.522205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:06.638099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.294116ms","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-04-07T15:11:06.638297Z","caller":"traceutil/trace.go:171","msg":"trace[909759866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2926; }","duration":"120.515942ms","start":"2026-04-07T15:11:06.517761Z","end":"2026-04-07T15:11:06.638277Z","steps":["trace[909759866] 'agreement among raft nodes before linearized reading' (duration: 120.199755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:06.638361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.208432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T15:11:06.63855Z","caller":"traceutil/trace.go:171","msg":"trace[1419317184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2926; }","duration":"100.52565ms","start":"2026-04-07T15:11:06.537878Z","end":"2026-04-07T15:11:06.638404Z","steps":["trace[1419317184] 'agreement among raft nodes before linearized reading' (duration: 100.137431ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:06.795924Z","caller":"traceutil/trace.go:171","msg":"trace[2045336532] transaction","detail":"{read_only:false; response_revision:2928; number_of_response:1; }","duration":"152.655066ms","start":"2026-04-07T15:11:06.643236Z","end":"2026-04-07T15:11:06.795891Z","steps":["trace[2045336532] 'process raft request' (duration: 139.334135ms)","trace[2045336532] 'compare' (duration: 13.030504ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:11:06.796064Z","caller":"traceutil/trace.go:171","msg":"trace[1701460360] transaction","detail":"{read_only:false; response_revision:2929; number_of_response:1; }","duration":"152.726788ms","start":"2026-04-07T15:11:06.64331Z","end":"2026-04-07T15:11:06.796037Z","steps":["trace[1701460360] 'process raft request' (duration: 152.440812ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:06.796557Z","caller":"traceutil/trace.go:171","msg":"trace[127709898] transaction","detail":"{read_only:false; response_revision:2930; number_of_response:1; }","duration":"149.451214ms","start":"2026-04-07T15:11:06.647076Z","end":"2026-04-07T15:11:06.796528Z","steps":["trace[127709898] 'process raft request' (duration: 148.765549ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:07.47922Z","caller":"traceutil/trace.go:171","msg":"trace[693182389] transaction","detail":"{read_only:false; response_revision:2934; number_of_response:1; }","duration":"199.874314ms","start":"2026-04-07T15:11:07.279313Z","end":"2026-04-07T15:11:07.479187Z","steps":["trace[693182389] 'process raft request' (duration: 199.419144ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:11:30.629586Z","caller":"traceutil/trace.go:171","msg":"trace[1912743134] transaction","detail":"{read_only:false; response_revision:3053; number_of_response:1; }","duration":"144.346845ms","start":"2026-04-07T15:11:30.485207Z","end":"2026-04-07T15:11:30.629554Z","steps":["trace[1912743134] 'process raft request' (duration: 117.622578ms)","trace[1912743134] 'compare' (duration: 26.617844ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:11:53.244038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.845357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:11:53.244439Z","caller":"traceutil/trace.go:171","msg":"trace[2110277828] transaction","detail":"{read_only:false; response_revision:3175; number_of_response:1; }","duration":"136.068472ms","start":"2026-04-07T15:11:53.108283Z","end":"2026-04-07T15:11:53.244351Z","steps":["trace[2110277828] 'process raft request' (duration: 25.83079ms)","trace[2110277828] 'compare' (duration: 109.662361ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:11:57.928991Z","caller":"traceutil/trace.go:171","msg":"trace[1437358411] transaction","detail":"{read_only:false; response_revision:3197; number_of_response:1; }","duration":"166.77451ms","start":"2026-04-07T15:11:57.762186Z","end":"2026-04-07T15:11:57.928961Z","steps":["trace[1437358411] 'process raft request' (duration: 166.374416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:11:59.014988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.01937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:11:59.015151Z","caller":"traceutil/trace.go:171","msg":"trace[549746327] transaction","detail":"{read_only:false; response_revision:3201; number_of_response:1; }","duration":"151.810835ms","start":"2026-04-07T15:11:58.863308Z","end":"2026-04-07T15:11:59.015119Z","steps":["trace[549746327] 'process raft request' (duration: 32.568007ms)","trace[549746327] 'compare' (duration: 118.870394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:21.409172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.249986ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T15:14:21.409777Z","caller":"traceutil/trace.go:171","msg":"trace[1648459417] transaction","detail":"{read_only:false; response_revision:4140; number_of_response:1; }","duration":"220.577381ms","start":"2026-04-07T15:14:21.189153Z","end":"2026-04-07T15:14:21.40973Z","steps":["trace[1648459417] 'process raft request' (duration: 220.272872ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:21.410154Z","caller":"traceutil/trace.go:171","msg":"trace[852093038] transaction","detail":"{read_only:false; response_revision:4141; number_of_response:1; }","duration":"101.992188ms","start":"2026-04-07T15:14:21.30814Z","end":"2026-04-07T15:14:21.410132Z","steps":["trace[852093038] 'process raft request' (duration: 101.440813ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:28.935325Z","caller":"traceutil/trace.go:171","msg":"trace[859790539] transaction","detail":"{read_only:false; response_revision:4519; number_of_response:1; }","duration":"123.455536ms","start":"2026-04-07T15:14:28.811831Z","end":"2026-04-07T15:14:28.935286Z","steps":["trace[859790539] 'process raft request' (duration: 123.188559ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:28.936281Z","caller":"traceutil/trace.go:171","msg":"trace[523537679] linearizableReadLoop","detail":"{readStateIndex:4698; appliedIndex:4698; }","duration":"114.463831ms","start":"2026-04-07T15:14:28.821786Z","end":"2026-04-07T15:14:28.93625Z","steps":["trace[523537679] 'read index received' (duration: 114.45128ms)","trace[523537679] 'applied index is now lower than readState.Index' (duration: 7.231µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:28.936601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.805791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-8bgpv.18a41b94e7b2d022\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-07T15:14:28.936884Z","caller":"traceutil/trace.go:171","msg":"trace[2014708430] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-8bgpv.18a41b94e7b2d022; range_end:; response_count:1; response_revision:4519; }","duration":"115.14682ms","start":"2026-04-07T15:14:28.821715Z","end":"2026-04-07T15:14:28.936862Z","steps":["trace[2014708430] 'agreement among raft nodes before linearized reading' (duration: 114.651406ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:29.209308Z","caller":"traceutil/trace.go:171","msg":"trace[208245664] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"133.533499ms","start":"2026-04-07T15:14:29.07575Z","end":"2026-04-07T15:14:29.209283Z","steps":["trace[208245664] 'process raft request' (duration: 132.725467ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:35.743425Z","caller":"traceutil/trace.go:171","msg":"trace[1374941656] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"269.425169ms","start":"2026-04-07T15:14:35.473939Z","end":"2026-04-07T15:14:35.743364Z","steps":["trace[1374941656] 'process raft request' (duration: 269.223564ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:35.967368Z","caller":"traceutil/trace.go:171","msg":"trace[1175436558] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"486.943883ms","start":"2026-04-07T15:14:35.480386Z","end":"2026-04-07T15:14:35.96733Z","steps":["trace[1175436558] 'process raft request' (duration: 485.962456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:35.967612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:35.480372Z","time spent":"487.1208ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:14:35.967788Z","caller":"traceutil/trace.go:171","msg":"trace[931914774] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"467.106405ms","start":"2026-04-07T15:14:35.500625Z","end":"2026-04-07T15:14:35.967731Z","steps":["trace[931914774] 'process raft request' (duration: 466.623951ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:35.967847Z","caller":"traceutil/trace.go:171","msg":"trace[1262615903] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"370.307075ms","start":"2026-04-07T15:14:35.597506Z","end":"2026-04-07T15:14:35.967813Z","steps":["trace[1262615903] 'process raft request' (duration: 370.094819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:35.967956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.236044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:14:35.968011Z","caller":"traceutil/trace.go:171","msg":"trace[1584877478] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:4680; }","duration":"486.320166ms","start":"2026-04-07T15:14:35.48168Z","end":"2026-04-07T15:14:35.968Z","steps":["trace[1584877478] 'agreement among raft nodes before linearized reading' (duration: 486.203913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:35.96805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:35.48165Z","time spent":"486.39011ms","remote":"127.0.0.1:54692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:14:35.967805Z","caller":"traceutil/trace.go:171","msg":"trace[308781639] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4858; }","duration":"486.08961ms","start":"2026-04-07T15:14:35.481684Z","end":"2026-04-07T15:14:35.967773Z","steps":["trace[308781639] 'read index received' (duration: 260.96164ms)","trace[308781639] 'applied index is now lower than readState.Index' (duration: 225.12615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:35.968257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:35.500612Z","time spent":"467.371763ms","remote":"127.0.0.1:54488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:14:35.968299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:35.597484Z","time spent":"370.476679ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:14:36.045785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.346225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-07T15:14:36.045929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.046202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-pdqzd-tls\" ","response":"range_response_count:1 size:2183"} {"level":"warn","ts":"2026-04-07T15:14:36.045939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.681185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:57130"} {"level":"info","ts":"2026-04-07T15:14:36.045991Z","caller":"traceutil/trace.go:171","msg":"trace[1198763910] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-pdqzd-tls; range_end:; response_count:1; response_revision:4681; }","duration":"177.133014ms","start":"2026-04-07T15:14:35.868838Z","end":"2026-04-07T15:14:36.045971Z","steps":["trace[1198763910] 'agreement among raft nodes before linearized reading' (duration: 177.016571ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:36.046024Z","caller":"traceutil/trace.go:171","msg":"trace[1106641280] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4681; }","duration":"292.929651ms","start":"2026-04-07T15:14:35.753078Z","end":"2026-04-07T15:14:36.046007Z","steps":["trace[1106641280] 'agreement among raft nodes before linearized reading' (duration: 292.595782ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:36.04608Z","caller":"traceutil/trace.go:171","msg":"trace[1253603511] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"176.916799ms","start":"2026-04-07T15:14:35.869122Z","end":"2026-04-07T15:14:36.046039Z","steps":["trace[1253603511] 'process raft request' (duration: 134.997679ms)","trace[1253603511] 'compare' (duration: 41.062846ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:14:36.045903Z","caller":"traceutil/trace.go:171","msg":"trace[210603781] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4681; }","duration":"219.502861ms","start":"2026-04-07T15:14:35.826378Z","end":"2026-04-07T15:14:36.045881Z","steps":["trace[210603781] 'agreement among raft nodes before linearized reading' (duration: 219.277474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:36.045803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.148666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T15:14:36.046323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.505694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-xxk6j\" ","response":"range_response_count:1 size:5150"} {"level":"info","ts":"2026-04-07T15:14:36.04634Z","caller":"traceutil/trace.go:171","msg":"trace[382115913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4681; }","duration":"215.704583ms","start":"2026-04-07T15:14:35.830621Z","end":"2026-04-07T15:14:36.046325Z","steps":["trace[382115913] 'agreement among raft nodes before linearized reading' (duration: 214.946632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:36.046358Z","caller":"traceutil/trace.go:171","msg":"trace[60278229] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-xxk6j; range_end:; response_count:1; response_revision:4681; }","duration":"201.587535ms","start":"2026-04-07T15:14:35.844761Z","end":"2026-04-07T15:14:36.046349Z","steps":["trace[60278229] 'agreement among raft nodes before linearized reading' (duration: 201.465952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:36.046525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.055653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T15:14:36.046681Z","caller":"traceutil/trace.go:171","msg":"trace[384023976] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4681; }","duration":"294.349152ms","start":"2026-04-07T15:14:35.752285Z","end":"2026-04-07T15:14:36.046634Z","steps":["trace[384023976] 'agreement among raft nodes before linearized reading' (duration: 293.315042ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:36.613471Z","caller":"traceutil/trace.go:171","msg":"trace[1364832269] linearizableReadLoop","detail":"{readStateIndex:4877; appliedIndex:4876; }","duration":"271.172942ms","start":"2026-04-07T15:14:36.342269Z","end":"2026-04-07T15:14:36.613442Z","steps":["trace[1364832269] 'read index received' (duration: 270.958576ms)","trace[1364832269] 'applied index is now lower than readState.Index' (duration: 212.896µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:14:36.613574Z","caller":"traceutil/trace.go:171","msg":"trace[1831817114] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"293.742977ms","start":"2026-04-07T15:14:36.319783Z","end":"2026-04-07T15:14:36.613526Z","steps":["trace[1831817114] 'process raft request' (duration: 293.44879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:36.613723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.428038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-07T15:14:36.613769Z","caller":"traceutil/trace.go:171","msg":"trace[370362357] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4694; }","duration":"271.50685ms","start":"2026-04-07T15:14:36.342247Z","end":"2026-04-07T15:14:36.613754Z","steps":["trace[370362357] 'agreement among raft nodes before linearized reading' (duration: 271.342566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:36.61397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.949122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:14:36.614033Z","caller":"traceutil/trace.go:171","msg":"trace[1164957731] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:4694; }","duration":"174.035854ms","start":"2026-04-07T15:14:36.439978Z","end":"2026-04-07T15:14:36.614014Z","steps":["trace[1164957731] 'agreement among raft nodes before linearized reading' (duration: 173.826898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:36.614208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.81722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5eb02221-98a7-4460-b52f-9e8b165d97a5\" ","response":"range_response_count:1 size:3893"} {"level":"info","ts":"2026-04-07T15:14:36.614307Z","caller":"traceutil/trace.go:171","msg":"trace[493819535] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5eb02221-98a7-4460-b52f-9e8b165d97a5; range_end:; response_count:1; response_revision:4694; }","duration":"144.981785ms","start":"2026-04-07T15:14:36.469301Z","end":"2026-04-07T15:14:36.614283Z","steps":["trace[493819535] 'agreement among raft nodes before linearized reading' (duration: 144.808921ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:38.534408Z","caller":"traceutil/trace.go:171","msg":"trace[1239959324] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"120.17729ms","start":"2026-04-07T15:14:38.414191Z","end":"2026-04-07T15:14:38.534368Z","steps":["trace[1239959324] 'process raft request' (duration: 119.947194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:38.852425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.045372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5eb02221-98a7-4460-b52f-9e8b165d97a5\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-04-07T15:14:38.852515Z","caller":"traceutil/trace.go:171","msg":"trace[1643457210] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5eb02221-98a7-4460-b52f-9e8b165d97a5; range_end:; response_count:1; response_revision:4711; }","duration":"228.179717ms","start":"2026-04-07T15:14:38.62431Z","end":"2026-04-07T15:14:38.85249Z","steps":["trace[1643457210] 'range keys from in-memory index tree' (duration: 227.841958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:38.852626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.301118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:14:38.852713Z","caller":"traceutil/trace.go:171","msg":"trace[981674194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4711; }","duration":"154.424383ms","start":"2026-04-07T15:14:38.698264Z","end":"2026-04-07T15:14:38.852688Z","steps":["trace[981674194] 'range keys from in-memory index tree' (duration: 154.232247ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:38.986017Z","caller":"traceutil/trace.go:171","msg":"trace[1535997961] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"128.080262ms","start":"2026-04-07T15:14:38.857906Z","end":"2026-04-07T15:14:38.985986Z","steps":["trace[1535997961] 'process raft request' (duration: 127.922157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.217132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.041438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:14:43.217394Z","caller":"traceutil/trace.go:171","msg":"trace[381538250] linearizableReadLoop","detail":"{readStateIndex:5017; appliedIndex:5015; }","duration":"716.339504ms","start":"2026-04-07T15:14:42.501038Z","end":"2026-04-07T15:14:43.217377Z","steps":["trace[381538250] 'read index received' (duration: 343.901285ms)","trace[381538250] 'applied index is now lower than readState.Index' (duration: 372.436839ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:14:43.217489Z","caller":"traceutil/trace.go:171","msg":"trace[853761868] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"738.30208ms","start":"2026-04-07T15:14:42.479173Z","end":"2026-04-07T15:14:43.217475Z","steps":["trace[853761868] 'process raft request' (duration: 365.83768ms)","trace[853761868] 'compare' (duration: 371.795171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:43.217553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:42.479152Z","time spent":"738.373721ms","remote":"127.0.0.1:54488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:14:43.217767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.882048ms","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":"warn","ts":"2026-04-07T15:14:43.217836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.283771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5eb02221-98a7-4460-b52f-9e8b165d97a5\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-07T15:14:43.21785Z","caller":"traceutil/trace.go:171","msg":"trace[1675325535] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4830; }","duration":"743.028633ms","start":"2026-04-07T15:14:42.4748Z","end":"2026-04-07T15:14:43.217829Z","steps":["trace[1675325535] 'agreement among raft nodes before linearized reading' (duration: 742.695313ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:43.217871Z","caller":"traceutil/trace.go:171","msg":"trace[215047983] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5eb02221-98a7-4460-b52f-9e8b165d97a5; range_end:; response_count:1; response_revision:4830; }","duration":"712.350113ms","start":"2026-04-07T15:14:42.50551Z","end":"2026-04-07T15:14:43.21786Z","steps":["trace[215047983] 'agreement among raft nodes before linearized reading' (duration: 712.221029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.217905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:42.474785Z","time spent":"743.110394ms","remote":"127.0.0.1:54504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3530,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-04-07T15:14:43.217963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:42.505494Z","time spent":"712.403914ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1474,"request content":"key:\"/registry/persistentvolumes/pvc-5eb02221-98a7-4460-b52f-9e8b165d97a5\" "} {"level":"warn","ts":"2026-04-07T15:14:43.218134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.486562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:14:43.218172Z","caller":"traceutil/trace.go:171","msg":"trace[1722176462] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4830; }","duration":"467.548904ms","start":"2026-04-07T15:14:42.750612Z","end":"2026-04-07T15:14:43.218161Z","steps":["trace[1722176462] 'agreement among raft nodes before linearized reading' (duration: 467.477702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.218201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:42.7506Z","time spent":"467.594685ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:14:43.218297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.919154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T15:14:43.218325Z","caller":"traceutil/trace.go:171","msg":"trace[1569006795] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"736.86149ms","start":"2026-04-07T15:14:42.481425Z","end":"2026-04-07T15:14:43.218286Z","steps":["trace[1569006795] 'process raft request' (duration: 735.8169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.218365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.404118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:14:43.218398Z","caller":"traceutil/trace.go:171","msg":"trace[1446244558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4830; }","duration":"518.499182ms","start":"2026-04-07T15:14:42.69989Z","end":"2026-04-07T15:14:43.21839Z","steps":["trace[1446244558] 'agreement among raft nodes before linearized reading' (duration: 518.45037ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:43.218376Z","caller":"traceutil/trace.go:171","msg":"trace[632734028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4830; }","duration":"216.083808ms","start":"2026-04-07T15:14:43.002274Z","end":"2026-04-07T15:14:43.218358Z","steps":["trace[632734028] 'agreement among raft nodes before linearized reading' (duration: 215.736029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.218664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.985532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:14:43.218737Z","caller":"traceutil/trace.go:171","msg":"trace[50850114] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4830; }","duration":"556.324862ms","start":"2026-04-07T15:14:42.662401Z","end":"2026-04-07T15:14:43.218726Z","steps":["trace[50850114] 'agreement among raft nodes before linearized reading' (duration: 556.027943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.21877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:42.662365Z","time spent":"556.395543ms","remote":"127.0.0.1:59100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:14:43.218815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.506596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:14:43.218859Z","caller":"traceutil/trace.go:171","msg":"trace[1113363494] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4830; }","duration":"541.585358ms","start":"2026-04-07T15:14:42.677264Z","end":"2026-04-07T15:14:43.21885Z","steps":["trace[1113363494] 'agreement among raft nodes before linearized reading' (duration: 541.508236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.218888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:42.677247Z","time spent":"541.634229ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":16,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:14:43.218427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:42.699878Z","time spent":"518.541532ms","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:14:43.219016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:42.481415Z","time spent":"736.982373ms","remote":"127.0.0.1:54578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11832,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:14:43.420739Z","caller":"traceutil/trace.go:171","msg":"trace[415746267] linearizableReadLoop","detail":"{readStateIndex:5020; appliedIndex:5017; }","duration":"189.909425ms","start":"2026-04-07T15:14:43.230803Z","end":"2026-04-07T15:14:43.420712Z","steps":["trace[415746267] 'read index received' (duration: 50.079732ms)","trace[415746267] 'applied index is now lower than readState.Index' (duration: 139.828403ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:14:43.420825Z","caller":"traceutil/trace.go:171","msg":"trace[23767458] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"193.850686ms","start":"2026-04-07T15:14:43.226949Z","end":"2026-04-07T15:14:43.4208Z","steps":["trace[23767458] 'process raft request' (duration: 193.531897ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:43.420884Z","caller":"traceutil/trace.go:171","msg":"trace[434881925] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"193.673902ms","start":"2026-04-07T15:14:43.227181Z","end":"2026-04-07T15:14:43.420855Z","steps":["trace[434881925] 'process raft request' (duration: 193.470006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.420999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.168623ms","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-04-07T15:14:43.421043Z","caller":"traceutil/trace.go:171","msg":"trace[342641048] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4833; }","duration":"190.258955ms","start":"2026-04-07T15:14:43.230769Z","end":"2026-04-07T15:14:43.421028Z","steps":["trace[342641048] 'agreement among raft nodes before linearized reading' (duration: 190.076341ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:43.464412Z","caller":"traceutil/trace.go:171","msg":"trace[1166251680] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"177.646413ms","start":"2026-04-07T15:14:43.286741Z","end":"2026-04-07T15:14:43.464387Z","steps":["trace[1166251680] 'process raft request' (duration: 177.093638ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:43.636637Z","caller":"traceutil/trace.go:171","msg":"trace[1717724106] linearizableReadLoop","detail":"{readStateIndex:5024; appliedIndex:5023; }","duration":"154.244647ms","start":"2026-04-07T15:14:43.48237Z","end":"2026-04-07T15:14:43.636615Z","steps":["trace[1717724106] 'read index received' (duration: 153.397133ms)","trace[1717724106] 'applied index is now lower than readState.Index' (duration: 846.354µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:43.636866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.7998ms","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-04-07T15:14:43.636904Z","caller":"traceutil/trace.go:171","msg":"trace[468158158] 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:4837; }","duration":"165.860442ms","start":"2026-04-07T15:14:43.47103Z","end":"2026-04-07T15:14:43.636891Z","steps":["trace[468158158] 'agreement among raft nodes before linearized reading' (duration: 165.723718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:43.636874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.039427ms","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-04-07T15:14:43.637073Z","caller":"traceutil/trace.go:171","msg":"trace[428322545] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4837; }","duration":"151.274673ms","start":"2026-04-07T15:14:43.485777Z","end":"2026-04-07T15:14:43.637051Z","steps":["trace[428322545] 'agreement among raft nodes before linearized reading' (duration: 151.018166ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:43.636857Z","caller":"traceutil/trace.go:171","msg":"trace[940668523] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"159.35185ms","start":"2026-04-07T15:14:43.477335Z","end":"2026-04-07T15:14:43.636687Z","steps":["trace[940668523] 'process raft request' (duration: 159.127404ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:51.575794Z","caller":"traceutil/trace.go:171","msg":"trace[291240009] linearizableReadLoop","detail":"{readStateIndex:5160; appliedIndex:5159; }","duration":"190.060251ms","start":"2026-04-07T15:14:51.385707Z","end":"2026-04-07T15:14:51.575767Z","steps":["trace[291240009] 'read index received' (duration: 189.761173ms)","trace[291240009] 'applied index is now lower than readState.Index' (duration: 298.018µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:14:51.575895Z","caller":"traceutil/trace.go:171","msg":"trace[735346679] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"259.913742ms","start":"2026-04-07T15:14:51.315953Z","end":"2026-04-07T15:14:51.575867Z","steps":["trace[735346679] 'process raft request' (duration: 259.630604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:51.575974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.239377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:14:51.576026Z","caller":"traceutil/trace.go:171","msg":"trace[961545286] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4970; }","duration":"190.331639ms","start":"2026-04-07T15:14:51.38568Z","end":"2026-04-07T15:14:51.576011Z","steps":["trace[961545286] 'agreement among raft nodes before linearized reading' (duration: 190.236257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:51.57613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.219264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:14:51.576164Z","caller":"traceutil/trace.go:171","msg":"trace[2023973510] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4970; }","duration":"132.308158ms","start":"2026-04-07T15:14:51.443842Z","end":"2026-04-07T15:14:51.57615Z","steps":["trace[2023973510] 'agreement among raft nodes before linearized reading' (duration: 132.224475ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:57.130736Z","caller":"traceutil/trace.go:171","msg":"trace[960224817] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"352.361987ms","start":"2026-04-07T15:14:56.778356Z","end":"2026-04-07T15:14:57.130718Z","steps":["trace[960224817] 'process raft request' (duration: 352.072029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:57.130887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:56.778335Z","time spent":"352.45997ms","remote":"127.0.0.1:54578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11654,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:14:57.131072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.150452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:14:57.131136Z","caller":"traceutil/trace.go:171","msg":"trace[796321490] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:5015; }","duration":"309.276245ms","start":"2026-04-07T15:14:56.821843Z","end":"2026-04-07T15:14:57.131119Z","steps":["trace[796321490] 'agreement among raft nodes before linearized reading' (duration: 309.164112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:57.131175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:14:56.821821Z","time spent":"309.344308ms","remote":"127.0.0.1:50504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:14:57.130624Z","caller":"traceutil/trace.go:171","msg":"trace[332565845] linearizableReadLoop","detail":"{readStateIndex:5207; appliedIndex:5206; }","duration":"308.693209ms","start":"2026-04-07T15:14:56.821904Z","end":"2026-04-07T15:14:57.130598Z","steps":["trace[332565845] 'read index received' (duration: 308.446063ms)","trace[332565845] 'applied index is now lower than readState.Index' (duration: 246.396µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:57.131768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.795469ms","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-04-07T15:14:57.131856Z","caller":"traceutil/trace.go:171","msg":"trace[1847637586] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5015; }","duration":"249.896222ms","start":"2026-04-07T15:14:56.881941Z","end":"2026-04-07T15:14:57.131837Z","steps":["trace[1847637586] 'agreement among raft nodes before linearized reading' (duration: 249.614774ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:57.25183Z","caller":"traceutil/trace.go:171","msg":"trace[1782675378] linearizableReadLoop","detail":"{readStateIndex:5210; appliedIndex:5207; }","duration":"102.644368ms","start":"2026-04-07T15:14:57.149158Z","end":"2026-04-07T15:14:57.251803Z","steps":["trace[1782675378] 'read index received' (duration: 37.63095ms)","trace[1782675378] 'applied index is now lower than readState.Index' (duration: 65.012338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:14:57.252067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.879134ms","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-04-07T15:14:57.2521Z","caller":"traceutil/trace.go:171","msg":"trace[700006123] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5018; }","duration":"102.973127ms","start":"2026-04-07T15:14:57.149118Z","end":"2026-04-07T15:14:57.252091Z","steps":["trace[700006123] 'agreement among raft nodes before linearized reading' (duration: 102.787812ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:57.252389Z","caller":"traceutil/trace.go:171","msg":"trace[623308162] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"109.22208ms","start":"2026-04-07T15:14:57.143158Z","end":"2026-04-07T15:14:57.25238Z","steps":["trace[623308162] 'process raft request' (duration: 108.418098ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:57.252581Z","caller":"traceutil/trace.go:171","msg":"trace[838064681] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"103.607604ms","start":"2026-04-07T15:14:57.148966Z","end":"2026-04-07T15:14:57.252574Z","steps":["trace[838064681] 'process raft request' (duration: 102.747781ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:57.727973Z","caller":"traceutil/trace.go:171","msg":"trace[156303207] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"126.486377ms","start":"2026-04-07T15:14:57.601452Z","end":"2026-04-07T15:14:57.727938Z","steps":["trace[156303207] 'process raft request' (duration: 126.181428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:57.778629Z","caller":"traceutil/trace.go:171","msg":"trace[484375689] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"172.035097ms","start":"2026-04-07T15:14:57.606564Z","end":"2026-04-07T15:14:57.778599Z","steps":["trace[484375689] 'process raft request' (duration: 171.701107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:14:57.963673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.831365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:14:57.96377Z","caller":"traceutil/trace.go:171","msg":"trace[923586711] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5024; }","duration":"109.96351ms","start":"2026-04-07T15:14:57.853786Z","end":"2026-04-07T15:14:57.963749Z","steps":["trace[923586711] 'count revisions from in-memory index tree' (duration: 109.719864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:26.529065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.991192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:15:26.529194Z","caller":"traceutil/trace.go:171","msg":"trace[1672391889] linearizableReadLoop","detail":"{readStateIndex:5500; appliedIndex:5499; }","duration":"108.541576ms","start":"2026-04-07T15:15:26.420636Z","end":"2026-04-07T15:15:26.529177Z","steps":["trace[1672391889] 'read index received' (duration: 87.322µs)","trace[1672391889] 'applied index is now lower than readState.Index' (duration: 108.453024ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:26.529291Z","caller":"traceutil/trace.go:171","msg":"trace[1227206237] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"295.243557ms","start":"2026-04-07T15:15:26.234034Z","end":"2026-04-07T15:15:26.529277Z","steps":["trace[1227206237] 'process raft request' (duration: 166.895615ms)","trace[1227206237] 'compare' (duration: 127.751545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:15:26.529531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.284771ms","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-04-07T15:15:26.529579Z","caller":"traceutil/trace.go:171","msg":"trace[1048774510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5301; }","duration":"104.351053ms","start":"2026-04-07T15:15:26.425217Z","end":"2026-04-07T15:15:26.529568Z","steps":["trace[1048774510] 'agreement among raft nodes before linearized reading' (duration: 104.218269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:26.529664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.892216ms","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-04-07T15:15:26.529782Z","caller":"traceutil/trace.go:171","msg":"trace[542242607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5301; }","duration":"109.053739ms","start":"2026-04-07T15:15:26.420703Z","end":"2026-04-07T15:15:26.529757Z","steps":["trace[542242607] 'agreement among raft nodes before linearized reading' (duration: 108.71462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:26.52971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.076131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T15:15:26.529951Z","caller":"traceutil/trace.go:171","msg":"trace[503694956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5301; }","duration":"109.378379ms","start":"2026-04-07T15:15:26.420561Z","end":"2026-04-07T15:15:26.52994Z","steps":["trace[503694956] 'agreement among raft nodes before linearized reading' (duration: 109.090801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:26.530135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.196449ms","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-04-07T15:15:26.530398Z","caller":"traceutil/trace.go:171","msg":"trace[97456852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5301; }","duration":"107.420215ms","start":"2026-04-07T15:15:26.422849Z","end":"2026-04-07T15:15:26.530269Z","steps":["trace[97456852] 'agreement among raft nodes before linearized reading' (duration: 106.976593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:28.633819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.344684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:15:28.633953Z","caller":"traceutil/trace.go:171","msg":"trace[925056800] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:5309; }","duration":"186.59499ms","start":"2026-04-07T15:15:28.447314Z","end":"2026-04-07T15:15:28.633909Z","steps":["trace[925056800] 'count revisions from in-memory index tree' (duration: 186.23254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:28.634307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.479399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:15:28.63434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.315842ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:15:28.634415Z","caller":"traceutil/trace.go:171","msg":"trace[420017529] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:5309; }","duration":"182.606262ms","start":"2026-04-07T15:15:28.451781Z","end":"2026-04-07T15:15:28.634387Z","steps":["trace[420017529] 'count revisions from in-memory index tree' (duration: 182.414968ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:28.634428Z","caller":"traceutil/trace.go:171","msg":"trace[792836452] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5309; }","duration":"216.407465ms","start":"2026-04-07T15:15:28.418007Z","end":"2026-04-07T15:15:28.634415Z","steps":["trace[792836452] 'range keys from in-memory index tree' (duration: 216.301772ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.882879Z","caller":"traceutil/trace.go:171","msg":"trace[621579364] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"222.101782ms","start":"2026-04-07T15:15:30.660708Z","end":"2026-04-07T15:15:30.88281Z","steps":["trace[621579364] 'process raft request' (duration: 175.745791ms)","trace[621579364] 'compare' (duration: 46.073813ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:30.896909Z","caller":"traceutil/trace.go:171","msg":"trace[1690648191] linearizableReadLoop","detail":"{readStateIndex:5523; appliedIndex:5521; }","duration":"232.83499ms","start":"2026-04-07T15:15:30.664039Z","end":"2026-04-07T15:15:30.896874Z","steps":["trace[1690648191] 'read index received' (duration: 172.418321ms)","trace[1690648191] 'applied index is now lower than readState.Index' (duration: 60.415049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:15:30.897299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.228381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:15:30.89763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.06752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6084"} {"level":"warn","ts":"2026-04-07T15:15:30.897704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.568966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:15:30.897842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.628004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-04-07T15:15:30.897918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.967999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-p9lth\" ","response":"range_response_count:1 size:7716"} {"level":"warn","ts":"2026-04-07T15:15:30.898042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.527925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/keystone-credential-keys\" ","response":"range_response_count:1 size:353"} {"level":"info","ts":"2026-04-07T15:15:30.898135Z","caller":"traceutil/trace.go:171","msg":"trace[630756190] range","detail":"{range_begin:/registry/secrets/openstack/keystone-credential-keys; range_end:; response_count:1; response_revision:5326; }","duration":"175.650078ms","start":"2026-04-07T15:15:30.722461Z","end":"2026-04-07T15:15:30.898111Z","steps":["trace[630756190] 'agreement among raft nodes before linearized reading' (duration: 175.109914ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.898276Z","caller":"traceutil/trace.go:171","msg":"trace[714885271] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"235.825681ms","start":"2026-04-07T15:15:30.661097Z","end":"2026-04-07T15:15:30.896923Z","steps":["trace[714885271] 'process raft request' (duration: 235.491132ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.898508Z","caller":"traceutil/trace.go:171","msg":"trace[1545215347] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"228.881604ms","start":"2026-04-07T15:15:30.668268Z","end":"2026-04-07T15:15:30.89715Z","steps":["trace[1545215347] 'process raft request' (duration: 228.506684ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.89859Z","caller":"traceutil/trace.go:171","msg":"trace[1726438349] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"227.718263ms","start":"2026-04-07T15:15:30.669442Z","end":"2026-04-07T15:15:30.89716Z","steps":["trace[1726438349] 'process raft request' (duration: 227.380264ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.898834Z","caller":"traceutil/trace.go:171","msg":"trace[2130044355] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5326; }","duration":"232.211654ms","start":"2026-04-07T15:15:30.665486Z","end":"2026-04-07T15:15:30.897698Z","steps":["trace[2130044355] 'agreement among raft nodes before linearized reading' (duration: 231.914896ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.898944Z","caller":"traceutil/trace.go:171","msg":"trace[1780798773] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5326; }","duration":"228.702139ms","start":"2026-04-07T15:15:30.66908Z","end":"2026-04-07T15:15:30.897782Z","steps":["trace[1780798773] 'agreement among raft nodes before linearized reading' (duration: 228.554535ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.899146Z","caller":"traceutil/trace.go:171","msg":"trace[35754332] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5326; }","duration":"166.734168ms","start":"2026-04-07T15:15:30.731177Z","end":"2026-04-07T15:15:30.897911Z","steps":["trace[35754332] 'agreement among raft nodes before linearized reading' (duration: 166.238304ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.899218Z","caller":"traceutil/trace.go:171","msg":"trace[1658985185] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-p9lth; range_end:; response_count:1; response_revision:5326; }","duration":"106.166494ms","start":"2026-04-07T15:15:30.791835Z","end":"2026-04-07T15:15:30.898001Z","steps":["trace[1658985185] 'agreement among raft nodes before linearized reading' (duration: 105.715731ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:30.898938Z","caller":"traceutil/trace.go:171","msg":"trace[1641752699] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5326; }","duration":"233.388606ms","start":"2026-04-07T15:15:30.664012Z","end":"2026-04-07T15:15:30.8974Z","steps":["trace[1641752699] 'agreement among raft nodes before linearized reading' (duration: 233.152889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:31.145692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.403962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:15:31.146089Z","caller":"traceutil/trace.go:171","msg":"trace[404224396] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"236.293572ms","start":"2026-04-07T15:15:30.90978Z","end":"2026-04-07T15:15:31.146074Z","steps":["trace[404224396] 'process raft request' (duration: 107.431758ms)","trace[404224396] 'compare' (duration: 128.281658ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:31.146182Z","caller":"traceutil/trace.go:171","msg":"trace[1789417012] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5525; }","duration":"180.044554ms","start":"2026-04-07T15:15:30.96613Z","end":"2026-04-07T15:15:31.146175Z","steps":["trace[1789417012] 'read index received' (duration: 50.991016ms)","trace[1789417012] 'applied index is now lower than readState.Index' (duration: 129.052488ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:15:31.147558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.414592ms","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-04-07T15:15:31.147623Z","caller":"traceutil/trace.go:171","msg":"trace[987956201] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5328; }","duration":"181.547295ms","start":"2026-04-07T15:15:30.966059Z","end":"2026-04-07T15:15:31.147606Z","steps":["trace[987956201] 'agreement among raft nodes before linearized reading' (duration: 180.959889ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:16:02.508355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2908} {"level":"info","ts":"2026-04-07T15:16:02.587774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2908,"took":"78.100927ms","hash":1352423783,"current-db-size-bytes":52817920,"current-db-size":"53 MB","current-db-size-in-use-bytes":29065216,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T15:16:02.587835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1352423783,"revision":2908,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:17:01.145555Z","caller":"traceutil/trace.go:171","msg":"trace[367880780] transaction","detail":"{read_only:false; response_revision:5922; number_of_response:1; }","duration":"140.095686ms","start":"2026-04-07T15:17:01.005421Z","end":"2026-04-07T15:17:01.145517Z","steps":["trace[367880780] 'process raft request' (duration: 139.846129ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:03.625057Z","caller":"traceutil/trace.go:171","msg":"trace[1242108088] transaction","detail":"{read_only:false; response_revision:5936; number_of_response:1; }","duration":"113.428007ms","start":"2026-04-07T15:17:03.511549Z","end":"2026-04-07T15:17:03.624977Z","steps":["trace[1242108088] 'process raft request' (duration: 81.029521ms)","trace[1242108088] 'compare' (duration: 32.292623ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:17:06.584409Z","caller":"traceutil/trace.go:171","msg":"trace[1800638725] transaction","detail":"{read_only:false; response_revision:5949; number_of_response:1; }","duration":"134.438934ms","start":"2026-04-07T15:17:06.449945Z","end":"2026-04-07T15:17:06.584384Z","steps":["trace[1800638725] 'process raft request' (duration: 117.537049ms)","trace[1800638725] 'compare' (duration: 16.794222ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:17:06.835019Z","caller":"traceutil/trace.go:171","msg":"trace[1204633400] linearizableReadLoop","detail":"{readStateIndex:6172; appliedIndex:6171; }","duration":"136.068276ms","start":"2026-04-07T15:17:06.698919Z","end":"2026-04-07T15:17:06.834988Z","steps":["trace[1204633400] 'read index received' (duration: 81.4978ms)","trace[1204633400] 'applied index is now lower than readState.Index' (duration: 54.568916ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:17:06.8351Z","caller":"traceutil/trace.go:171","msg":"trace[306686108] transaction","detail":"{read_only:false; response_revision:5951; number_of_response:1; }","duration":"169.026766ms","start":"2026-04-07T15:17:06.666014Z","end":"2026-04-07T15:17:06.835041Z","steps":["trace[306686108] 'process raft request' (duration: 114.364256ms)","trace[306686108] 'compare' (duration: 54.450644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:17:06.835188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.24203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:17:06.83523Z","caller":"traceutil/trace.go:171","msg":"trace[2010224327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5951; }","duration":"136.359313ms","start":"2026-04-07T15:17:06.698859Z","end":"2026-04-07T15:17:06.835219Z","steps":["trace[2010224327] 'agreement among raft nodes before linearized reading' (duration: 136.258571ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:10.752743Z","caller":"traceutil/trace.go:171","msg":"trace[1403706381] linearizableReadLoop","detail":"{readStateIndex:6190; appliedIndex:6189; }","duration":"282.601023ms","start":"2026-04-07T15:17:10.470114Z","end":"2026-04-07T15:17:10.752715Z","steps":["trace[1403706381] 'read index received' (duration: 282.437519ms)","trace[1403706381] 'applied index is now lower than readState.Index' (duration: 162.254µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:17:10.752781Z","caller":"traceutil/trace.go:171","msg":"trace[1155673018] transaction","detail":"{read_only:false; response_revision:5969; number_of_response:1; }","duration":"291.618815ms","start":"2026-04-07T15:17:10.46113Z","end":"2026-04-07T15:17:10.752749Z","steps":["trace[1155673018] 'process raft request' (duration: 291.364327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:17:10.753053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.912701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-07T15:17:10.753115Z","caller":"traceutil/trace.go:171","msg":"trace[1482436900] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5969; }","duration":"283.048144ms","start":"2026-04-07T15:17:10.470052Z","end":"2026-04-07T15:17:10.7531Z","steps":["trace[1482436900] 'agreement among raft nodes before linearized reading' (duration: 282.815868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:10.801809Z","caller":"traceutil/trace.go:171","msg":"trace[1249878528] transaction","detail":"{read_only:false; response_revision:5970; number_of_response:1; }","duration":"170.801946ms","start":"2026-04-07T15:17:10.630981Z","end":"2026-04-07T15:17:10.801783Z","steps":["trace[1249878528] 'process raft request' (duration: 170.698483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:17:10.801975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.034331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:17:10.80206Z","caller":"traceutil/trace.go:171","msg":"trace[766940552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5970; }","duration":"103.175695ms","start":"2026-04-07T15:17:10.698863Z","end":"2026-04-07T15:17:10.802039Z","steps":["trace[766940552] 'agreement among raft nodes before linearized reading' (duration: 103.040482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:17:10.802088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.603496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T15:17:10.80215Z","caller":"traceutil/trace.go:171","msg":"trace[35869011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5970; }","duration":"204.721459ms","start":"2026-04-07T15:17:10.597412Z","end":"2026-04-07T15:17:10.802133Z","steps":["trace[35869011] 'agreement among raft nodes before linearized reading' (duration: 204.38996ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:10.872215Z","caller":"traceutil/trace.go:171","msg":"trace[722654688] transaction","detail":"{read_only:false; response_revision:5971; number_of_response:1; }","duration":"113.143982ms","start":"2026-04-07T15:17:10.759041Z","end":"2026-04-07T15:17:10.872185Z","steps":["trace[722654688] 'process raft request' (duration: 87.183314ms)","trace[722654688] 'compare' (duration: 25.774283ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:17:11.053574Z","caller":"traceutil/trace.go:171","msg":"trace[43361286] linearizableReadLoop","detail":"{readStateIndex:6194; appliedIndex:6193; }","duration":"181.249174ms","start":"2026-04-07T15:17:10.872293Z","end":"2026-04-07T15:17:11.053542Z","steps":["trace[43361286] 'read index received' (duration: 114.398344ms)","trace[43361286] 'applied index is now lower than readState.Index' (duration: 66.84933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:17:11.053746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.318797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:17:11.053811Z","caller":"traceutil/trace.go:171","msg":"trace[81548169] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5973; }","duration":"183.44047ms","start":"2026-04-07T15:17:10.870349Z","end":"2026-04-07T15:17:11.053789Z","steps":["trace[81548169] 'agreement among raft nodes before linearized reading' (duration: 183.315517ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:11.053889Z","caller":"traceutil/trace.go:171","msg":"trace[1335261156] transaction","detail":"{read_only:false; response_revision:5973; number_of_response:1; }","duration":"202.296727ms","start":"2026-04-07T15:17:10.85146Z","end":"2026-04-07T15:17:11.053756Z","steps":["trace[1335261156] 'process raft request' (duration: 135.251631ms)","trace[1335261156] 'compare' (duration: 66.539892ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:17:29.807719Z","caller":"traceutil/trace.go:171","msg":"trace[2057497201] transaction","detail":"{read_only:false; response_revision:6066; number_of_response:1; }","duration":"173.191502ms","start":"2026-04-07T15:17:29.634507Z","end":"2026-04-07T15:17:29.807698Z","steps":["trace[2057497201] 'process raft request' (duration: 172.940155ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:17:29.807932Z","caller":"traceutil/trace.go:171","msg":"trace[1420767063] linearizableReadLoop","detail":"{readStateIndex:6291; appliedIndex:6290; }","duration":"108.774534ms","start":"2026-04-07T15:17:29.698868Z","end":"2026-04-07T15:17:29.807643Z","steps":["trace[1420767063] 'read index received' (duration: 108.482296ms)","trace[1420767063] 'applied index is now lower than readState.Index' (duration: 290.828µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:17:29.808101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.032335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:17:29.808192Z","caller":"traceutil/trace.go:171","msg":"trace[1937864877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6066; }","duration":"108.108697ms","start":"2026-04-07T15:17:29.700024Z","end":"2026-04-07T15:17:29.808132Z","steps":["trace[1937864877] 'agreement among raft nodes before linearized reading' (duration: 107.982653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:17:29.808438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.176484ms","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-04-07T15:17:29.808556Z","caller":"traceutil/trace.go:171","msg":"trace[2051225127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6066; }","duration":"109.710227ms","start":"2026-04-07T15:17:29.698818Z","end":"2026-04-07T15:17:29.808528Z","steps":["trace[2051225127] 'agreement among raft nodes before linearized reading' (duration: 108.994069ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:20.362399Z","caller":"traceutil/trace.go:171","msg":"trace[1479885209] transaction","detail":"{read_only:false; response_revision:6473; number_of_response:1; }","duration":"122.909911ms","start":"2026-04-07T15:18:20.239449Z","end":"2026-04-07T15:18:20.362359Z","steps":["trace[1479885209] 'process raft request' (duration: 122.658844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:20.362539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.141324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:18:20.362644Z","caller":"traceutil/trace.go:171","msg":"trace[1985866809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6473; }","duration":"100.303758ms","start":"2026-04-07T15:18:20.262301Z","end":"2026-04-07T15:18:20.362605Z","steps":["trace[1985866809] 'agreement among raft nodes before linearized reading' (duration: 100.00381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:20.362649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.024492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T15:18:20.362774Z","caller":"traceutil/trace.go:171","msg":"trace[579132783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6473; }","duration":"100.205275ms","start":"2026-04-07T15:18:20.26254Z","end":"2026-04-07T15:18:20.362745Z","steps":["trace[579132783] 'agreement among raft nodes before linearized reading' (duration: 99.920738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:20.363131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.674898ms","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-04-07T15:18:20.363595Z","caller":"traceutil/trace.go:171","msg":"trace[184380362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6473; }","duration":"101.189131ms","start":"2026-04-07T15:18:20.262377Z","end":"2026-04-07T15:18:20.363566Z","steps":["trace[184380362] 'agreement among raft nodes before linearized reading' (duration: 100.044942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:20.524995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.787316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:18:20.525101Z","caller":"traceutil/trace.go:171","msg":"trace[836105847] transaction","detail":"{read_only:false; response_revision:6474; number_of_response:1; }","duration":"157.645059ms","start":"2026-04-07T15:18:20.367438Z","end":"2026-04-07T15:18:20.525083Z","steps":["trace[836105847] 'process raft request' (duration: 38.680019ms)","trace[836105847] 'compare' (duration: 118.682822ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:20.629938Z","caller":"traceutil/trace.go:171","msg":"trace[2014307911] transaction","detail":"{read_only:false; response_revision:6476; number_of_response:1; }","duration":"261.510354ms","start":"2026-04-07T15:18:20.368412Z","end":"2026-04-07T15:18:20.629922Z","steps":["trace[2014307911] 'process raft request' (duration: 261.474254ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:20.62999Z","caller":"traceutil/trace.go:171","msg":"trace[923606108] transaction","detail":"{read_only:false; response_revision:6475; number_of_response:1; }","duration":"261.998207ms","start":"2026-04-07T15:18:20.36796Z","end":"2026-04-07T15:18:20.629958Z","steps":["trace[923606108] 'process raft request' (duration: 259.518394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:23.605566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.96933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:18:23.605694Z","caller":"traceutil/trace.go:171","msg":"trace[497663008] transaction","detail":"{read_only:false; response_revision:6507; number_of_response:1; }","duration":"163.35692ms","start":"2026-04-07T15:18:23.442317Z","end":"2026-04-07T15:18:23.605674Z","steps":["trace[497663008] 'process raft request' (duration: 40.164445ms)","trace[497663008] 'compare' (duration: 122.801315ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:23.606294Z","caller":"traceutil/trace.go:171","msg":"trace[1267598731] transaction","detail":"{read_only:false; response_revision:6508; number_of_response:1; }","duration":"155.184874ms","start":"2026-04-07T15:18:23.451088Z","end":"2026-04-07T15:18:23.606272Z","steps":["trace[1267598731] 'process raft request' (duration: 154.983669ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:23.795098Z","caller":"traceutil/trace.go:171","msg":"trace[1677709685] transaction","detail":"{read_only:false; response_revision:6510; number_of_response:1; }","duration":"141.664321ms","start":"2026-04-07T15:18:23.653401Z","end":"2026-04-07T15:18:23.795065Z","steps":["trace[1677709685] 'process raft request' (duration: 70.40389ms)","trace[1677709685] 'compare' (duration: 71.084807ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:29.749687Z","caller":"traceutil/trace.go:171","msg":"trace[1847137313] transaction","detail":"{read_only:false; response_revision:6558; number_of_response:1; }","duration":"297.568755ms","start":"2026-04-07T15:18:29.452085Z","end":"2026-04-07T15:18:29.749654Z","steps":["trace[1847137313] 'process raft request' (duration: 297.192365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:29.749964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.095107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-07T15:18:29.750081Z","caller":"traceutil/trace.go:171","msg":"trace[1683528102] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6558; }","duration":"273.299942ms","start":"2026-04-07T15:18:29.476757Z","end":"2026-04-07T15:18:29.750057Z","steps":["trace[1683528102] 'agreement among raft nodes before linearized reading' (duration: 272.941713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:29.749541Z","caller":"traceutil/trace.go:171","msg":"trace[608993641] linearizableReadLoop","detail":"{readStateIndex:6799; appliedIndex:6798; }","duration":"272.692967ms","start":"2026-04-07T15:18:29.476824Z","end":"2026-04-07T15:18:29.749517Z","steps":["trace[608993641] 'read index received' (duration: 272.527583ms)","trace[608993641] 'applied index is now lower than readState.Index' (duration: 163.614µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:29.764487Z","caller":"traceutil/trace.go:171","msg":"trace[1218500725] transaction","detail":"{read_only:false; response_revision:6559; number_of_response:1; }","duration":"284.205407ms","start":"2026-04-07T15:18:29.480258Z","end":"2026-04-07T15:18:29.764464Z","steps":["trace[1218500725] 'process raft request' (duration: 283.995282ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:29.764516Z","caller":"traceutil/trace.go:171","msg":"trace[775530052] transaction","detail":"{read_only:false; response_revision:6560; number_of_response:1; }","duration":"283.545061ms","start":"2026-04-07T15:18:29.480936Z","end":"2026-04-07T15:18:29.764481Z","steps":["trace[775530052] 'process raft request' (duration: 283.443948ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:29.764526Z","caller":"traceutil/trace.go:171","msg":"trace[1038732507] transaction","detail":"{read_only:false; response_revision:6561; number_of_response:1; }","duration":"137.441502ms","start":"2026-04-07T15:18:29.627064Z","end":"2026-04-07T15:18:29.764506Z","steps":["trace[1038732507] 'process raft request' (duration: 137.34966ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:29.903098Z","caller":"traceutil/trace.go:171","msg":"trace[1618367911] linearizableReadLoop","detail":"{readStateIndex:6804; appliedIndex:6803; }","duration":"138.57406ms","start":"2026-04-07T15:18:29.764494Z","end":"2026-04-07T15:18:29.903068Z","steps":["trace[1618367911] 'read index received' (duration: 137.827111ms)","trace[1618367911] 'applied index is now lower than readState.Index' (duration: 731.319µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:29.903412Z","caller":"traceutil/trace.go:171","msg":"trace[1416084499] transaction","detail":"{read_only:false; response_revision:6563; number_of_response:1; }","duration":"144.763796ms","start":"2026-04-07T15:18:29.75843Z","end":"2026-04-07T15:18:29.903193Z","steps":["trace[1416084499] 'process raft request' (duration: 144.000947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:29.903479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.591865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:18:29.903623Z","caller":"traceutil/trace.go:171","msg":"trace[1573366323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6563; }","duration":"137.765229ms","start":"2026-04-07T15:18:29.765841Z","end":"2026-04-07T15:18:29.903607Z","steps":["trace[1573366323] 'agreement among raft nodes before linearized reading' (duration: 137.568314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:29.90368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.779943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-07T15:18:29.903783Z","caller":"traceutil/trace.go:171","msg":"trace[1467713436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6563; }","duration":"150.06006ms","start":"2026-04-07T15:18:29.753704Z","end":"2026-04-07T15:18:29.903764Z","steps":["trace[1467713436] 'agreement among raft nodes before linearized reading' (duration: 149.518856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:29.904018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.303943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T15:18:29.904141Z","caller":"traceutil/trace.go:171","msg":"trace[1453027580] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:6563; }","duration":"100.479208ms","start":"2026-04-07T15:18:29.803638Z","end":"2026-04-07T15:18:29.904117Z","steps":["trace[1453027580] 'agreement among raft nodes before linearized reading' (duration: 100.278222ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:47.133373Z","caller":"traceutil/trace.go:171","msg":"trace[134226869] transaction","detail":"{read_only:false; response_revision:6654; number_of_response:1; }","duration":"150.006748ms","start":"2026-04-07T15:18:46.983339Z","end":"2026-04-07T15:18:47.133346Z","steps":["trace[134226869] 'process raft request' (duration: 149.826914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:47.883167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.133545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:18:47.883263Z","caller":"traceutil/trace.go:171","msg":"trace[1686357281] linearizableReadLoop","detail":"{readStateIndex:6901; appliedIndex:6900; }","duration":"891.140888ms","start":"2026-04-07T15:18:46.992107Z","end":"2026-04-07T15:18:47.883248Z","steps":["trace[1686357281] 'read index received' (duration: 141.274377ms)","trace[1686357281] 'applied index is now lower than readState.Index' (duration: 749.865401ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:47.883379Z","caller":"traceutil/trace.go:171","msg":"trace[945813540] transaction","detail":"{read_only:false; response_revision:6655; number_of_response:1; }","duration":"899.195522ms","start":"2026-04-07T15:18:46.984149Z","end":"2026-04-07T15:18:47.883344Z","steps":["trace[945813540] 'process raft request' (duration: 452.790201ms)","trace[945813540] 'compare' (duration: 445.900379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:18:47.883537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.426827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"warn","ts":"2026-04-07T15:18:47.883544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:46.984136Z","time spent":"899.312865ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:18:47.883579Z","caller":"traceutil/trace.go:171","msg":"trace[151377661] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6655; }","duration":"891.506999ms","start":"2026-04-07T15:18:46.992061Z","end":"2026-04-07T15:18:47.883568Z","steps":["trace[151377661] 'agreement among raft nodes before linearized reading' (duration: 891.372815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:47.883612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:46.992041Z","time spent":"891.56347ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4808,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-04-07T15:18:48.444895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.742502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:18:48.444989Z","caller":"traceutil/trace.go:171","msg":"trace[312641381] transaction","detail":"{read_only:false; response_revision:6656; number_of_response:1; }","duration":"616.297047ms","start":"2026-04-07T15:18:47.828674Z","end":"2026-04-07T15:18:48.444971Z","steps":["trace[312641381] 'process raft request' (duration: 180.399732ms)","trace[312641381] 'compare' (duration: 435.528466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:18:48.445054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:47.828652Z","time spent":"616.37044ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:18:48.446512Z","caller":"traceutil/trace.go:171","msg":"trace[1893458509] linearizableReadLoop","detail":"{readStateIndex:6905; appliedIndex:6901; }","duration":"563.20697ms","start":"2026-04-07T15:18:47.883287Z","end":"2026-04-07T15:18:48.446494Z","steps":["trace[1893458509] 'read index received' (duration: 125.816818ms)","trace[1893458509] 'applied index is now lower than readState.Index' (duration: 437.389202ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:48.446524Z","caller":"traceutil/trace.go:171","msg":"trace[191601928] transaction","detail":"{read_only:false; response_revision:6659; number_of_response:1; }","duration":"615.95906ms","start":"2026-04-07T15:18:47.830551Z","end":"2026-04-07T15:18:48.446511Z","steps":["trace[191601928] 'process raft request' (duration: 615.907738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:48.446716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.963653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:18:48.446727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:47.830543Z","time spent":"616.099894ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:18:48.446769Z","caller":"traceutil/trace.go:171","msg":"trace[1913300643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6659; }","duration":"748.061936ms","start":"2026-04-07T15:18:47.698698Z","end":"2026-04-07T15:18:48.44676Z","steps":["trace[1913300643] 'agreement among raft nodes before linearized reading' (duration: 747.890431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:48.446822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:47.698643Z","time spent":"748.172808ms","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:18:48.446851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267891115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T15:18:48.446931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189160913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:18:48.446937Z","caller":"traceutil/trace.go:171","msg":"trace[191035426] transaction","detail":"{read_only:false; response_revision:6658; number_of_response:1; }","duration":"616.530904ms","start":"2026-04-07T15:18:47.830369Z","end":"2026-04-07T15:18:48.4469Z","steps":["trace[191035426] 'process raft request' (duration: 616.053672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:48.447074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.876853ms","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-04-07T15:18:48.447052Z","caller":"traceutil/trace.go:171","msg":"trace[982796008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6659; }","duration":"1.18942721s","start":"2026-04-07T15:18:47.257541Z","end":"2026-04-07T15:18:48.446969Z","steps":["trace[982796008] 'agreement among raft nodes before linearized reading' (duration: 1.18905242s)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:48.446867Z","caller":"traceutil/trace.go:171","msg":"trace[850132519] transaction","detail":"{read_only:false; response_revision:6657; number_of_response:1; }","duration":"616.636917ms","start":"2026-04-07T15:18:47.830195Z","end":"2026-04-07T15:18:48.446832Z","steps":["trace[850132519] 'process raft request' (duration: 616.154754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:48.447131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:47.830359Z","time spent":"616.678278ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:18:48.447155Z","caller":"traceutil/trace.go:171","msg":"trace[2113796465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6659; }","duration":"396.945935ms","start":"2026-04-07T15:18:48.050153Z","end":"2026-04-07T15:18:48.447099Z","steps":["trace[2113796465] 'agreement among raft nodes before linearized reading' (duration: 396.861253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:48.447187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:48.050132Z","time spent":"397.047898ms","remote":"127.0.0.1:54654","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":"warn","ts":"2026-04-07T15:18:48.44712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:47.257525Z","time spent":"1.189583654s","remote":"127.0.0.1:54408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:18:48.44723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:47.830184Z","time spent":"616.965055ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:18:48.44696Z","caller":"traceutil/trace.go:171","msg":"trace[508232808] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:6659; }","duration":"1.26807335s","start":"2026-04-07T15:18:47.178867Z","end":"2026-04-07T15:18:48.44694Z","steps":["trace[508232808] 'agreement among raft nodes before linearized reading' (duration: 1.267848585s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:48.447331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:47.178852Z","time spent":"1.268449139s","remote":"127.0.0.1:50478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T15:18:48.446786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.269349ms","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":"info","ts":"2026-04-07T15:18:48.447429Z","caller":"traceutil/trace.go:171","msg":"trace[515443186] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:6659; }","duration":"929.986186ms","start":"2026-04-07T15:18:47.517421Z","end":"2026-04-07T15:18:48.447408Z","steps":["trace[515443186] 'agreement among raft nodes before linearized reading' (duration: 929.116304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:18:48.447487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:18:47.517401Z","time spent":"930.053539ms","remote":"127.0.0.1:60626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"info","ts":"2026-04-07T15:18:54.764842Z","caller":"traceutil/trace.go:171","msg":"trace[1448622079] transaction","detail":"{read_only:false; response_revision:6691; number_of_response:1; }","duration":"104.17814ms","start":"2026-04-07T15:18:54.660633Z","end":"2026-04-07T15:18:54.764811Z","steps":["trace[1448622079] 'process raft request' (duration: 104.028567ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:56.153788Z","caller":"traceutil/trace.go:171","msg":"trace[1643385102] transaction","detail":"{read_only:false; response_revision:6697; number_of_response:1; }","duration":"199.585349ms","start":"2026-04-07T15:18:55.95418Z","end":"2026-04-07T15:18:56.153765Z","steps":["trace[1643385102] 'process raft request' (duration: 156.101897ms)","trace[1643385102] 'compare' (duration: 42.97923ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:18:56.153861Z","caller":"traceutil/trace.go:171","msg":"trace[2015221927] transaction","detail":"{read_only:false; response_revision:6698; number_of_response:1; }","duration":"159.963222ms","start":"2026-04-07T15:18:55.993877Z","end":"2026-04-07T15:18:56.15384Z","steps":["trace[2015221927] 'process raft request' (duration: 159.597703ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:18:56.153936Z","caller":"traceutil/trace.go:171","msg":"trace[116539090] transaction","detail":"{read_only:false; response_revision:6699; number_of_response:1; }","duration":"140.499434ms","start":"2026-04-07T15:18:56.013378Z","end":"2026-04-07T15:18:56.153878Z","steps":["trace[116539090] 'process raft request' (duration: 140.396621ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:19:22.283212Z","caller":"traceutil/trace.go:171","msg":"trace[1262818202] transaction","detail":"{read_only:false; response_revision:6887; number_of_response:1; }","duration":"126.487211ms","start":"2026-04-07T15:19:22.156685Z","end":"2026-04-07T15:19:22.283173Z","steps":["trace[1262818202] 'process raft request' (duration: 126.260414ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:19:55.090814Z","caller":"traceutil/trace.go:171","msg":"trace[1461835832] transaction","detail":"{read_only:false; response_revision:7077; number_of_response:1; }","duration":"172.994491ms","start":"2026-04-07T15:19:54.917795Z","end":"2026-04-07T15:19:55.09079Z","steps":["trace[1461835832] 'process raft request' (duration: 82.378748ms)","trace[1461835832] 'compare' (duration: 90.523942ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:19:55.113738Z","caller":"traceutil/trace.go:171","msg":"trace[487041763] transaction","detail":"{read_only:false; response_revision:7078; number_of_response:1; }","duration":"194.333245ms","start":"2026-04-07T15:19:54.919376Z","end":"2026-04-07T15:19:55.113709Z","steps":["trace[487041763] 'process raft request' (duration: 194.167231ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:02.535845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5569} {"level":"info","ts":"2026-04-07T15:21:02.74426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5569,"took":"207.046609ms","hash":108291084,"current-db-size-bytes":52817920,"current-db-size":"53 MB","current-db-size-in-use-bytes":18231296,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-07T15:21:02.744358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":108291084,"revision":5569,"compact-revision":2908}