{"level":"warn","ts":"2026-04-20T04:45:04.49863Z","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-20T04:45:04.498733Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-20T04:45:04.498828Z","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-20T04:45:04.498838Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-20T04:45:04.498865Z","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-20T04:45:04.499665Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-04-20T04:45:04.499754Z","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.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-20T04:45:04.503221Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.160548ms"} {"level":"info","ts":"2026-04-20T04:45:04.512737Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-04-20T04:45:04.512824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T04:45:04.51286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-04-20T04:45:04.512873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T04:45:04.512892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-04-20T04:45:04.512939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-04-20T04:45:04.519251Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T04:45:04.521133Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T04:45:04.522102Z","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-20T04:45:04.524066Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T04:45:04.52431Z","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-20T04:45:04.524369Z","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-20T04:45:04.524402Z","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-20T04:45:04.524323Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T04:45:04.525456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-04-20T04:45:04.526614Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-20T04:45:04.531561Z","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-20T04:45:04.531925Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T04:45:04.531957Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T04:45:04.532069Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-20T04:45:04.532117Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-20T04:45:05.213549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T04:45:05.213606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T04:45:05.213638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-04-20T04:45:05.213652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-04-20T04:45:05.213659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-20T04:45:05.21367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-04-20T04:45:05.213679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-20T04:45:05.244553Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:45:05.244547Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T04:45:05.244969Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:45:05.246065Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T04:45:05.246144Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T04:45:05.246382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T04:45:05.248623Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-04-20T04:45:05.246555Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T04:45:05.271696Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:45:05.272008Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:45:05.272106Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T04:45:11.398522Z","caller":"traceutil/trace.go:171","msg":"trace[1982971999] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"151.94128ms","start":"2026-04-20T04:45:11.24656Z","end":"2026-04-20T04:45:11.398501Z","steps":["trace[1982971999] 'read index received' (duration: 109.959175ms)","trace[1982971999] 'applied index is now lower than readState.Index' (duration: 41.981605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:11.398758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.530655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-20T04:45:11.398781Z","caller":"traceutil/trace.go:171","msg":"trace[2082284250] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:271; }","duration":"102.610436ms","start":"2026-04-20T04:45:11.296163Z","end":"2026-04-20T04:45:11.398774Z","steps":["trace[2082284250] 'agreement among raft nodes before linearized reading' (duration: 102.546195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:11.39874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.137564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:45:11.398827Z","caller":"traceutil/trace.go:171","msg":"trace[547307468] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:271; }","duration":"152.283387ms","start":"2026-04-20T04:45:11.246522Z","end":"2026-04-20T04:45:11.398805Z","steps":["trace[547307468] 'agreement among raft nodes before linearized reading' (duration: 152.125994ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:11.399153Z","caller":"traceutil/trace.go:171","msg":"trace[1666603531] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"170.085517ms","start":"2026-04-20T04:45:11.228938Z","end":"2026-04-20T04:45:11.399024Z","steps":["trace[1666603531] 'process raft request' (duration: 127.586493ms)","trace[1666603531] 'compare' (duration: 41.581259ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:11.618113Z","caller":"traceutil/trace.go:171","msg":"trace[2024165011] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"192.651396ms","start":"2026-04-20T04:45:11.425416Z","end":"2026-04-20T04:45:11.618068Z","steps":["trace[2024165011] 'process raft request' (duration: 156.712999ms)","trace[2024165011] 'compare' (duration: 35.646912ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:11.618235Z","caller":"traceutil/trace.go:171","msg":"trace[1281252812] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"146.99275ms","start":"2026-04-20T04:45:11.471226Z","end":"2026-04-20T04:45:11.618219Z","steps":["trace[1281252812] 'process raft request' (duration: 146.712206ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:11.75479Z","caller":"traceutil/trace.go:171","msg":"trace[873261420] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"109.322904ms","start":"2026-04-20T04:45:11.645447Z","end":"2026-04-20T04:45:11.754769Z","steps":["trace[873261420] 'read index received' (duration: 74.73531ms)","trace[873261420] 'applied index is now lower than readState.Index' (duration: 34.586484ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:11.754849Z","caller":"traceutil/trace.go:171","msg":"trace[1594915786] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"124.288169ms","start":"2026-04-20T04:45:11.630534Z","end":"2026-04-20T04:45:11.754823Z","steps":["trace[1594915786] 'process raft request' (duration: 89.660754ms)","trace[1594915786] 'compare' (duration: 34.491873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:11.754906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.449027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-20T04:45:11.754933Z","caller":"traceutil/trace.go:171","msg":"trace[438113332] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:276; }","duration":"109.530807ms","start":"2026-04-20T04:45:11.645393Z","end":"2026-04-20T04:45:11.754924Z","steps":["trace[438113332] 'agreement among raft nodes before linearized reading' (duration: 109.459646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:34.240876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.986579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:45:34.241007Z","caller":"traceutil/trace.go:171","msg":"trace[1774170094] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"401.974581ms","start":"2026-04-20T04:45:33.839007Z","end":"2026-04-20T04:45:34.240982Z","steps":["trace[1774170094] 'process raft request' (duration: 237.247633ms)","trace[1774170094] 'compare' (duration: 163.798067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:34.241183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:33.83898Z","time spent":"402.072754ms","remote":"127.0.0.1:45458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:34.911771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.959187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:45:34.911916Z","caller":"traceutil/trace.go:171","msg":"trace[1232643928] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"665.309051ms","start":"2026-04-20T04:45:34.246587Z","end":"2026-04-20T04:45:34.911896Z","steps":["trace[1232643928] 'process raft request' (duration: 497.166412ms)","trace[1232643928] 'compare' (duration: 167.816675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:34.911974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:34.246554Z","time spent":"665.396432ms","remote":"127.0.0.1:45684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:34.91264Z","caller":"traceutil/trace.go:171","msg":"trace[1249206205] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:454; }","duration":"270.863971ms","start":"2026-04-20T04:45:34.641755Z","end":"2026-04-20T04:45:34.912619Z","steps":["trace[1249206205] 'read index received' (duration: 101.988413ms)","trace[1249206205] 'applied index is now lower than readState.Index' (duration: 168.874558ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:34.912754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.992812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:45:34.912737Z","caller":"traceutil/trace.go:171","msg":"trace[255521333] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"666.053779ms","start":"2026-04-20T04:45:34.246649Z","end":"2026-04-20T04:45:34.912703Z","steps":["trace[255521333] 'process raft request' (duration: 665.895167ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:34.912794Z","caller":"traceutil/trace.go:171","msg":"trace[1779281869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"271.071042ms","start":"2026-04-20T04:45:34.641708Z","end":"2026-04-20T04:45:34.912779Z","steps":["trace[1779281869] 'agreement among raft nodes before linearized reading' (duration: 270.981211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:34.913045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:34.246614Z","time spent":"666.21113ms","remote":"127.0.0.1:45684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:36.926823Z","caller":"traceutil/trace.go:171","msg":"trace[1465557893] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"262.453464ms","start":"2026-04-20T04:45:36.664346Z","end":"2026-04-20T04:45:36.926799Z","steps":["trace[1465557893] 'process raft request' (duration: 262.328252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:37.446784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155923118196002,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:45:37.761933Z","caller":"traceutil/trace.go:171","msg":"trace[56129308] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.093907827s","start":"2026-04-20T04:45:36.668006Z","end":"2026-04-20T04:45:37.761913Z","steps":["trace[56129308] 'process raft request' (duration: 1.093716345s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:37.762037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.667988Z","time spent":"1.093997388s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:37.762028Z","caller":"traceutil/trace.go:171","msg":"trace[156837494] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.085824882s","start":"2026-04-20T04:45:36.676173Z","end":"2026-04-20T04:45:37.761998Z","steps":["trace[156837494] 'process raft request' (duration: 1.085673241s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:37.762156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.676158Z","time spent":"1.085931963s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:37.762186Z","caller":"traceutil/trace.go:171","msg":"trace[137516396] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.084648459s","start":"2026-04-20T04:45:36.67753Z","end":"2026-04-20T04:45:37.762179Z","steps":["trace[137516396] 'process raft request' (duration: 1.084348776s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:37.762212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.677519Z","time spent":"1.084681549s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:37.762138Z","caller":"traceutil/trace.go:171","msg":"trace[973411312] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.093819767s","start":"2026-04-20T04:45:36.668126Z","end":"2026-04-20T04:45:37.761946Z","steps":["trace[973411312] 'process raft request' (duration: 1.093696916s)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:37.762363Z","caller":"traceutil/trace.go:171","msg":"trace[161329395] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.080739162s","start":"2026-04-20T04:45:36.681562Z","end":"2026-04-20T04:45:37.762301Z","steps":["trace[161329395] 'process raft request' (duration: 1.080571031s)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:37.762455Z","caller":"traceutil/trace.go:171","msg":"trace[140793844] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.076510285s","start":"2026-04-20T04:45:36.685932Z","end":"2026-04-20T04:45:37.762442Z","steps":["trace[140793844] 'process raft request' (duration: 1.076354312s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:37.762496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.685901Z","time spent":"1.076580265s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:37.762511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.681493Z","time spent":"1.080948916s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:37.762355Z","caller":"traceutil/trace.go:171","msg":"trace[85548422] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.076458383s","start":"2026-04-20T04:45:36.685886Z","end":"2026-04-20T04:45:37.762344Z","steps":["trace[85548422] 'process raft request' (duration: 1.076371352s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:37.762578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.685862Z","time spent":"1.076695206s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:37.762663Z","caller":"traceutil/trace.go:171","msg":"trace[1989220499] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.058350933s","start":"2026-04-20T04:45:36.704304Z","end":"2026-04-20T04:45:37.762655Z","steps":["trace[1989220499] 'process raft request' (duration: 1.058027719s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:37.762707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.704291Z","time spent":"1.058398493s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:37.762765Z","caller":"traceutil/trace.go:171","msg":"trace[792805136] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.030364128s","start":"2026-04-20T04:45:36.732394Z","end":"2026-04-20T04:45:37.762758Z","steps":["trace[792805136] 'process raft request' (duration: 1.030286947s)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:37.76277Z","caller":"traceutil/trace.go:171","msg":"trace[832352816] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"937.846121ms","start":"2026-04-20T04:45:36.824915Z","end":"2026-04-20T04:45:37.762761Z","steps":["trace[832352816] 'process raft request' (duration: 937.810331ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:37.76276Z","caller":"traceutil/trace.go:171","msg":"trace[316322208] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.058372414s","start":"2026-04-20T04:45:36.704371Z","end":"2026-04-20T04:45:37.762743Z","steps":["trace[316322208] 'process raft request' (duration: 1.058243443s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:37.762796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.732382Z","time spent":"1.030400408s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:37.762805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.824799Z","time spent":"937.987472ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:37.762835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.704367Z","time spent":"1.058428504s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:37.763154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.668118Z","time spent":"1.094301273s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:38.579138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.700267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:45:38.579377Z","caller":"traceutil/trace.go:171","msg":"trace[375403023] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.646982395s","start":"2026-04-20T04:45:36.932372Z","end":"2026-04-20T04:45:38.579354Z","steps":["trace[375403023] 'process raft request' (duration: 921.971155ms)","trace[375403023] 'compare' (duration: 724.529105ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:38.579521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.932349Z","time spent":"1.647088587s","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:38.579552Z","caller":"traceutil/trace.go:171","msg":"trace[873021738] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:494; }","duration":"1.632869811s","start":"2026-04-20T04:45:36.946658Z","end":"2026-04-20T04:45:38.579528Z","steps":["trace[873021738] 'read index received' (duration: 815.246824ms)","trace[873021738] 'applied index is now lower than readState.Index' (duration: 817.621107ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:38.579681Z","caller":"traceutil/trace.go:171","msg":"trace[5184104] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"802.881988ms","start":"2026-04-20T04:45:37.776773Z","end":"2026-04-20T04:45:38.579655Z","steps":["trace[5184104] 'process raft request' (duration: 802.657685ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.579709Z","caller":"traceutil/trace.go:171","msg":"trace[1219247286] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"802.007398ms","start":"2026-04-20T04:45:37.777663Z","end":"2026-04-20T04:45:38.57967Z","steps":["trace[1219247286] 'process raft request' (duration: 801.826576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.579752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633075814s","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-20T04:45:38.579764Z","caller":"traceutil/trace.go:171","msg":"trace[1942164168] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"798.233764ms","start":"2026-04-20T04:45:37.781512Z","end":"2026-04-20T04:45:38.579746Z","steps":["trace[1942164168] 'process raft request' (duration: 798.043232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.579795Z","caller":"traceutil/trace.go:171","msg":"trace[876125677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"1.633162445s","start":"2026-04-20T04:45:36.946622Z","end":"2026-04-20T04:45:38.579784Z","steps":["trace[876125677] 'agreement among raft nodes before linearized reading' (duration: 1.633053014s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.579855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.946608Z","time spent":"1.633238266s","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T04:45:38.579854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.77674Z","time spent":"803.027239ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:38.579873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.777653Z","time spent":"802.128019ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:38.579941Z","caller":"traceutil/trace.go:171","msg":"trace[1820262701] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.644326144s","start":"2026-04-20T04:45:36.935607Z","end":"2026-04-20T04:45:38.579933Z","steps":["trace[1820262701] 'process raft request' (duration: 1.643639957s)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.580036Z","caller":"traceutil/trace.go:171","msg":"trace[687680138] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"803.232721ms","start":"2026-04-20T04:45:37.776792Z","end":"2026-04-20T04:45:38.580025Z","steps":["trace[687680138] 'process raft request' (duration: 802.666616ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.580047Z","caller":"traceutil/trace.go:171","msg":"trace[794034485] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"807.597661ms","start":"2026-04-20T04:45:37.772437Z","end":"2026-04-20T04:45:38.580035Z","steps":["trace[794034485] 'process raft request' (duration: 806.922423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.580053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.781498Z","time spent":"798.311434ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:45:38.580111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.776782Z","time spent":"803.285732ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:45:38.580122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.772396Z","time spent":"807.677782ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:45:38.580192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.935578Z","time spent":"1.644462217s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:45:38.580206Z","caller":"traceutil/trace.go:171","msg":"trace[464501857] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"1.043583319s","start":"2026-04-20T04:45:37.536088Z","end":"2026-04-20T04:45:38.579671Z","steps":["trace[464501857] 'process raft request' (duration: 1.043221824s)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.580207Z","caller":"traceutil/trace.go:171","msg":"trace[14249214] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"800.862304ms","start":"2026-04-20T04:45:37.779333Z","end":"2026-04-20T04:45:38.580195Z","steps":["trace[14249214] 'process raft request' (duration: 800.180066ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.580239Z","caller":"traceutil/trace.go:171","msg":"trace[1054353821] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"807.567371ms","start":"2026-04-20T04:45:37.77266Z","end":"2026-04-20T04:45:38.580228Z","steps":["trace[1054353821] 'process raft request' (duration: 806.739491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.580273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.53605Z","time spent":"1.044188347s","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:45:38.580287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.779324Z","time spent":"800.921175ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:38.580292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.772645Z","time spent":"807.626012ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:38.580519Z","caller":"traceutil/trace.go:171","msg":"trace[829713102] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"793.296747ms","start":"2026-04-20T04:45:37.787212Z","end":"2026-04-20T04:45:38.580509Z","steps":["trace[829713102] 'process raft request' (duration: 792.427418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.580606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.787181Z","time spent":"793.392598ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:45:38.580744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613627008s","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-20T04:45:38.580782Z","caller":"traceutil/trace.go:171","msg":"trace[490488704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"1.613685488s","start":"2026-04-20T04:45:36.96709Z","end":"2026-04-20T04:45:38.580776Z","steps":["trace[490488704] 'agreement among raft nodes before linearized reading' (duration: 1.613588947s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.58081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.967078Z","time spent":"1.613726758s","remote":"127.0.0.1:45608","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-20T04:45:38.580928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.924355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T04:45:38.58097Z","caller":"traceutil/trace.go:171","msg":"trace[1623237574] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:498; }","duration":"232.991776ms","start":"2026-04-20T04:45:38.347968Z","end":"2026-04-20T04:45:38.58096Z","steps":["trace[1623237574] 'agreement among raft nodes before linearized reading' (duration: 232.868725ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.581123Z","caller":"traceutil/trace.go:171","msg":"trace[1867899729] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"793.214346ms","start":"2026-04-20T04:45:37.787898Z","end":"2026-04-20T04:45:38.581113Z","steps":["trace[1867899729] 'process raft request' (duration: 792.533578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.581166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307214874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-20T04:45:38.581193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.787891Z","time spent":"793.262727ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:45:38.58122Z","caller":"traceutil/trace.go:171","msg":"trace[1039223151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"1.307287165s","start":"2026-04-20T04:45:37.273908Z","end":"2026-04-20T04:45:38.581195Z","steps":["trace[1039223151] 'agreement among raft nodes before linearized reading' (duration: 1.307210794s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.581258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.273886Z","time spent":"1.307364496s","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T04:45:38.581303Z","caller":"traceutil/trace.go:171","msg":"trace[177936100] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"740.944984ms","start":"2026-04-20T04:45:37.840349Z","end":"2026-04-20T04:45:38.581294Z","steps":["trace[177936100] 'process raft request' (duration: 740.258586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.581347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.840329Z","time spent":"740.998804ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:38.581362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.228787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:45:38.581406Z","caller":"traceutil/trace.go:171","msg":"trace[1799873428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"754.335998ms","start":"2026-04-20T04:45:37.827058Z","end":"2026-04-20T04:45:38.581394Z","steps":["trace[1799873428] 'agreement among raft nodes before linearized reading' (duration: 754.223387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.581448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:37.82702Z","time spent":"754.416569ms","remote":"127.0.0.1:45370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T04:45:38.581491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602714269s","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-20T04:45:38.581612Z","caller":"traceutil/trace.go:171","msg":"trace[1040550216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"1.602950453s","start":"2026-04-20T04:45:36.978655Z","end":"2026-04-20T04:45:38.581606Z","steps":["trace[1040550216] 'agreement among raft nodes before linearized reading' (duration: 1.602652719s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.581766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:36.978626Z","time spent":"1.603133095s","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T04:45:38.841954Z","caller":"traceutil/trace.go:171","msg":"trace[1906197] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"256.167428ms","start":"2026-04-20T04:45:38.585768Z","end":"2026-04-20T04:45:38.841935Z","steps":["trace[1906197] 'process raft request' (duration: 184.170939ms)","trace[1906197] 'compare' (duration: 71.874848ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:38.84228Z","caller":"traceutil/trace.go:171","msg":"trace[777264695] transaction","detail":"{read_only:false; number_of_response:0; response_revision:499; }","duration":"256.358442ms","start":"2026-04-20T04:45:38.585907Z","end":"2026-04-20T04:45:38.842265Z","steps":["trace[777264695] 'process raft request' (duration: 256.007727ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.842381Z","caller":"traceutil/trace.go:171","msg":"trace[1084793088] transaction","detail":"{read_only:false; number_of_response:0; response_revision:499; }","duration":"256.090159ms","start":"2026-04-20T04:45:38.586268Z","end":"2026-04-20T04:45:38.842358Z","steps":["trace[1084793088] 'process raft request' (duration: 255.920357ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.842467Z","caller":"traceutil/trace.go:171","msg":"trace[904566814] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"255.617922ms","start":"2026-04-20T04:45:38.586829Z","end":"2026-04-20T04:45:38.842447Z","steps":["trace[904566814] 'process raft request' (duration: 255.39815ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.842597Z","caller":"traceutil/trace.go:171","msg":"trace[1904345802] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"249.646585ms","start":"2026-04-20T04:45:38.592942Z","end":"2026-04-20T04:45:38.842588Z","steps":["trace[1904345802] 'process raft request' (duration: 249.442033ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.842715Z","caller":"traceutil/trace.go:171","msg":"trace[1179973281] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"253.479179ms","start":"2026-04-20T04:45:38.589216Z","end":"2026-04-20T04:45:38.842695Z","steps":["trace[1179973281] 'process raft request' (duration: 253.111994ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.842816Z","caller":"traceutil/trace.go:171","msg":"trace[514219153] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"249.792307ms","start":"2026-04-20T04:45:38.593018Z","end":"2026-04-20T04:45:38.84281Z","steps":["trace[514219153] 'process raft request' (duration: 249.526253ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.843072Z","caller":"traceutil/trace.go:171","msg":"trace[75656601] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"249.959159ms","start":"2026-04-20T04:45:38.5931Z","end":"2026-04-20T04:45:38.843059Z","steps":["trace[75656601] 'process raft request' (duration: 249.685656ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.843333Z","caller":"traceutil/trace.go:171","msg":"trace[1883824405] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"245.673569ms","start":"2026-04-20T04:45:38.597649Z","end":"2026-04-20T04:45:38.843323Z","steps":["trace[1883824405] 'process raft request' (duration: 245.326006ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.843588Z","caller":"traceutil/trace.go:171","msg":"trace[585944029] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"245.816781ms","start":"2026-04-20T04:45:38.597725Z","end":"2026-04-20T04:45:38.843542Z","steps":["trace[585944029] 'process raft request' (duration: 245.411276ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.843787Z","caller":"traceutil/trace.go:171","msg":"trace[1795861205] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"243.236332ms","start":"2026-04-20T04:45:38.600539Z","end":"2026-04-20T04:45:38.843775Z","steps":["trace[1795861205] 'process raft request' (duration: 242.738466ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.843951Z","caller":"traceutil/trace.go:171","msg":"trace[726486493] transaction","detail":"{read_only:false; number_of_response:0; response_revision:499; }","duration":"257.965369ms","start":"2026-04-20T04:45:38.585939Z","end":"2026-04-20T04:45:38.843904Z","steps":["trace[726486493] 'process raft request' (duration: 256.155519ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.844536Z","caller":"traceutil/trace.go:171","msg":"trace[190895761] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"214.513184ms","start":"2026-04-20T04:45:38.630007Z","end":"2026-04-20T04:45:38.84452Z","steps":["trace[190895761] 'process raft request' (duration: 213.614214ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.844555Z","caller":"traceutil/trace.go:171","msg":"trace[1240630394] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"155.054386ms","start":"2026-04-20T04:45:38.689487Z","end":"2026-04-20T04:45:38.844542Z","steps":["trace[1240630394] 'process raft request' (duration: 154.956025ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.844615Z","caller":"traceutil/trace.go:171","msg":"trace[13951147] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"236.608927ms","start":"2026-04-20T04:45:38.60799Z","end":"2026-04-20T04:45:38.844599Z","steps":["trace[13951147] 'process raft request' (duration: 235.384024ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.844657Z","caller":"traceutil/trace.go:171","msg":"trace[936009330] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:515; }","duration":"199.392081ms","start":"2026-04-20T04:45:38.645254Z","end":"2026-04-20T04:45:38.844646Z","steps":["trace[936009330] 'read index received' (duration: 124.719201ms)","trace[936009330] 'applied index is now lower than readState.Index' (duration: 74.67211ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:38.844766Z","caller":"traceutil/trace.go:171","msg":"trace[1259810626] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"199.306611ms","start":"2026-04-20T04:45:38.645446Z","end":"2026-04-20T04:45:38.844753Z","steps":["trace[1259810626] 'process raft request' (duration: 198.932667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:38.844774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.505923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:45:38.844808Z","caller":"traceutil/trace.go:171","msg":"trace[1587030466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"199.605696ms","start":"2026-04-20T04:45:38.645193Z","end":"2026-04-20T04:45:38.844799Z","steps":["trace[1587030466] 'agreement among raft nodes before linearized reading' (duration: 199.486764ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:38.96281Z","caller":"traceutil/trace.go:171","msg":"trace[1995686734] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"104.813395ms","start":"2026-04-20T04:45:38.857963Z","end":"2026-04-20T04:45:38.962777Z","steps":["trace[1995686734] 'process raft request' (duration: 98.388492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:39.609073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.080072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:45:39.609499Z","caller":"traceutil/trace.go:171","msg":"trace[339664668] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"630.554158ms","start":"2026-04-20T04:45:38.978924Z","end":"2026-04-20T04:45:39.609478Z","steps":["trace[339664668] 'process raft request' (duration: 84.023081ms)","trace[339664668] 'compare' (duration: 545.91336ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:39.609669Z","caller":"traceutil/trace.go:171","msg":"trace[1181716662] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"576.877809ms","start":"2026-04-20T04:45:39.032759Z","end":"2026-04-20T04:45:39.609637Z","steps":["trace[1181716662] 'process raft request' (duration: 576.495545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:39.609832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:39.032722Z","time spent":"577.03906ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:39.609693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:38.978898Z","time spent":"630.75387ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:45:40.410012Z","caller":"traceutil/trace.go:171","msg":"trace[1322907894] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"151.484362ms","start":"2026-04-20T04:45:40.258502Z","end":"2026-04-20T04:45:40.409986Z","steps":["trace[1322907894] 'process raft request' (duration: 151.311991ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:40.443115Z","caller":"traceutil/trace.go:171","msg":"trace[909316463] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"138.229445ms","start":"2026-04-20T04:45:40.304869Z","end":"2026-04-20T04:45:40.443099Z","steps":["trace[909316463] 'process raft request' (duration: 138.152333ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:40.443173Z","caller":"traceutil/trace.go:171","msg":"trace[297448780] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"148.189966ms","start":"2026-04-20T04:45:40.294936Z","end":"2026-04-20T04:45:40.443126Z","steps":["trace[297448780] 'process raft request' (duration: 147.720021ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:42.701991Z","caller":"traceutil/trace.go:171","msg":"trace[518480156] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"304.61541ms","start":"2026-04-20T04:45:42.397347Z","end":"2026-04-20T04:45:42.701963Z","steps":["trace[518480156] 'process raft request' (duration: 304.197646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:42.70213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:42.397314Z","time spent":"304.751091ms","remote":"127.0.0.1:45450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:45:43.818098Z","caller":"traceutil/trace.go:171","msg":"trace[814269896] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"103.665758ms","start":"2026-04-20T04:45:43.713782Z","end":"2026-04-20T04:45:43.817447Z","steps":["trace[814269896] 'process raft request' (duration: 102.949189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:44.032028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.983286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:45:44.03212Z","caller":"traceutil/trace.go:171","msg":"trace[878625945] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:571; }","duration":"115.123837ms","start":"2026-04-20T04:45:43.916975Z","end":"2026-04-20T04:45:44.032098Z","steps":["trace[878625945] 'range keys from in-memory index tree' (duration: 114.912595ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:44.713592Z","caller":"traceutil/trace.go:171","msg":"trace[288917004] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"132.898641ms","start":"2026-04-20T04:45:44.580668Z","end":"2026-04-20T04:45:44.713567Z","steps":["trace[288917004] 'process raft request' (duration: 132.742709ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:45.845159Z","caller":"traceutil/trace.go:171","msg":"trace[1417381454] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"144.777705ms","start":"2026-04-20T04:45:45.700365Z","end":"2026-04-20T04:45:45.845143Z","steps":["trace[1417381454] 'read index received' (duration: 144.203049ms)","trace[1417381454] 'applied index is now lower than readState.Index' (duration: 574.106µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:45.845242Z","caller":"traceutil/trace.go:171","msg":"trace[1482393536] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"187.932245ms","start":"2026-04-20T04:45:45.657293Z","end":"2026-04-20T04:45:45.845225Z","steps":["trace[1482393536] 'process raft request' (duration: 187.47277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:45.845313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.922567ms","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-20T04:45:45.845357Z","caller":"traceutil/trace.go:171","msg":"trace[1276101180] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:580; }","duration":"145.007227ms","start":"2026-04-20T04:45:45.700341Z","end":"2026-04-20T04:45:45.845349Z","steps":["trace[1276101180] 'agreement among raft nodes before linearized reading' (duration: 144.882576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:45.84558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.133029ms","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-20T04:45:45.845599Z","caller":"traceutil/trace.go:171","msg":"trace[949547159] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:580; }","duration":"145.190031ms","start":"2026-04-20T04:45:45.700405Z","end":"2026-04-20T04:45:45.845595Z","steps":["trace[949547159] 'agreement among raft nodes before linearized reading' (duration: 145.156469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:45.845783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.972127ms","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-20T04:45:45.845801Z","caller":"traceutil/trace.go:171","msg":"trace[262785236] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:580; }","duration":"145.002797ms","start":"2026-04-20T04:45:45.700795Z","end":"2026-04-20T04:45:45.845797Z","steps":["trace[262785236] 'agreement among raft nodes before linearized reading' (duration: 144.977217ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:47.361388Z","caller":"traceutil/trace.go:171","msg":"trace[1597685647] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"500.747429ms","start":"2026-04-20T04:45:46.8606Z","end":"2026-04-20T04:45:47.361347Z","steps":["trace[1597685647] 'process raft request' (duration: 496.859397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:47.361467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.694556ms","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-20T04:45:47.361647Z","caller":"traceutil/trace.go:171","msg":"trace[130467573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"430.930429ms","start":"2026-04-20T04:45:46.930703Z","end":"2026-04-20T04:45:47.361634Z","steps":["trace[130467573] 'agreement among raft nodes before linearized reading' (duration: 430.699167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:47.361683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:46.930675Z","time spent":"431.000541ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T04:45:47.361838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:46.860572Z","time spent":"501.165022ms","remote":"127.0.0.1:45450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:45:47.361876Z","caller":"traceutil/trace.go:171","msg":"trace[1315253254] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"430.534005ms","start":"2026-04-20T04:45:46.930763Z","end":"2026-04-20T04:45:47.361297Z","steps":["trace[1315253254] 'read index received' (duration: 423.811224ms)","trace[1315253254] 'applied index is now lower than readState.Index' (duration: 6.721791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:47.36211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.037158ms","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-20T04:45:47.362146Z","caller":"traceutil/trace.go:171","msg":"trace[1460962963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"428.13603ms","start":"2026-04-20T04:45:46.934Z","end":"2026-04-20T04:45:47.362136Z","steps":["trace[1460962963] 'agreement among raft nodes before linearized reading' (duration: 428.069999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:47.362168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:46.933975Z","time spent":"428.18776ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T04:45:47.362169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.896005ms","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-20T04:45:47.362196Z","caller":"traceutil/trace.go:171","msg":"trace[242302764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"388.944295ms","start":"2026-04-20T04:45:46.973244Z","end":"2026-04-20T04:45:47.362188Z","steps":["trace[242302764] 'agreement among raft nodes before linearized reading' (duration: 388.857424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:47.362219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:46.973233Z","time spent":"388.981965ms","remote":"127.0.0.1:45608","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-20T04:45:47.362291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.099147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T04:45:47.36234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.476433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h8lf6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-20T04:45:47.362359Z","caller":"traceutil/trace.go:171","msg":"trace[46471755] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h8lf6; range_end:; response_count:1; response_revision:587; }","duration":"158.550124ms","start":"2026-04-20T04:45:47.203802Z","end":"2026-04-20T04:45:47.362352Z","steps":["trace[46471755] 'agreement among raft nodes before linearized reading' (duration: 158.464793ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:47.363867Z","caller":"traceutil/trace.go:171","msg":"trace[1020564810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"424.183547ms","start":"2026-04-20T04:45:46.938148Z","end":"2026-04-20T04:45:47.362331Z","steps":["trace[1020564810] 'agreement among raft nodes before linearized reading' (duration: 423.973186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:47.363941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:46.938133Z","time spent":"425.789644ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T04:45:48.324319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155923118196251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T04:45:48.385156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.8439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:45:48.38537Z","caller":"traceutil/trace.go:171","msg":"trace[15142964] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.006677952s","start":"2026-04-20T04:45:47.378678Z","end":"2026-04-20T04:45:48.385356Z","steps":["trace[15142964] 'process raft request' (duration: 1.006633161s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:48.385417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:47.378659Z","time spent":"1.006735763s","remote":"127.0.0.1:45540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:45:48.385552Z","caller":"traceutil/trace.go:171","msg":"trace[1051905006] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.015028889s","start":"2026-04-20T04:45:47.370515Z","end":"2026-04-20T04:45:48.385544Z","steps":["trace[1051905006] 'process raft request' (duration: 115.688744ms)","trace[1051905006] 'compare' (duration: 898.645088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:48.38559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:47.369982Z","time spent":"1.015586296s","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:45:48.385964Z","caller":"traceutil/trace.go:171","msg":"trace[978287312] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.015332423s","start":"2026-04-20T04:45:47.370603Z","end":"2026-04-20T04:45:48.385935Z","steps":["trace[978287312] 'process raft request' (duration: 1.014629146s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:48.386096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:47.370598Z","time spent":"1.015423634s","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:45:48.386071Z","caller":"traceutil/trace.go:171","msg":"trace[937264817] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.015403903s","start":"2026-04-20T04:45:47.370633Z","end":"2026-04-20T04:45:48.386037Z","steps":["trace[937264817] 'process raft request' (duration: 1.014638365s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:48.386248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:47.370629Z","time spent":"1.015527864s","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:45:48.439144Z","caller":"traceutil/trace.go:171","msg":"trace[602192041] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"615.25538ms","start":"2026-04-20T04:45:47.823872Z","end":"2026-04-20T04:45:48.439127Z","steps":["trace[602192041] 'read index received' (duration: 615.143679ms)","trace[602192041] 'applied index is now lower than readState.Index' (duration: 110.881µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:48.439232Z","caller":"traceutil/trace.go:171","msg":"trace[1984663346] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.017236059s","start":"2026-04-20T04:45:47.42199Z","end":"2026-04-20T04:45:48.439226Z","steps":["trace[1984663346] 'process raft request' (duration: 1.017041757s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:48.439293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:47.421963Z","time spent":"1.017286329s","remote":"127.0.0.1:45590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:45:48.439446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.558343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:45:48.439505Z","caller":"traceutil/trace.go:171","msg":"trace[1023748530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"615.653804ms","start":"2026-04-20T04:45:47.823838Z","end":"2026-04-20T04:45:48.439491Z","steps":["trace[1023748530] 'agreement among raft nodes before linearized reading' (duration: 615.552742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:48.439541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:47.823804Z","time spent":"615.729055ms","remote":"127.0.0.1:45370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T04:45:48.943194Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155923118196259,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:45:48.998973Z","caller":"traceutil/trace.go:171","msg":"trace[138191257] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"556.476992ms","start":"2026-04-20T04:45:48.44247Z","end":"2026-04-20T04:45:48.998947Z","steps":["trace[138191257] 'read index received' (duration: 538.102291ms)","trace[138191257] 'applied index is now lower than readState.Index' (duration: 18.372321ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:48.999197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.713484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-20T04:45:48.999234Z","caller":"traceutil/trace.go:171","msg":"trace[812362040] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:594; }","duration":"556.789716ms","start":"2026-04-20T04:45:48.442434Z","end":"2026-04-20T04:45:48.999224Z","steps":["trace[812362040] 'agreement among raft nodes before linearized reading' (duration: 556.643114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:48.999265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:48.442416Z","time spent":"556.840276ms","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-20T04:45:48.999369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:48.440479Z","time spent":"558.886597ms","remote":"127.0.0.1:45394","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T04:45:48.999443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.717133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:45:48.999597Z","caller":"traceutil/trace.go:171","msg":"trace[1010559227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"355.907675ms","start":"2026-04-20T04:45:48.64368Z","end":"2026-04-20T04:45:48.999587Z","steps":["trace[1010559227] 'agreement among raft nodes before linearized reading' (duration: 355.740323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:48.999632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:48.643647Z","time spent":"355.976826ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T04:45:50.67259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.943749ms","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-20T04:45:50.672668Z","caller":"traceutil/trace.go:171","msg":"trace[591985488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"283.07752ms","start":"2026-04-20T04:45:50.389575Z","end":"2026-04-20T04:45:50.672653Z","steps":["trace[591985488] 'range keys from in-memory index tree' (duration: 282.767187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:50.672988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.666906ms","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-20T04:45:50.673009Z","caller":"traceutil/trace.go:171","msg":"trace[730374364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"281.743537ms","start":"2026-04-20T04:45:50.39126Z","end":"2026-04-20T04:45:50.673003Z","steps":["trace[730374364] 'range keys from in-memory index tree' (duration: 281.528794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:50.672996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.383094ms","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-20T04:45:50.673043Z","caller":"traceutil/trace.go:171","msg":"trace[50569920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"283.478145ms","start":"2026-04-20T04:45:50.389552Z","end":"2026-04-20T04:45:50.67303Z","steps":["trace[50569920] 'range keys from in-memory index tree' (duration: 283.169942ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:50.675734Z","caller":"traceutil/trace.go:171","msg":"trace[1551924110] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"272.149299ms","start":"2026-04-20T04:45:50.403557Z","end":"2026-04-20T04:45:50.675707Z","steps":["trace[1551924110] 'process raft request' (duration: 271.985207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:52.912766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.202151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-20T04:45:52.912852Z","caller":"traceutil/trace.go:171","msg":"trace[566277489] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"106.308492ms","start":"2026-04-20T04:45:52.806523Z","end":"2026-04-20T04:45:52.912832Z","steps":["trace[566277489] 'range keys from in-memory index tree' (duration: 105.930719ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:53.511876Z","caller":"traceutil/trace.go:171","msg":"trace[186657620] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"254.821573ms","start":"2026-04-20T04:45:53.257018Z","end":"2026-04-20T04:45:53.511839Z","steps":["trace[186657620] 'process raft request' (duration: 252.115085ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:53.577573Z","caller":"traceutil/trace.go:171","msg":"trace[1372358107] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:683; }","duration":"155.201425ms","start":"2026-04-20T04:45:53.422344Z","end":"2026-04-20T04:45:53.577546Z","steps":["trace[1372358107] 'read index received' (duration: 86.80459ms)","trace[1372358107] 'applied index is now lower than readState.Index' (duration: 68.394675ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:45:53.577661Z","caller":"traceutil/trace.go:171","msg":"trace[1286568727] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"171.248246ms","start":"2026-04-20T04:45:53.406383Z","end":"2026-04-20T04:45:53.577631Z","steps":["trace[1286568727] 'process raft request' (duration: 170.989244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:45:53.577933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.578289ms","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-20T04:45:53.578029Z","caller":"traceutil/trace.go:171","msg":"trace[607085096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"155.728661ms","start":"2026-04-20T04:45:53.422287Z","end":"2026-04-20T04:45:53.578016Z","steps":["trace[607085096] 'agreement among raft nodes before linearized reading' (duration: 155.433657ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:53.897496Z","caller":"traceutil/trace.go:171","msg":"trace[16057145] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:685; }","duration":"296.408341ms","start":"2026-04-20T04:45:53.601061Z","end":"2026-04-20T04:45:53.897469Z","steps":["trace[16057145] 'read index received' (duration: 70.616008ms)","trace[16057145] 'applied index is now lower than readState.Index' (duration: 225.791263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:53.897744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.655383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-20T04:45:53.89779Z","caller":"traceutil/trace.go:171","msg":"trace[1122368767] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:665; }","duration":"296.752104ms","start":"2026-04-20T04:45:53.601023Z","end":"2026-04-20T04:45:53.897775Z","steps":["trace[1122368767] 'agreement among raft nodes before linearized reading' (duration: 296.570612ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:45:53.897728Z","caller":"traceutil/trace.go:171","msg":"trace[1630473455] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"305.40292ms","start":"2026-04-20T04:45:53.592151Z","end":"2026-04-20T04:45:53.897554Z","steps":["trace[1630473455] 'process raft request' (duration: 267.785803ms)","trace[1630473455] 'compare' (duration: 37.387135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:45:53.898547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:45:53.592128Z","time spent":"306.010806ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:45:53.899197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.59409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:45:53.899399Z","caller":"traceutil/trace.go:171","msg":"trace[1374751673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"254.786923ms","start":"2026-04-20T04:45:53.644556Z","end":"2026-04-20T04:45:53.899343Z","steps":["trace[1374751673] 'agreement among raft nodes before linearized reading' (duration: 254.571201ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:46:03.840965Z","caller":"traceutil/trace.go:171","msg":"trace[128868915] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"197.857851ms","start":"2026-04-20T04:46:03.64309Z","end":"2026-04-20T04:46:03.840948Z","steps":["trace[128868915] 'read index received' (duration: 197.69951ms)","trace[128868915] 'applied index is now lower than readState.Index' (duration: 157.701µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:46:03.841091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.976242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:46:03.84107Z","caller":"traceutil/trace.go:171","msg":"trace[469079383] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"541.799536ms","start":"2026-04-20T04:46:03.299233Z","end":"2026-04-20T04:46:03.841033Z","steps":["trace[469079383] 'process raft request' (duration: 541.608614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:03.841213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.947077ms","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-20T04:46:03.84124Z","caller":"traceutil/trace.go:171","msg":"trace[181359741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"122.008888ms","start":"2026-04-20T04:46:03.719223Z","end":"2026-04-20T04:46:03.841232Z","steps":["trace[181359741] 'agreement among raft nodes before linearized reading' (duration: 121.910947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:03.841363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:46:03.299222Z","time spent":"541.937818ms","remote":"127.0.0.1:45540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:46:03.841119Z","caller":"traceutil/trace.go:171","msg":"trace[478051476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"198.059922ms","start":"2026-04-20T04:46:03.64305Z","end":"2026-04-20T04:46:03.84111Z","steps":["trace[478051476] 'agreement among raft nodes before linearized reading' (duration: 197.989992ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:46:25.649812Z","caller":"traceutil/trace.go:171","msg":"trace[62465699] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"205.183966ms","start":"2026-04-20T04:46:25.444587Z","end":"2026-04-20T04:46:25.649771Z","steps":["trace[62465699] 'process raft request' (duration: 183.3698ms)","trace[62465699] 'compare' (duration: 21.586095ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:46:32.177406Z","caller":"traceutil/trace.go:171","msg":"trace[897696440] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"376.509044ms","start":"2026-04-20T04:46:31.800873Z","end":"2026-04-20T04:46:32.177382Z","steps":["trace[897696440] 'read index received' (duration: 376.339297ms)","trace[897696440] 'applied index is now lower than readState.Index' (duration: 168.657µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:46:32.177476Z","caller":"traceutil/trace.go:171","msg":"trace[226643218] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"429.53756ms","start":"2026-04-20T04:46:31.747905Z","end":"2026-04-20T04:46:32.177442Z","steps":["trace[226643218] 'process raft request' (duration: 429.3258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:32.177585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.688292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T04:46:32.177655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.71445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-04-20T04:46:32.177684Z","caller":"traceutil/trace.go:171","msg":"trace[1108670535] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1144; }","duration":"376.854129ms","start":"2026-04-20T04:46:31.800807Z","end":"2026-04-20T04:46:32.177661Z","steps":["trace[1108670535] 'agreement among raft nodes before linearized reading' (duration: 376.700343ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:46:32.177697Z","caller":"traceutil/trace.go:171","msg":"trace[1307949634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1144; }","duration":"167.815334ms","start":"2026-04-20T04:46:32.009871Z","end":"2026-04-20T04:46:32.177686Z","steps":["trace[1307949634] 'agreement among raft nodes before linearized reading' (duration: 167.656368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:46:32.17775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:46:31.800781Z","time spent":"376.953583ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T04:46:32.177847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:46:31.74787Z","time spent":"429.7503ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:46:54.514442Z","caller":"traceutil/trace.go:171","msg":"trace[1655103593] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"225.364766ms","start":"2026-04-20T04:46:54.289052Z","end":"2026-04-20T04:46:54.514417Z","steps":["trace[1655103593] 'process raft request' (duration: 225.16606ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:19.533134Z","caller":"traceutil/trace.go:171","msg":"trace[1355303255] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1877; }","duration":"383.307665ms","start":"2026-04-20T04:47:19.149793Z","end":"2026-04-20T04:47:19.533101Z","steps":["trace[1355303255] 'read index received' (duration: 383.266014ms)","trace[1355303255] 'applied index is now lower than readState.Index' (duration: 40.091µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:47:19.533276Z","caller":"traceutil/trace.go:171","msg":"trace[1546040549] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"453.887312ms","start":"2026-04-20T04:47:19.079377Z","end":"2026-04-20T04:47:19.533264Z","steps":["trace[1546040549] 'process raft request' (duration: 453.511853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:19.533602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:19.079352Z","time spent":"453.954004ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:47:19.533686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.87221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T04:47:19.533942Z","caller":"traceutil/trace.go:171","msg":"trace[131268912] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1811; }","duration":"384.172638ms","start":"2026-04-20T04:47:19.149748Z","end":"2026-04-20T04:47:19.53392Z","steps":["trace[131268912] 'agreement among raft nodes before linearized reading' (duration: 383.674134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:19.533981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:19.149729Z","time spent":"384.24346ms","remote":"127.0.0.1:44554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-20T04:47:19.547575Z","caller":"traceutil/trace.go:171","msg":"trace[1719425496] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"319.48149ms","start":"2026-04-20T04:47:19.228078Z","end":"2026-04-20T04:47:19.54756Z","steps":["trace[1719425496] 'process raft request' (duration: 319.387097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:19.547693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:19.228052Z","time spent":"319.580632ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:47:19.548041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.430103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T04:47:19.548078Z","caller":"traceutil/trace.go:171","msg":"trace[1249433516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1812; }","duration":"394.490655ms","start":"2026-04-20T04:47:19.153579Z","end":"2026-04-20T04:47:19.548069Z","steps":["trace[1249433516] 'agreement among raft nodes before linearized reading' (duration: 394.378902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:19.548129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:19.153564Z","time spent":"394.535776ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T04:47:19.548243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.319308ms","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-20T04:47:19.548289Z","caller":"traceutil/trace.go:171","msg":"trace[1842781585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1812; }","duration":"392.38056ms","start":"2026-04-20T04:47:19.155894Z","end":"2026-04-20T04:47:19.548275Z","steps":["trace[1842781585] 'agreement among raft nodes before linearized reading' (duration: 392.268966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:19.548322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:19.155883Z","time spent":"392.430671ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T04:47:19.548346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.855295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T04:47:19.548409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.979473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T04:47:19.548701Z","caller":"traceutil/trace.go:171","msg":"trace[274159622] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1812; }","duration":"134.408334ms","start":"2026-04-20T04:47:19.414251Z","end":"2026-04-20T04:47:19.548659Z","steps":["trace[274159622] 'agreement among raft nodes before linearized reading' (duration: 133.720516ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:19.548486Z","caller":"traceutil/trace.go:171","msg":"trace[1406159014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1812; }","duration":"389.05337ms","start":"2026-04-20T04:47:19.159396Z","end":"2026-04-20T04:47:19.54845Z","steps":["trace[1406159014] 'agreement among raft nodes before linearized reading' (duration: 388.669659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:19.548946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:19.159373Z","time spent":"389.545652ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T04:47:33.65282Z","caller":"traceutil/trace.go:171","msg":"trace[1033545847] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"185.101018ms","start":"2026-04-20T04:47:33.467704Z","end":"2026-04-20T04:47:33.652805Z","steps":["trace[1033545847] 'read index received' (duration: 184.990126ms)","trace[1033545847] 'applied index is now lower than readState.Index' (duration: 110.062µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:47:33.65289Z","caller":"traceutil/trace.go:171","msg":"trace[1395825233] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"208.797466ms","start":"2026-04-20T04:47:33.444065Z","end":"2026-04-20T04:47:33.652862Z","steps":["trace[1395825233] 'process raft request' (duration: 208.586102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:33.660659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.938296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:47:33.660701Z","caller":"traceutil/trace.go:171","msg":"trace[626845477] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1921; }","duration":"193.025018ms","start":"2026-04-20T04:47:33.467665Z","end":"2026-04-20T04:47:33.66069Z","steps":["trace[626845477] 'agreement among raft nodes before linearized reading' (duration: 185.230591ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:54.208558Z","caller":"traceutil/trace.go:171","msg":"trace[36654963] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"119.555181ms","start":"2026-04-20T04:47:54.088974Z","end":"2026-04-20T04:47:54.208529Z","steps":["trace[36654963] 'process raft request' (duration: 119.396307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:55.480478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155923118199175,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:47:55.485071Z","caller":"traceutil/trace.go:171","msg":"trace[1054908559] linearizableReadLoop","detail":"{readStateIndex:2131; appliedIndex:2130; }","duration":"505.512611ms","start":"2026-04-20T04:47:54.979523Z","end":"2026-04-20T04:47:55.485035Z","steps":["trace[1054908559] 'read index received' (duration: 505.279677ms)","trace[1054908559] 'applied index is now lower than readState.Index' (duration: 229.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:47:55.485109Z","caller":"traceutil/trace.go:171","msg":"trace[213031590] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"542.852172ms","start":"2026-04-20T04:47:54.942228Z","end":"2026-04-20T04:47:55.48508Z","steps":["trace[213031590] 'process raft request' (duration: 542.666589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:55.485251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:54.942217Z","time spent":"542.969824ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:47:55.485331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.798537ms","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-20T04:47:55.48538Z","caller":"traceutil/trace.go:171","msg":"trace[1296313179] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2057; }","duration":"505.897619ms","start":"2026-04-20T04:47:54.97947Z","end":"2026-04-20T04:47:55.485368Z","steps":["trace[1296313179] 'agreement among raft nodes before linearized reading' (duration: 505.655244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:55.485417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:54.97945Z","time spent":"505.95839ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-20T04:47:55.964289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.092681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:47:55.964383Z","caller":"traceutil/trace.go:171","msg":"trace[1872324216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2058; }","duration":"321.231055ms","start":"2026-04-20T04:47:55.643131Z","end":"2026-04-20T04:47:55.964362Z","steps":["trace[1872324216] 'range keys from in-memory index tree' (duration: 321.01776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:55.964429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:55.643115Z","time spent":"321.304926ms","remote":"127.0.0.1:45358","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-20T04:47:55.964677Z","caller":"traceutil/trace.go:171","msg":"trace[1148786759] linearizableReadLoop","detail":"{readStateIndex:2133; appliedIndex:2132; }","duration":"196.514665ms","start":"2026-04-20T04:47:55.768137Z","end":"2026-04-20T04:47:55.964652Z","steps":["trace[1148786759] 'read index received' (duration: 146.728513ms)","trace[1148786759] 'applied index is now lower than readState.Index' (duration: 49.785192ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:47:55.964756Z","caller":"traceutil/trace.go:171","msg":"trace[1456311495] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"225.962229ms","start":"2026-04-20T04:47:55.738761Z","end":"2026-04-20T04:47:55.964723Z","steps":["trace[1456311495] 'process raft request' (duration: 175.996294ms)","trace[1456311495] 'compare' (duration: 49.753971ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:47:55.964937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.039694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T04:47:55.964909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.353754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:47:55.964986Z","caller":"traceutil/trace.go:171","msg":"trace[577597460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2059; }","duration":"195.124456ms","start":"2026-04-20T04:47:55.769849Z","end":"2026-04-20T04:47:55.964973Z","steps":["trace[577597460] 'agreement among raft nodes before linearized reading' (duration: 194.948132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:55.964971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.253508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T04:47:55.965038Z","caller":"traceutil/trace.go:171","msg":"trace[265341789] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2059; }","duration":"194.33563ms","start":"2026-04-20T04:47:55.770684Z","end":"2026-04-20T04:47:55.96502Z","steps":["trace[265341789] 'agreement among raft nodes before linearized reading' (duration: 194.173276ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:55.965032Z","caller":"traceutil/trace.go:171","msg":"trace[1650994692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2059; }","duration":"182.559929ms","start":"2026-04-20T04:47:55.782447Z","end":"2026-04-20T04:47:55.965007Z","steps":["trace[1650994692] 'agreement among raft nodes before linearized reading' (duration: 182.353444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:55.965249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.173087ms","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-20T04:47:55.965328Z","caller":"traceutil/trace.go:171","msg":"trace[781917671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2059; }","duration":"195.267429ms","start":"2026-04-20T04:47:55.770036Z","end":"2026-04-20T04:47:55.965304Z","steps":["trace[781917671] 'agreement among raft nodes before linearized reading' (duration: 195.054845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:55.96712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.979866ms","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-20T04:47:55.967185Z","caller":"traceutil/trace.go:171","msg":"trace[1005700077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2059; }","duration":"199.103578ms","start":"2026-04-20T04:47:55.768065Z","end":"2026-04-20T04:47:55.967169Z","steps":["trace[1005700077] 'agreement among raft nodes before linearized reading' (duration: 196.697128ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:56.654024Z","caller":"traceutil/trace.go:171","msg":"trace[1669557000] linearizableReadLoop","detail":"{readStateIndex:2138; appliedIndex:2135; }","duration":"116.269452ms","start":"2026-04-20T04:47:56.537736Z","end":"2026-04-20T04:47:56.654006Z","steps":["trace[1669557000] 'read index received' (duration: 115.336242ms)","trace[1669557000] 'applied index is now lower than readState.Index' (duration: 932.25µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:47:56.654092Z","caller":"traceutil/trace.go:171","msg":"trace[535393282] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"683.507829ms","start":"2026-04-20T04:47:55.970553Z","end":"2026-04-20T04:47:56.654061Z","steps":["trace[535393282] 'process raft request' (duration: 682.510748ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:56.654157Z","caller":"traceutil/trace.go:171","msg":"trace[1249044708] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"682.482987ms","start":"2026-04-20T04:47:55.97166Z","end":"2026-04-20T04:47:56.654143Z","steps":["trace[1249044708] 'process raft request' (duration: 682.263263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:56.65423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:55.970528Z","time spent":"683.623311ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:47:56.654542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.804012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:47:56.654595Z","caller":"traceutil/trace.go:171","msg":"trace[725885763] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2064; }","duration":"116.897715ms","start":"2026-04-20T04:47:56.537682Z","end":"2026-04-20T04:47:56.65458Z","steps":["trace[725885763] 'agreement among raft nodes before linearized reading' (duration: 116.790012ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:47:56.654646Z","caller":"traceutil/trace.go:171","msg":"trace[353226900] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"150.978701ms","start":"2026-04-20T04:47:56.503655Z","end":"2026-04-20T04:47:56.654634Z","steps":["trace[353226900] 'process raft request' (duration: 150.319968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:47:56.654314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:47:55.971648Z","time spent":"682.542069ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:48:59.538651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155923118200234,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T04:48:59.814864Z","caller":"traceutil/trace.go:171","msg":"trace[629294226] linearizableReadLoop","detail":"{readStateIndex:2594; appliedIndex:2593; }","duration":"776.768507ms","start":"2026-04-20T04:48:59.038075Z","end":"2026-04-20T04:48:59.814843Z","steps":["trace[629294226] 'read index received' (duration: 776.580565ms)","trace[629294226] 'applied index is now lower than readState.Index' (duration: 185.362µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:48:59.815135Z","caller":"traceutil/trace.go:171","msg":"trace[1206425936] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"816.043955ms","start":"2026-04-20T04:48:58.999077Z","end":"2026-04-20T04:48:59.815121Z","steps":["trace[1206425936] 'process raft request' (duration: 815.633009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:59.815239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:58.999054Z","time spent":"816.116827ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:48:59.815438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.362536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:48:59.81548Z","caller":"traceutil/trace.go:171","msg":"trace[784581857] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2505; }","duration":"777.433387ms","start":"2026-04-20T04:48:59.038034Z","end":"2026-04-20T04:48:59.815467Z","steps":["trace[784581857] 'agreement among raft nodes before linearized reading' (duration: 777.368156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:59.815511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:59.038018Z","time spent":"777.483369ms","remote":"127.0.0.1:40280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-04-20T04:48:59.815746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.077492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-20T04:48:59.815807Z","caller":"traceutil/trace.go:171","msg":"trace[67448963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2505; }","duration":"552.176654ms","start":"2026-04-20T04:48:59.263616Z","end":"2026-04-20T04:48:59.815793Z","steps":["trace[67448963] 'agreement among raft nodes before linearized reading' (duration: 552.027652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:59.815909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:59.263594Z","time spent":"552.297047ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T04:48:59.816334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.996391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:48:59.816379Z","caller":"traceutil/trace.go:171","msg":"trace[524312822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2505; }","duration":"172.066352ms","start":"2026-04-20T04:48:59.644302Z","end":"2026-04-20T04:48:59.816368Z","steps":["trace[524312822] 'agreement among raft nodes before linearized reading' (duration: 172.006461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:59.81656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.121872ms","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-20T04:48:59.816601Z","caller":"traceutil/trace.go:171","msg":"trace[935489112] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2505; }","duration":"395.189543ms","start":"2026-04-20T04:48:59.421402Z","end":"2026-04-20T04:48:59.816592Z","steps":["trace[935489112] 'agreement among raft nodes before linearized reading' (duration: 395.113361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:48:59.816629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:48:59.42139Z","time spent":"395.230294ms","remote":"127.0.0.1:45780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-04-20T04:49:26.075116Z","caller":"traceutil/trace.go:171","msg":"trace[1445800903] linearizableReadLoop","detail":"{readStateIndex:2746; appliedIndex:2745; }","duration":"109.377953ms","start":"2026-04-20T04:49:25.965698Z","end":"2026-04-20T04:49:26.075076Z","steps":["trace[1445800903] 'read index received' (duration: 109.11444ms)","trace[1445800903] 'applied index is now lower than readState.Index' (duration: 261.793µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:49:26.075215Z","caller":"traceutil/trace.go:171","msg":"trace[1190295341] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"164.179231ms","start":"2026-04-20T04:49:25.910999Z","end":"2026-04-20T04:49:26.075178Z","steps":["trace[1190295341] 'process raft request' (duration: 163.724755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:49:26.075771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.792569ms","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-20T04:49:26.075924Z","caller":"traceutil/trace.go:171","msg":"trace[2017461734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2651; }","duration":"109.972831ms","start":"2026-04-20T04:49:25.965924Z","end":"2026-04-20T04:49:26.075897Z","steps":["trace[2017461734] 'agreement among raft nodes before linearized reading' (duration: 109.558066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:49:26.076163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.786649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T04:49:26.07624Z","caller":"traceutil/trace.go:171","msg":"trace[1996812431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2651; }","duration":"110.605771ms","start":"2026-04-20T04:49:25.965617Z","end":"2026-04-20T04:49:26.076223Z","steps":["trace[1996812431] 'agreement among raft nodes before linearized reading' (duration: 109.685517ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:49:37.274727Z","caller":"traceutil/trace.go:171","msg":"trace[1732857714] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"129.501456ms","start":"2026-04-20T04:49:37.145185Z","end":"2026-04-20T04:49:37.274686Z","steps":["trace[1732857714] 'process raft request' (duration: 85.381764ms)","trace[1732857714] 'compare' (duration: 43.838898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:49:37.274979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.624064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a7f75be9c962ca\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-20T04:49:37.27506Z","caller":"traceutil/trace.go:171","msg":"trace[2138344993] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a7f75be9c962ca; range_end:; response_count:1; response_revision:2711; }","duration":"115.747256ms","start":"2026-04-20T04:49:37.159297Z","end":"2026-04-20T04:49:37.275045Z","steps":["trace[2138344993] 'agreement among raft nodes before linearized reading' (duration: 115.561923ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:49:37.275674Z","caller":"traceutil/trace.go:171","msg":"trace[474881364] linearizableReadLoop","detail":"{readStateIndex:2808; appliedIndex:2806; }","duration":"116.309473ms","start":"2026-04-20T04:49:37.159345Z","end":"2026-04-20T04:49:37.275654Z","steps":["trace[474881364] 'read index received' (duration: 16.190845ms)","trace[474881364] 'applied index is now lower than readState.Index' (duration: 99.106584ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:49:52.526057Z","caller":"traceutil/trace.go:171","msg":"trace[1168264130] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"110.433603ms","start":"2026-04-20T04:49:52.415583Z","end":"2026-04-20T04:49:52.526016Z","steps":["trace[1168264130] 'process raft request' (duration: 110.223129ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:49:55.532934Z","caller":"traceutil/trace.go:171","msg":"trace[139258670] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"156.0417ms","start":"2026-04-20T04:49:55.376862Z","end":"2026-04-20T04:49:55.532904Z","steps":["trace[139258670] 'process raft request' (duration: 155.849077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:32.444853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.603362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:50:32.444978Z","caller":"traceutil/trace.go:171","msg":"trace[1181432330] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2998; }","duration":"136.785176ms","start":"2026-04-20T04:50:32.30817Z","end":"2026-04-20T04:50:32.444956Z","steps":["trace[1181432330] 'count revisions from in-memory index tree' (duration: 136.50196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:32.444952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.361727ms","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-20T04:50:32.445149Z","caller":"traceutil/trace.go:171","msg":"trace[1150536230] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2998; }","duration":"173.592169ms","start":"2026-04-20T04:50:32.271501Z","end":"2026-04-20T04:50:32.445093Z","steps":["trace[1150536230] 'range keys from in-memory index tree' (duration: 173.264325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:40.918794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.114134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:50:40.91895Z","caller":"traceutil/trace.go:171","msg":"trace[2077749959] transaction","detail":"{read_only:false; response_revision:3045; number_of_response:1; }","duration":"153.512778ms","start":"2026-04-20T04:50:40.765396Z","end":"2026-04-20T04:50:40.918908Z","steps":["trace[2077749959] 'process raft request' (duration: 35.978157ms)","trace[2077749959] 'compare' (duration: 117.003619ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:50:41.110593Z","caller":"traceutil/trace.go:171","msg":"trace[1402145267] linearizableReadLoop","detail":"{readStateIndex:3158; appliedIndex:3156; }","duration":"112.706866ms","start":"2026-04-20T04:50:40.997837Z","end":"2026-04-20T04:50:41.110544Z","steps":["trace[1402145267] 'read index received' (duration: 97.629804ms)","trace[1402145267] 'applied index is now lower than readState.Index' (duration: 15.075692ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:50:41.110648Z","caller":"traceutil/trace.go:171","msg":"trace[48596321] transaction","detail":"{read_only:false; response_revision:3047; number_of_response:1; }","duration":"122.253119ms","start":"2026-04-20T04:50:40.988372Z","end":"2026-04-20T04:50:41.110625Z","steps":["trace[48596321] 'process raft request' (duration: 122.125353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:50:41.110953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.00654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:50:41.111077Z","caller":"traceutil/trace.go:171","msg":"trace[155725951] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3047; }","duration":"113.213347ms","start":"2026-04-20T04:50:40.997812Z","end":"2026-04-20T04:50:41.111026Z","steps":["trace[155725951] 'agreement among raft nodes before linearized reading' (duration: 112.935296ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:50:41.110633Z","caller":"traceutil/trace.go:171","msg":"trace[1819321588] transaction","detail":"{read_only:false; response_revision:3046; number_of_response:1; }","duration":"122.440745ms","start":"2026-04-20T04:50:40.988134Z","end":"2026-04-20T04:50:41.110575Z","steps":["trace[1819321588] 'process raft request' (duration: 107.29358ms)","trace[1819321588] 'compare' (duration: 14.913345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:50:55.909828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.826467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:50:55.909979Z","caller":"traceutil/trace.go:171","msg":"trace[1943492815] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:3122; }","duration":"103.069026ms","start":"2026-04-20T04:50:55.806879Z","end":"2026-04-20T04:50:55.909948Z","steps":["trace[1943492815] 'count revisions from in-memory index tree' (duration: 102.65665ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.207991Z","caller":"traceutil/trace.go:171","msg":"trace[23622234] transaction","detail":"{read_only:false; response_revision:3313; number_of_response:1; }","duration":"199.982623ms","start":"2026-04-20T04:51:31.007969Z","end":"2026-04-20T04:51:31.207952Z","steps":["trace[23622234] 'process raft request' (duration: 185.403046ms)","trace[23622234] 'compare' (duration: 14.324198ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:31.208009Z","caller":"traceutil/trace.go:171","msg":"trace[325798943] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3313; }","duration":"195.131561ms","start":"2026-04-20T04:51:31.012846Z","end":"2026-04-20T04:51:31.207978Z","steps":["trace[325798943] 'process raft request' (duration: 195.024497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:31.21232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.70056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T04:51:31.21248Z","caller":"traceutil/trace.go:171","msg":"trace[642997959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3313; }","duration":"103.932447ms","start":"2026-04-20T04:51:31.108514Z","end":"2026-04-20T04:51:31.212446Z","steps":["trace[642997959] 'agreement among raft nodes before linearized reading' (duration: 99.550281ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.713901Z","caller":"traceutil/trace.go:171","msg":"trace[700934621] transaction","detail":"{read_only:false; response_revision:3322; number_of_response:1; }","duration":"156.576594ms","start":"2026-04-20T04:51:31.55725Z","end":"2026-04-20T04:51:31.713826Z","steps":["trace[700934621] 'process raft request' (duration: 156.474161ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.714294Z","caller":"traceutil/trace.go:171","msg":"trace[1935762116] linearizableReadLoop","detail":"{readStateIndex:3447; appliedIndex:3443; }","duration":"105.531291ms","start":"2026-04-20T04:51:31.608746Z","end":"2026-04-20T04:51:31.714277Z","steps":["trace[1935762116] 'read index received' (duration: 48.715696ms)","trace[1935762116] 'applied index is now lower than readState.Index' (duration: 56.814255ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:31.714378Z","caller":"traceutil/trace.go:171","msg":"trace[677837345] transaction","detail":"{read_only:false; response_revision:3323; number_of_response:1; }","duration":"134.467596ms","start":"2026-04-20T04:51:31.579901Z","end":"2026-04-20T04:51:31.714369Z","steps":["trace[677837345] 'process raft request' (duration: 134.233449ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.714628Z","caller":"traceutil/trace.go:171","msg":"trace[1843349703] transaction","detail":"{read_only:false; response_revision:3321; number_of_response:1; }","duration":"169.737713ms","start":"2026-04-20T04:51:31.544872Z","end":"2026-04-20T04:51:31.71461Z","steps":["trace[1843349703] 'process raft request' (duration: 112.563536ms)","trace[1843349703] 'compare' (duration: 55.961437ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:31.714683Z","caller":"traceutil/trace.go:171","msg":"trace[1489875186] transaction","detail":"{read_only:false; response_revision:3324; number_of_response:1; }","duration":"126.598213ms","start":"2026-04-20T04:51:31.588073Z","end":"2026-04-20T04:51:31.714672Z","steps":["trace[1489875186] 'process raft request' (duration: 126.138288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:31.715048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.257955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-xdf2p.18a7f73c611448d0\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-20T04:51:31.715163Z","caller":"traceutil/trace.go:171","msg":"trace[1725329384] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-xdf2p.18a7f73c611448d0; range_end:; response_count:1; response_revision:3324; }","duration":"106.441892ms","start":"2026-04-20T04:51:31.608692Z","end":"2026-04-20T04:51:31.715134Z","steps":["trace[1725329384] 'agreement among raft nodes before linearized reading' (duration: 106.06574ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.98608Z","caller":"traceutil/trace.go:171","msg":"trace[398342072] transaction","detail":"{read_only:false; response_revision:3328; number_of_response:1; }","duration":"169.126253ms","start":"2026-04-20T04:51:31.816899Z","end":"2026-04-20T04:51:31.986025Z","steps":["trace[398342072] 'process raft request' (duration: 136.611529ms)","trace[398342072] 'compare' (duration: 31.964965ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:31.98658Z","caller":"traceutil/trace.go:171","msg":"trace[296166300] transaction","detail":"{read_only:false; response_revision:3331; number_of_response:1; }","duration":"169.434263ms","start":"2026-04-20T04:51:31.817084Z","end":"2026-04-20T04:51:31.986519Z","steps":["trace[296166300] 'process raft request' (duration: 169.06789ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.986749Z","caller":"traceutil/trace.go:171","msg":"trace[2009760552] transaction","detail":"{read_only:false; response_revision:3332; number_of_response:1; }","duration":"169.668721ms","start":"2026-04-20T04:51:31.817067Z","end":"2026-04-20T04:51:31.986736Z","steps":["trace[2009760552] 'process raft request' (duration: 169.115322ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.98673Z","caller":"traceutil/trace.go:171","msg":"trace[697655435] transaction","detail":"{read_only:false; response_revision:3329; number_of_response:1; }","duration":"169.656191ms","start":"2026-04-20T04:51:31.817016Z","end":"2026-04-20T04:51:31.986672Z","steps":["trace[697655435] 'process raft request' (duration: 168.928226ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.986807Z","caller":"traceutil/trace.go:171","msg":"trace[878501224] transaction","detail":"{read_only:false; response_revision:3333; number_of_response:1; }","duration":"147.220172ms","start":"2026-04-20T04:51:31.839545Z","end":"2026-04-20T04:51:31.986765Z","steps":["trace[878501224] 'process raft request' (duration: 146.687074ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:31.986957Z","caller":"traceutil/trace.go:171","msg":"trace[1710688318] transaction","detail":"{read_only:false; response_revision:3330; number_of_response:1; }","duration":"169.889827ms","start":"2026-04-20T04:51:31.817058Z","end":"2026-04-20T04:51:31.986948Z","steps":["trace[1710688318] 'process raft request' (duration: 169.034239ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:32.170657Z","caller":"traceutil/trace.go:171","msg":"trace[1715342104] transaction","detail":"{read_only:false; response_revision:3338; number_of_response:1; }","duration":"120.210724ms","start":"2026-04-20T04:51:32.050414Z","end":"2026-04-20T04:51:32.170625Z","steps":["trace[1715342104] 'process raft request' (duration: 112.927092ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:33.178847Z","caller":"traceutil/trace.go:171","msg":"trace[765371910] transaction","detail":"{read_only:false; response_revision:3347; number_of_response:1; }","duration":"138.223004ms","start":"2026-04-20T04:51:33.040591Z","end":"2026-04-20T04:51:33.178814Z","steps":["trace[765371910] 'process raft request' (duration: 125.188563ms)","trace[765371910] 'compare' (duration: 12.414211ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:33.179653Z","caller":"traceutil/trace.go:171","msg":"trace[1789403555] transaction","detail":"{read_only:false; response_revision:3348; number_of_response:1; }","duration":"138.988629ms","start":"2026-04-20T04:51:33.040646Z","end":"2026-04-20T04:51:33.179634Z","steps":["trace[1789403555] 'process raft request' (duration: 137.974856ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:33.17964Z","caller":"traceutil/trace.go:171","msg":"trace[544126847] linearizableReadLoop","detail":"{readStateIndex:3473; appliedIndex:3471; }","duration":"138.369429ms","start":"2026-04-20T04:51:33.041238Z","end":"2026-04-20T04:51:33.179608Z","steps":["trace[544126847] 'read index received' (duration: 124.559371ms)","trace[544126847] 'applied index is now lower than readState.Index' (duration: 13.803397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:51:33.179855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.548964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/secretexports.secretgen.carvel.dev\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:51:33.180039Z","caller":"traceutil/trace.go:171","msg":"trace[1345706327] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/secretexports.secretgen.carvel.dev; range_end:; response_count:0; response_revision:3348; }","duration":"138.770111ms","start":"2026-04-20T04:51:33.041199Z","end":"2026-04-20T04:51:33.179969Z","steps":["trace[1345706327] 'agreement among raft nodes before linearized reading' (duration: 138.521913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:33.459308Z","caller":"traceutil/trace.go:171","msg":"trace[1289455592] transaction","detail":"{read_only:false; response_revision:3352; number_of_response:1; }","duration":"248.966287ms","start":"2026-04-20T04:51:33.210291Z","end":"2026-04-20T04:51:33.459257Z","steps":["trace[1289455592] 'process raft request' (duration: 241.736689ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:33.459403Z","caller":"traceutil/trace.go:171","msg":"trace[2040593503] linearizableReadLoop","detail":"{readStateIndex:3479; appliedIndex:3476; }","duration":"186.462361ms","start":"2026-04-20T04:51:33.272907Z","end":"2026-04-20T04:51:33.459369Z","steps":["trace[2040593503] 'read index received' (duration: 179.105087ms)","trace[2040593503] 'applied index is now lower than readState.Index' (duration: 7.356044ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:51:33.460017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.09096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T04:51:33.46011Z","caller":"traceutil/trace.go:171","msg":"trace[885881639] transaction","detail":"{read_only:false; response_revision:3353; number_of_response:1; }","duration":"232.652009ms","start":"2026-04-20T04:51:33.227443Z","end":"2026-04-20T04:51:33.460095Z","steps":["trace[885881639] 'process raft request' (duration: 231.728828ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:33.460127Z","caller":"traceutil/trace.go:171","msg":"trace[1525771472] transaction","detail":"{read_only:false; response_revision:3354; number_of_response:1; }","duration":"229.834335ms","start":"2026-04-20T04:51:33.230275Z","end":"2026-04-20T04:51:33.460109Z","steps":["trace[1525771472] 'process raft request' (duration: 229.032748ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:33.46008Z","caller":"traceutil/trace.go:171","msg":"trace[1288813776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3354; }","duration":"187.233536ms","start":"2026-04-20T04:51:33.272831Z","end":"2026-04-20T04:51:33.460064Z","steps":["trace[1288813776] 'agreement among raft nodes before linearized reading' (duration: 186.939856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:33.461087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.891494ms","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-20T04:51:33.46121Z","caller":"traceutil/trace.go:171","msg":"trace[1325967247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3354; }","duration":"177.076549ms","start":"2026-04-20T04:51:33.284104Z","end":"2026-04-20T04:51:33.461181Z","steps":["trace[1325967247] 'agreement among raft nodes before linearized reading' (duration: 175.695054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:33.811317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.926689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:51:33.811787Z","caller":"traceutil/trace.go:171","msg":"trace[1672715855] transaction","detail":"{read_only:false; response_revision:3357; number_of_response:1; }","duration":"344.962475ms","start":"2026-04-20T04:51:33.466604Z","end":"2026-04-20T04:51:33.811567Z","steps":["trace[1672715855] 'process raft request' (duration: 344.8428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:33.811917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:33.46658Z","time spent":"345.299256ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3765,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:51:33.811766Z","caller":"traceutil/trace.go:171","msg":"trace[314986678] transaction","detail":"{read_only:false; response_revision:3356; number_of_response:1; }","duration":"345.11206ms","start":"2026-04-20T04:51:33.466612Z","end":"2026-04-20T04:51:33.811724Z","steps":["trace[314986678] 'process raft request' (duration: 130.663334ms)","trace[314986678] 'compare' (duration: 213.599408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:51:33.812328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:33.466603Z","time spent":"345.567324ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:51:33.813353Z","caller":"traceutil/trace.go:171","msg":"trace[1690618964] transaction","detail":"{read_only:false; response_revision:3358; number_of_response:1; }","duration":"323.218315ms","start":"2026-04-20T04:51:33.490098Z","end":"2026-04-20T04:51:33.813316Z","steps":["trace[1690618964] 'process raft request' (duration: 322.728369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:33.814268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:33.490084Z","time spent":"324.107265ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6321,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:51:34.017386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.35687ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T04:51:34.017741Z","caller":"traceutil/trace.go:171","msg":"trace[1802794447] linearizableReadLoop","detail":"{readStateIndex:3484; appliedIndex:3480; }","duration":"456.242495ms","start":"2026-04-20T04:51:33.561453Z","end":"2026-04-20T04:51:34.017695Z","steps":["trace[1802794447] 'read index received' (duration: 35.779144ms)","trace[1802794447] 'applied index is now lower than readState.Index' (duration: 420.46035ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:34.017809Z","caller":"traceutil/trace.go:171","msg":"trace[111430513] transaction","detail":"{read_only:false; response_revision:3359; number_of_response:1; }","duration":"293.730196ms","start":"2026-04-20T04:51:33.724053Z","end":"2026-04-20T04:51:34.017783Z","steps":["trace[111430513] 'process raft request' (duration: 293.508019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.018145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.667149ms","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-20T04:51:34.018255Z","caller":"traceutil/trace.go:171","msg":"trace[1167838209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3359; }","duration":"456.845334ms","start":"2026-04-20T04:51:33.561385Z","end":"2026-04-20T04:51:34.01823Z","steps":["trace[1167838209] 'agreement among raft nodes before linearized reading' (duration: 456.584515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.018308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:33.561353Z","time spent":"456.943968ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T04:51:34.030267Z","caller":"traceutil/trace.go:171","msg":"trace[1099383828] transaction","detail":"{read_only:false; response_revision:3360; number_of_response:1; }","duration":"210.883594ms","start":"2026-04-20T04:51:33.819363Z","end":"2026-04-20T04:51:34.030247Z","steps":["trace[1099383828] 'process raft request' (duration: 210.683358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.030499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.45418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:51:34.030555Z","caller":"traceutil/trace.go:171","msg":"trace[1796082895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3361; }","duration":"385.570354ms","start":"2026-04-20T04:51:33.644971Z","end":"2026-04-20T04:51:34.030542Z","steps":["trace[1796082895] 'agreement among raft nodes before linearized reading' (duration: 385.45009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.030589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:33.644932Z","time spent":"385.650286ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T04:51:34.030713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.921437ms","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-20T04:51:34.030714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.823099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T04:51:34.030775Z","caller":"traceutil/trace.go:171","msg":"trace[1544320004] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3361; }","duration":"263.03129ms","start":"2026-04-20T04:51:33.767727Z","end":"2026-04-20T04:51:34.030758Z","steps":["trace[1544320004] 'agreement among raft nodes before linearized reading' (duration: 262.773721ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:34.030823Z","caller":"traceutil/trace.go:171","msg":"trace[1671165002] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3361; }","duration":"309.994224ms","start":"2026-04-20T04:51:33.720801Z","end":"2026-04-20T04:51:34.030795Z","steps":["trace[1671165002] 'agreement among raft nodes before linearized reading' (duration: 309.709784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.030891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:33.720774Z","time spent":"310.104287ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T04:51:34.03092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.266163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:51:34.030954Z","caller":"traceutil/trace.go:171","msg":"trace[427823939] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/certificates.secretgen.k14s.io; range_end:; response_count:0; response_revision:3361; }","duration":"210.319945ms","start":"2026-04-20T04:51:33.82062Z","end":"2026-04-20T04:51:34.030939Z","steps":["trace[427823939] 'agreement among raft nodes before linearized reading' (duration: 210.264963ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:34.031072Z","caller":"traceutil/trace.go:171","msg":"trace[1302959354] transaction","detail":"{read_only:false; response_revision:3361; number_of_response:1; }","duration":"210.725569ms","start":"2026-04-20T04:51:33.820333Z","end":"2026-04-20T04:51:34.031058Z","steps":["trace[1302959354] 'process raft request' (duration: 209.858361ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:34.272717Z","caller":"traceutil/trace.go:171","msg":"trace[312630793] transaction","detail":"{read_only:false; response_revision:3365; number_of_response:1; }","duration":"233.612867ms","start":"2026-04-20T04:51:34.03908Z","end":"2026-04-20T04:51:34.272693Z","steps":["trace[312630793] 'process raft request' (duration: 233.519494ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:34.272766Z","caller":"traceutil/trace.go:171","msg":"trace[209527821] transaction","detail":"{read_only:false; response_revision:3363; number_of_response:1; }","duration":"234.852338ms","start":"2026-04-20T04:51:34.037882Z","end":"2026-04-20T04:51:34.272734Z","steps":["trace[209527821] 'process raft request' (duration: 221.167098ms)","trace[209527821] 'compare' (duration: 13.36173ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:34.272766Z","caller":"traceutil/trace.go:171","msg":"trace[297240454] transaction","detail":"{read_only:false; response_revision:3364; number_of_response:1; }","duration":"233.875576ms","start":"2026-04-20T04:51:34.03884Z","end":"2026-04-20T04:51:34.272716Z","steps":["trace[297240454] 'process raft request' (duration: 233.708811ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:34.272923Z","caller":"traceutil/trace.go:171","msg":"trace[258161685] linearizableReadLoop","detail":"{readStateIndex:3492; appliedIndex:3488; }","duration":"155.738332ms","start":"2026-04-20T04:51:34.117146Z","end":"2026-04-20T04:51:34.272885Z","steps":["trace[258161685] 'read index received' (duration: 141.952797ms)","trace[258161685] 'applied index is now lower than readState.Index' (duration: 13.783105ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:34.273001Z","caller":"traceutil/trace.go:171","msg":"trace[801715779] transaction","detail":"{read_only:false; response_revision:3366; number_of_response:1; }","duration":"231.761617ms","start":"2026-04-20T04:51:34.041227Z","end":"2026-04-20T04:51:34.272988Z","steps":["trace[801715779] 'process raft request' (duration: 231.415075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.273234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.399364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:51:34.27329Z","caller":"traceutil/trace.go:171","msg":"trace[560418222] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:3366; }","duration":"136.517478ms","start":"2026-04-20T04:51:34.136748Z","end":"2026-04-20T04:51:34.273266Z","steps":["trace[560418222] 'agreement among raft nodes before linearized reading' (duration: 136.383764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.27331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.156776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9280"} {"level":"info","ts":"2026-04-20T04:51:34.273346Z","caller":"traceutil/trace.go:171","msg":"trace[1220722359] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:3366; }","duration":"156.219417ms","start":"2026-04-20T04:51:34.117116Z","end":"2026-04-20T04:51:34.273335Z","steps":["trace[1220722359] 'agreement among raft nodes before linearized reading' (duration: 155.969209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.702336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.112188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:51:34.702605Z","caller":"traceutil/trace.go:171","msg":"trace[664994848] linearizableReadLoop","detail":"{readStateIndex:3494; appliedIndex:3492; }","duration":"421.928532ms","start":"2026-04-20T04:51:34.280652Z","end":"2026-04-20T04:51:34.70258Z","steps":["trace[664994848] 'read index received' (duration: 25.361355ms)","trace[664994848] 'applied index is now lower than readState.Index' (duration: 396.565547ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:34.702725Z","caller":"traceutil/trace.go:171","msg":"trace[1325487599] transaction","detail":"{read_only:false; response_revision:3369; number_of_response:1; }","duration":"421.080724ms","start":"2026-04-20T04:51:34.281632Z","end":"2026-04-20T04:51:34.702712Z","steps":["trace[1325487599] 'process raft request' (duration: 420.842877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.702822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:34.28161Z","time spent":"421.155188ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:51:34.70287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.208583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:51:34.702986Z","caller":"traceutil/trace.go:171","msg":"trace[967979877] transaction","detail":"{read_only:false; response_revision:3368; number_of_response:1; }","duration":"422.536933ms","start":"2026-04-20T04:51:34.280401Z","end":"2026-04-20T04:51:34.702938Z","steps":["trace[967979877] 'process raft request' (duration: 159.715973ms)","trace[967979877] 'compare' (duration: 261.90456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:51:34.703123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:34.280385Z","time spent":"422.654578ms","remote":"127.0.0.1:45874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":974,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T04:51:34.702998Z","caller":"traceutil/trace.go:171","msg":"trace[1437379726] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io; range_end:; response_count:0; response_revision:3369; }","duration":"422.358127ms","start":"2026-04-20T04:51:34.280594Z","end":"2026-04-20T04:51:34.702952Z","steps":["trace[1437379726] 'agreement among raft nodes before linearized reading' (duration: 422.216982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.703322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:34.28057Z","time spent":"422.740999ms","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io\" "} {"level":"warn","ts":"2026-04-20T04:51:34.702873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.375821ms","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-20T04:51:34.703466Z","caller":"traceutil/trace.go:171","msg":"trace[455153974] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3369; }","duration":"363.051964ms","start":"2026-04-20T04:51:34.340401Z","end":"2026-04-20T04:51:34.703453Z","steps":["trace[455153974] 'agreement among raft nodes before linearized reading' (duration: 362.360511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:34.703503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:51:34.340356Z","time spent":"363.138766ms","remote":"127.0.0.1:45780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-20T04:51:35.053401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.630533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:51:35.053689Z","caller":"traceutil/trace.go:171","msg":"trace[1208071460] linearizableReadLoop","detail":"{readStateIndex:3498; appliedIndex:3497; }","duration":"260.61033ms","start":"2026-04-20T04:51:34.793061Z","end":"2026-04-20T04:51:35.053671Z","steps":["trace[1208071460] 'read index received' (duration: 134.551533ms)","trace[1208071460] 'applied index is now lower than readState.Index' (duration: 126.056807ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:35.053792Z","caller":"traceutil/trace.go:171","msg":"trace[790902055] transaction","detail":"{read_only:false; response_revision:3372; number_of_response:1; }","duration":"288.953735ms","start":"2026-04-20T04:51:34.764827Z","end":"2026-04-20T04:51:35.053781Z","steps":["trace[790902055] 'process raft request' (duration: 162.848076ms)","trace[790902055] 'compare' (duration: 125.185619ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:51:35.053989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.269475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rsakeys.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:51:35.05408Z","caller":"traceutil/trace.go:171","msg":"trace[350794984] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rsakeys.secretgen.k14s.io; range_end:; response_count:0; response_revision:3372; }","duration":"289.41047ms","start":"2026-04-20T04:51:34.764645Z","end":"2026-04-20T04:51:35.054055Z","steps":["trace[350794984] 'agreement among raft nodes before linearized reading' (duration: 289.246984ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:35.089044Z","caller":"traceutil/trace.go:171","msg":"trace[41893631] transaction","detail":"{read_only:false; response_revision:3373; number_of_response:1; }","duration":"244.495284ms","start":"2026-04-20T04:51:34.844533Z","end":"2026-04-20T04:51:35.089028Z","steps":["trace[41893631] 'process raft request' (duration: 244.37625ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:35.089444Z","caller":"traceutil/trace.go:171","msg":"trace[1891833536] transaction","detail":"{read_only:false; response_revision:3374; number_of_response:1; }","duration":"190.252598ms","start":"2026-04-20T04:51:34.899126Z","end":"2026-04-20T04:51:35.089378Z","steps":["trace[1891833536] 'process raft request' (duration: 189.875405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:51:35.089548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.951715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-20T04:51:35.089641Z","caller":"traceutil/trace.go:171","msg":"trace[284274850] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3374; }","duration":"284.098729ms","start":"2026-04-20T04:51:34.805519Z","end":"2026-04-20T04:51:35.089618Z","steps":["trace[284274850] 'agreement among raft nodes before linearized reading' (duration: 283.708837ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:35.295425Z","caller":"traceutil/trace.go:171","msg":"trace[999579085] transaction","detail":"{read_only:false; response_revision:3380; number_of_response:1; }","duration":"118.976455ms","start":"2026-04-20T04:51:35.176392Z","end":"2026-04-20T04:51:35.295369Z","steps":["trace[999579085] 'process raft request' (duration: 101.388847ms)","trace[999579085] 'compare' (duration: 16.963517ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:35.295451Z","caller":"traceutil/trace.go:171","msg":"trace[990883708] transaction","detail":"{read_only:false; response_revision:3381; number_of_response:1; }","duration":"116.373979ms","start":"2026-04-20T04:51:35.179057Z","end":"2026-04-20T04:51:35.295431Z","steps":["trace[990883708] 'process raft request' (duration: 116.205103ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:35.404488Z","caller":"traceutil/trace.go:171","msg":"trace[1414476200] transaction","detail":"{read_only:false; response_revision:3383; number_of_response:1; }","duration":"101.424779ms","start":"2026-04-20T04:51:35.303009Z","end":"2026-04-20T04:51:35.404434Z","steps":["trace[1414476200] 'process raft request' (duration: 98.159131ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:35.568146Z","caller":"traceutil/trace.go:171","msg":"trace[581477550] transaction","detail":"{read_only:false; response_revision:3393; number_of_response:1; }","duration":"101.384388ms","start":"2026-04-20T04:51:35.466717Z","end":"2026-04-20T04:51:35.568101Z","steps":["trace[581477550] 'process raft request' (duration: 38.404061ms)","trace[581477550] 'compare' (duration: 62.769199ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:40.404073Z","caller":"traceutil/trace.go:171","msg":"trace[1447285522] transaction","detail":"{read_only:false; response_revision:3434; number_of_response:1; }","duration":"102.665451ms","start":"2026-04-20T04:51:40.30137Z","end":"2026-04-20T04:51:40.404035Z","steps":["trace[1447285522] 'process raft request' (duration: 102.352922ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:40.406259Z","caller":"traceutil/trace.go:171","msg":"trace[1179954594] transaction","detail":"{read_only:false; response_revision:3435; number_of_response:1; }","duration":"101.639677ms","start":"2026-04-20T04:51:40.304577Z","end":"2026-04-20T04:51:40.406216Z","steps":["trace[1179954594] 'process raft request' (duration: 100.829792ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:51:54.477617Z","caller":"traceutil/trace.go:171","msg":"trace[1287766221] transaction","detail":"{read_only:false; response_revision:3512; number_of_response:1; }","duration":"102.045143ms","start":"2026-04-20T04:51:54.375496Z","end":"2026-04-20T04:51:54.477541Z","steps":["trace[1287766221] 'process raft request' (duration: 77.565129ms)","trace[1287766221] 'compare' (duration: 24.064252ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:51:54.691939Z","caller":"traceutil/trace.go:171","msg":"trace[450453662] transaction","detail":"{read_only:false; response_revision:3517; number_of_response:1; }","duration":"131.906083ms","start":"2026-04-20T04:51:54.560008Z","end":"2026-04-20T04:51:54.691914Z","steps":["trace[450453662] 'process raft request' (duration: 118.668572ms)","trace[450453662] 'compare' (duration: 12.84153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:52:12.75432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.975548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T04:52:12.754387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.102442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:52:12.754415Z","caller":"traceutil/trace.go:171","msg":"trace[983086527] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3611; }","duration":"209.147353ms","start":"2026-04-20T04:52:12.545251Z","end":"2026-04-20T04:52:12.754399Z","steps":["trace[983086527] 'count revisions from in-memory index tree' (duration: 208.878046ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:12.754454Z","caller":"traceutil/trace.go:171","msg":"trace[839716858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3611; }","duration":"112.210845ms","start":"2026-04-20T04:52:12.642229Z","end":"2026-04-20T04:52:12.754439Z","steps":["trace[839716858] 'range keys from in-memory index tree' (duration: 112.045719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:12.754518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.293181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T04:52:12.754601Z","caller":"traceutil/trace.go:171","msg":"trace[899435590] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3611; }","duration":"175.437715ms","start":"2026-04-20T04:52:12.579144Z","end":"2026-04-20T04:52:12.754582Z","steps":["trace[899435590] 'range keys from in-memory index tree' (duration: 175.062694ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:44.160746Z","caller":"traceutil/trace.go:171","msg":"trace[709961217] transaction","detail":"{read_only:false; response_revision:3834; number_of_response:1; }","duration":"107.326598ms","start":"2026-04-20T04:52:44.05338Z","end":"2026-04-20T04:52:44.160706Z","steps":["trace[709961217] 'process raft request' (duration: 95.844303ms)","trace[709961217] 'compare' (duration: 11.082814ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:44.183935Z","caller":"traceutil/trace.go:171","msg":"trace[472209442] transaction","detail":"{read_only:false; response_revision:3835; number_of_response:1; }","duration":"127.073794ms","start":"2026-04-20T04:52:44.056805Z","end":"2026-04-20T04:52:44.183879Z","steps":["trace[472209442] 'process raft request' (duration: 125.811301ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:44.48682Z","caller":"traceutil/trace.go:171","msg":"trace[107639869] linearizableReadLoop","detail":"{readStateIndex:3984; appliedIndex:3983; }","duration":"201.887558ms","start":"2026-04-20T04:52:44.284897Z","end":"2026-04-20T04:52:44.486784Z","steps":["trace[107639869] 'read index received' (duration: 183.444396ms)","trace[107639869] 'applied index is now lower than readState.Index' (duration: 18.441492ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:52:44.486838Z","caller":"traceutil/trace.go:171","msg":"trace[252924216] transaction","detail":"{read_only:false; response_revision:3840; number_of_response:1; }","duration":"210.338314ms","start":"2026-04-20T04:52:44.276451Z","end":"2026-04-20T04:52:44.486789Z","steps":["trace[252924216] 'process raft request' (duration: 191.672397ms)","trace[252924216] 'compare' (duration: 18.37763ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:52:44.487098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.166845ms","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-20T04:52:44.487151Z","caller":"traceutil/trace.go:171","msg":"trace[1836858356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3840; }","duration":"202.295988ms","start":"2026-04-20T04:52:44.284841Z","end":"2026-04-20T04:52:44.487137Z","steps":["trace[1836858356] 'agreement among raft nodes before linearized reading' (duration: 202.065872ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:52:44.4889Z","caller":"traceutil/trace.go:171","msg":"trace[623944205] transaction","detail":"{read_only:false; response_revision:3841; number_of_response:1; }","duration":"191.398249ms","start":"2026-04-20T04:52:44.297458Z","end":"2026-04-20T04:52:44.488857Z","steps":["trace[623944205] 'process raft request' (duration: 190.967999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:52:45.259126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.579857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:52:45.259231Z","caller":"traceutil/trace.go:171","msg":"trace[1404124668] transaction","detail":"{read_only:false; response_revision:3850; number_of_response:1; }","duration":"171.24887ms","start":"2026-04-20T04:52:45.087963Z","end":"2026-04-20T04:52:45.259212Z","steps":["trace[1404124668] 'process raft request' (duration: 59.492038ms)","trace[1404124668] 'compare' (duration: 111.179147ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:21.152147Z","caller":"traceutil/trace.go:171","msg":"trace[1055903646] transaction","detail":"{read_only:false; response_revision:4046; number_of_response:1; }","duration":"105.81192ms","start":"2026-04-20T04:53:21.046281Z","end":"2026-04-20T04:53:21.152093Z","steps":["trace[1055903646] 'process raft request' (duration: 105.003871ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:21.471901Z","caller":"traceutil/trace.go:171","msg":"trace[1753910161] linearizableReadLoop","detail":"{readStateIndex:4209; appliedIndex:4208; }","duration":"143.455458ms","start":"2026-04-20T04:53:21.32842Z","end":"2026-04-20T04:53:21.471876Z","steps":["trace[1753910161] 'read index received' (duration: 142.925696ms)","trace[1753910161] 'applied index is now lower than readState.Index' (duration: 528.652µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:21.472037Z","caller":"traceutil/trace.go:171","msg":"trace[1671740941] transaction","detail":"{read_only:false; response_revision:4047; number_of_response:1; }","duration":"202.834887ms","start":"2026-04-20T04:53:21.269188Z","end":"2026-04-20T04:53:21.472022Z","steps":["trace[1671740941] 'process raft request' (duration: 202.307626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:21.472354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.906179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:53:21.472439Z","caller":"traceutil/trace.go:171","msg":"trace[1118647875] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4047; }","duration":"144.047942ms","start":"2026-04-20T04:53:21.328365Z","end":"2026-04-20T04:53:21.472413Z","steps":["trace[1118647875] 'agreement among raft nodes before linearized reading' (duration: 143.858008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:21.472442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.974898ms","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-20T04:53:21.472478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.294739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T04:53:21.472523Z","caller":"traceutil/trace.go:171","msg":"trace[403564272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4047; }","duration":"127.149452ms","start":"2026-04-20T04:53:21.345356Z","end":"2026-04-20T04:53:21.472505Z","steps":["trace[403564272] 'agreement among raft nodes before linearized reading' (duration: 126.796833ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:21.472557Z","caller":"traceutil/trace.go:171","msg":"trace[743460660] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4047; }","duration":"125.425462ms","start":"2026-04-20T04:53:21.347107Z","end":"2026-04-20T04:53:21.472533Z","steps":["trace[743460660] 'agreement among raft nodes before linearized reading' (duration: 125.156777ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:29.891841Z","caller":"traceutil/trace.go:171","msg":"trace[1964448530] transaction","detail":"{read_only:false; response_revision:4415; number_of_response:1; }","duration":"145.283295ms","start":"2026-04-20T04:53:29.746498Z","end":"2026-04-20T04:53:29.891781Z","steps":["trace[1964448530] 'process raft request' (duration: 139.694279ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:29.962243Z","caller":"traceutil/trace.go:171","msg":"trace[442533758] transaction","detail":"{read_only:false; response_revision:4416; number_of_response:1; }","duration":"211.091763ms","start":"2026-04-20T04:53:29.751135Z","end":"2026-04-20T04:53:29.962227Z","steps":["trace[442533758] 'process raft request' (duration: 210.661913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:29.962486Z","caller":"traceutil/trace.go:171","msg":"trace[1662061513] linearizableReadLoop","detail":"{readStateIndex:4586; appliedIndex:4582; }","duration":"119.437311ms","start":"2026-04-20T04:53:29.843027Z","end":"2026-04-20T04:53:29.962464Z","steps":["trace[1662061513] 'read index received' (duration: 43.31688ms)","trace[1662061513] 'applied index is now lower than readState.Index' (duration: 76.119591ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:29.96266Z","caller":"traceutil/trace.go:171","msg":"trace[576292202] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"169.314588ms","start":"2026-04-20T04:53:29.793332Z","end":"2026-04-20T04:53:29.962647Z","steps":["trace[576292202] 'process raft request' (duration: 169.088263ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:29.962559Z","caller":"traceutil/trace.go:171","msg":"trace[211060272] transaction","detail":"{read_only:false; response_revision:4417; number_of_response:1; }","duration":"201.417894ms","start":"2026-04-20T04:53:29.761131Z","end":"2026-04-20T04:53:29.962549Z","steps":["trace[211060272] 'process raft request' (duration: 200.943003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:29.963096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.018714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-qw9vh\" ","response":"range_response_count:1 size:2464"} {"level":"info","ts":"2026-04-20T04:53:29.963222Z","caller":"traceutil/trace.go:171","msg":"trace[326750772] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-qw9vh; range_end:; response_count:1; response_revision:4418; }","duration":"120.206129ms","start":"2026-04-20T04:53:29.842988Z","end":"2026-04-20T04:53:29.963194Z","steps":["trace[326750772] 'agreement among raft nodes before linearized reading' (duration: 119.808989ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:30.0673Z","caller":"traceutil/trace.go:171","msg":"trace[419683428] linearizableReadLoop","detail":"{readStateIndex:4587; appliedIndex:4586; }","duration":"104.661104ms","start":"2026-04-20T04:53:29.962619Z","end":"2026-04-20T04:53:30.06728Z","steps":["trace[419683428] 'read index received' (duration: 96.243034ms)","trace[419683428] 'applied index is now lower than readState.Index' (duration: 8.41739ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:30.067389Z","caller":"traceutil/trace.go:171","msg":"trace[313733358] transaction","detail":"{read_only:false; response_revision:4419; number_of_response:1; }","duration":"165.363785ms","start":"2026-04-20T04:53:29.902016Z","end":"2026-04-20T04:53:30.06738Z","steps":["trace[313733358] 'process raft request' (duration: 156.913934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:30.067597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.359481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls-zp6xk\" ","response":"range_response_count:1 size:2456"} {"level":"info","ts":"2026-04-20T04:53:30.067625Z","caller":"traceutil/trace.go:171","msg":"trace[630965337] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls-zp6xk; range_end:; response_count:1; response_revision:4419; }","duration":"125.438233ms","start":"2026-04-20T04:53:29.942179Z","end":"2026-04-20T04:53:30.067618Z","steps":["trace[630965337] 'agreement among raft nodes before linearized reading' (duration: 125.322901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:30.067954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.981214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:53:30.068108Z","caller":"traceutil/trace.go:171","msg":"trace[1877264633] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:4419; }","duration":"198.176957ms","start":"2026-04-20T04:53:29.869904Z","end":"2026-04-20T04:53:30.068081Z","steps":["trace[1877264633] 'agreement among raft nodes before linearized reading' (duration: 197.664805ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:30.072747Z","caller":"traceutil/trace.go:171","msg":"trace[942495968] transaction","detail":"{read_only:false; response_revision:4421; number_of_response:1; }","duration":"105.035111ms","start":"2026-04-20T04:53:29.96768Z","end":"2026-04-20T04:53:30.072715Z","steps":["trace[942495968] 'process raft request' (duration: 104.859437ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:30.07308Z","caller":"traceutil/trace.go:171","msg":"trace[1078026837] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4420; }","duration":"106.102326ms","start":"2026-04-20T04:53:29.966944Z","end":"2026-04-20T04:53:30.073046Z","steps":["trace[1078026837] 'process raft request' (duration: 105.374669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:30.327791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.915471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:3224"} {"level":"info","ts":"2026-04-20T04:53:30.327904Z","caller":"traceutil/trace.go:171","msg":"trace[1470477816] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4426; }","duration":"130.088585ms","start":"2026-04-20T04:53:30.197794Z","end":"2026-04-20T04:53:30.327883Z","steps":["trace[1470477816] 'range keys from in-memory index tree' (duration: 129.792248ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:30.328655Z","caller":"traceutil/trace.go:171","msg":"trace[967629249] transaction","detail":"{read_only:false; response_revision:4427; number_of_response:1; }","duration":"108.854385ms","start":"2026-04-20T04:53:30.219782Z","end":"2026-04-20T04:53:30.328636Z","steps":["trace[967629249] 'process raft request' (duration: 108.467706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:33.894128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.406173ms","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-20T04:53:33.894232Z","caller":"traceutil/trace.go:171","msg":"trace[1208018738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4519; }","duration":"253.548706ms","start":"2026-04-20T04:53:33.640662Z","end":"2026-04-20T04:53:33.894211Z","steps":["trace[1208018738] 'range keys from in-memory index tree' (duration: 253.262371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:33.894276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.81312ms","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-20T04:53:33.894315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.300689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4736"} {"level":"info","ts":"2026-04-20T04:53:33.89437Z","caller":"traceutil/trace.go:171","msg":"trace[2094539664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4519; }","duration":"256.937822ms","start":"2026-04-20T04:53:33.637411Z","end":"2026-04-20T04:53:33.894349Z","steps":["trace[2094539664] 'range keys from in-memory index tree' (duration: 256.674137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:33.894411Z","caller":"traceutil/trace.go:171","msg":"trace[385569219] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4519; }","duration":"252.421011ms","start":"2026-04-20T04:53:33.641967Z","end":"2026-04-20T04:53:33.894388Z","steps":["trace[385569219] 'range keys from in-memory index tree' (duration: 252.153025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:33.894387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.279486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T04:53:33.894495Z","caller":"traceutil/trace.go:171","msg":"trace[2111461014] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4519; }","duration":"251.417179ms","start":"2026-04-20T04:53:33.64305Z","end":"2026-04-20T04:53:33.894467Z","steps":["trace[2111461014] 'range keys from in-memory index tree' (duration: 251.083992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:33.894766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.557168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-20T04:53:33.894924Z","caller":"traceutil/trace.go:171","msg":"trace[1018228894] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4519; }","duration":"249.713612ms","start":"2026-04-20T04:53:33.645148Z","end":"2026-04-20T04:53:33.894862Z","steps":["trace[1018228894] 'range keys from in-memory index tree' (duration: 248.610656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:33.894948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.883133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954231"} {"level":"info","ts":"2026-04-20T04:53:33.895077Z","caller":"traceutil/trace.go:171","msg":"trace[768746012] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4519; }","duration":"245.048957ms","start":"2026-04-20T04:53:33.650004Z","end":"2026-04-20T04:53:33.895053Z","steps":["trace[768746012] 'range keys from in-memory index tree' (duration: 243.915891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:34.032028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.243477ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T04:53:34.032445Z","caller":"traceutil/trace.go:171","msg":"trace[1998041137] linearizableReadLoop","detail":"{readStateIndex:4689; appliedIndex:4688; }","duration":"135.877572ms","start":"2026-04-20T04:53:33.896547Z","end":"2026-04-20T04:53:34.032424Z","steps":["trace[1998041137] 'read index received' (duration: 43.071µs)","trace[1998041137] 'applied index is now lower than readState.Index' (duration: 135.832431ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:34.032775Z","caller":"traceutil/trace.go:171","msg":"trace[1301650829] transaction","detail":"{read_only:false; response_revision:4520; number_of_response:1; }","duration":"133.746253ms","start":"2026-04-20T04:53:33.899007Z","end":"2026-04-20T04:53:34.032753Z","steps":["trace[1301650829] 'process raft request' (duration: 133.274843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:34.033194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.624488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca6ea5d3-2b3c-43a8-83ff-aa01b8b85785\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-20T04:53:34.033238Z","caller":"traceutil/trace.go:171","msg":"trace[574254881] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca6ea5d3-2b3c-43a8-83ff-aa01b8b85785; range_end:; response_count:1; response_revision:4519; }","duration":"136.7119ms","start":"2026-04-20T04:53:33.896518Z","end":"2026-04-20T04:53:34.03323Z","steps":["trace[574254881] 'agreement among raft nodes before linearized reading' (duration: 135.978503ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:34.039419Z","caller":"traceutil/trace.go:171","msg":"trace[384036454] transaction","detail":"{read_only:false; response_revision:4521; number_of_response:1; }","duration":"140.31908ms","start":"2026-04-20T04:53:33.899075Z","end":"2026-04-20T04:53:34.039394Z","steps":["trace[384036454] 'process raft request' (duration: 140.122396ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:34.039849Z","caller":"traceutil/trace.go:171","msg":"trace[652920217] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"140.473084ms","start":"2026-04-20T04:53:33.899332Z","end":"2026-04-20T04:53:34.039805Z","steps":["trace[652920217] 'process raft request' (duration: 140.008843ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:34.040826Z","caller":"traceutil/trace.go:171","msg":"trace[1595438428] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"122.246155ms","start":"2026-04-20T04:53:33.918564Z","end":"2026-04-20T04:53:34.040811Z","steps":["trace[1595438428] 'process raft request' (duration: 121.11415ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:35.464044Z","caller":"traceutil/trace.go:171","msg":"trace[501198839] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"123.625104ms","start":"2026-04-20T04:53:35.340379Z","end":"2026-04-20T04:53:35.464004Z","steps":["trace[501198839] 'process raft request' (duration: 84.569204ms)","trace[501198839] 'compare' (duration: 38.661942ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:35.884957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.363846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:35.885105Z","caller":"traceutil/trace.go:171","msg":"trace[1569202339] linearizableReadLoop","detail":"{readStateIndex:4735; appliedIndex:4734; }","duration":"311.244766ms","start":"2026-04-20T04:53:35.573836Z","end":"2026-04-20T04:53:35.885081Z","steps":["trace[1569202339] 'read index received' (duration: 79.647395ms)","trace[1569202339] 'applied index is now lower than readState.Index' (duration: 231.592741ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:35.885296Z","caller":"traceutil/trace.go:171","msg":"trace[1361300248] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"401.862515ms","start":"2026-04-20T04:53:35.483363Z","end":"2026-04-20T04:53:35.885226Z","steps":["trace[1361300248] 'process raft request' (duration: 170.11133ms)","trace[1361300248] 'compare' (duration: 230.936127ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:35.88563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:35.483339Z","time spent":"402.161692ms","remote":"127.0.0.1:45438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T04:53:35.885851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.886681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74decbfe-4985-4a11-b7d1-809004dd64d7\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-04-20T04:53:35.885983Z","caller":"traceutil/trace.go:171","msg":"trace[1025441720] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-74decbfe-4985-4a11-b7d1-809004dd64d7; range_end:; response_count:1; response_revision:4565; }","duration":"312.145206ms","start":"2026-04-20T04:53:35.573817Z","end":"2026-04-20T04:53:35.885962Z","steps":["trace[1025441720] 'agreement among raft nodes before linearized reading' (duration: 311.511792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:35.886341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:35.57373Z","time spent":"312.349611ms","remote":"127.0.0.1:45540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3782,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74decbfe-4985-4a11-b7d1-809004dd64d7\" "} {"level":"info","ts":"2026-04-20T04:53:36.08237Z","caller":"traceutil/trace.go:171","msg":"trace[457225913] linearizableReadLoop","detail":"{readStateIndex:4736; appliedIndex:4735; }","duration":"197.021036ms","start":"2026-04-20T04:53:35.885316Z","end":"2026-04-20T04:53:36.082337Z","steps":["trace[457225913] 'read index received' (duration: 176.51827ms)","trace[457225913] 'applied index is now lower than readState.Index' (duration: 20.501366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:36.082856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.952391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:53:36.082929Z","caller":"traceutil/trace.go:171","msg":"trace[360904219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4566; }","duration":"436.086894ms","start":"2026-04-20T04:53:35.646822Z","end":"2026-04-20T04:53:36.082909Z","steps":["trace[360904219] 'agreement among raft nodes before linearized reading' (duration: 435.957041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:36.082971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:35.646794Z","time spent":"436.167256ms","remote":"127.0.0.1:45370","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-20T04:53:36.082782Z","caller":"traceutil/trace.go:171","msg":"trace[627695124] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"498.160607ms","start":"2026-04-20T04:53:35.584528Z","end":"2026-04-20T04:53:36.082688Z","steps":["trace[627695124] 'process raft request' (duration: 477.226922ms)","trace[627695124] 'compare' (duration: 20.401873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:36.083414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:35.584484Z","time spent":"498.663938ms","remote":"127.0.0.1:45590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:36.086537Z","caller":"traceutil/trace.go:171","msg":"trace[1456594070] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"193.046117ms","start":"2026-04-20T04:53:35.893432Z","end":"2026-04-20T04:53:36.086478Z","steps":["trace[1456594070] 'process raft request' (duration: 192.69478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:40.334998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.702254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:40.335395Z","caller":"traceutil/trace.go:171","msg":"trace[740505080] linearizableReadLoop","detail":"{readStateIndex:4847; appliedIndex:4844; }","duration":"160.480684ms","start":"2026-04-20T04:53:40.174885Z","end":"2026-04-20T04:53:40.335366Z","steps":["trace[740505080] 'read index received' (duration: 22.26699ms)","trace[740505080] 'applied index is now lower than readState.Index' (duration: 138.212054ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:40.335457Z","caller":"traceutil/trace.go:171","msg":"trace[780488095] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"161.094308ms","start":"2026-04-20T04:53:40.174329Z","end":"2026-04-20T04:53:40.335424Z","steps":["trace[780488095] 'process raft request' (duration: 160.814053ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:40.335523Z","caller":"traceutil/trace.go:171","msg":"trace[631158807] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"161.610689ms","start":"2026-04-20T04:53:40.173865Z","end":"2026-04-20T04:53:40.335475Z","steps":["trace[631158807] 'process raft request' (duration: 23.281492ms)","trace[631158807] 'compare' (duration: 137.321505ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:40.33554Z","caller":"traceutil/trace.go:171","msg":"trace[1530754838] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"161.13822ms","start":"2026-04-20T04:53:40.174375Z","end":"2026-04-20T04:53:40.335513Z","steps":["trace[1530754838] 'process raft request' (duration: 160.900414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:40.335637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.738651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-20T04:53:40.335689Z","caller":"traceutil/trace.go:171","msg":"trace[2048083595] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4672; }","duration":"160.804813ms","start":"2026-04-20T04:53:40.17487Z","end":"2026-04-20T04:53:40.335675Z","steps":["trace[2048083595] 'agreement among raft nodes before linearized reading' (duration: 160.591188ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:40.337805Z","caller":"traceutil/trace.go:171","msg":"trace[638166964] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"134.949552ms","start":"2026-04-20T04:53:40.202845Z","end":"2026-04-20T04:53:40.337794Z","steps":["trace[638166964] 'process raft request' (duration: 134.81681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:41.414695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.451006ms","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-20T04:53:41.414789Z","caller":"traceutil/trace.go:171","msg":"trace[1615259936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4689; }","duration":"266.607979ms","start":"2026-04-20T04:53:41.148161Z","end":"2026-04-20T04:53:41.414769Z","steps":["trace[1615259936] 'range keys from in-memory index tree' (duration: 265.440524ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:45.456135Z","caller":"traceutil/trace.go:171","msg":"trace[101094187] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"118.813378ms","start":"2026-04-20T04:53:45.337261Z","end":"2026-04-20T04:53:45.456074Z","steps":["trace[101094187] 'process raft request' (duration: 118.223585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.590416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.816203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:47.590574Z","caller":"traceutil/trace.go:171","msg":"trace[139628067] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"759.30505ms","start":"2026-04-20T04:53:46.831221Z","end":"2026-04-20T04:53:47.590526Z","steps":["trace[139628067] 'process raft request' (duration: 439.276251ms)","trace[139628067] 'compare' (duration: 319.571008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:47.590637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:46.831206Z","time spent":"759.401691ms","remote":"127.0.0.1:45512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:47.741633Z","caller":"traceutil/trace.go:171","msg":"trace[404964770] linearizableReadLoop","detail":"{readStateIndex:4970; appliedIndex:4967; }","duration":"875.145098ms","start":"2026-04-20T04:53:46.866461Z","end":"2026-04-20T04:53:47.741606Z","steps":["trace[404964770] 'read index received' (duration: 404.049745ms)","trace[404964770] 'applied index is now lower than readState.Index' (duration: 471.094463ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:47.742335Z","caller":"traceutil/trace.go:171","msg":"trace[547044227] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"909.7323ms","start":"2026-04-20T04:53:46.832589Z","end":"2026-04-20T04:53:47.742321Z","steps":["trace[547044227] 'process raft request' (duration: 908.80664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.742651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:46.832568Z","time spent":"909.803202ms","remote":"127.0.0.1:45612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:53:47.742831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.350596ms","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-20T04:53:47.74294Z","caller":"traceutil/trace.go:171","msg":"trace[1839921397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4793; }","duration":"876.523059ms","start":"2026-04-20T04:53:46.866399Z","end":"2026-04-20T04:53:47.742923Z","steps":["trace[1839921397] 'agreement among raft nodes before linearized reading' (duration: 876.205223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.743001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:46.866377Z","time spent":"876.616501ms","remote":"127.0.0.1:45608","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-20T04:53:47.744607Z","caller":"traceutil/trace.go:171","msg":"trace[1561128073] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"907.325298ms","start":"2026-04-20T04:53:46.834998Z","end":"2026-04-20T04:53:47.742323Z","steps":["trace[1561128073] 'process raft request' (duration: 906.551871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.744844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:46.834987Z","time spent":"909.716391ms","remote":"127.0.0.1:45540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:53:47.747551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.390598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42d5dd58-1ea0-40fe-93aa-b88e7cfd2b3f\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-04-20T04:53:47.747593Z","caller":"traceutil/trace.go:171","msg":"trace[50343203] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-42d5dd58-1ea0-40fe-93aa-b88e7cfd2b3f; range_end:; response_count:1; response_revision:4794; }","duration":"854.47622ms","start":"2026-04-20T04:53:46.893105Z","end":"2026-04-20T04:53:47.747581Z","steps":["trace[50343203] 'agreement among raft nodes before linearized reading' (duration: 854.311467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.747616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:46.893085Z","time spent":"854.524591ms","remote":"127.0.0.1:45540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42d5dd58-1ea0-40fe-93aa-b88e7cfd2b3f\" "} {"level":"info","ts":"2026-04-20T04:53:47.747694Z","caller":"traceutil/trace.go:171","msg":"trace[1151205030] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"148.702923ms","start":"2026-04-20T04:53:47.598983Z","end":"2026-04-20T04:53:47.747686Z","steps":["trace[1151205030] 'process raft request' (duration: 148.323946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.747903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.857669ms","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-20T04:53:47.747923Z","caller":"traceutil/trace.go:171","msg":"trace[708651926] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4794; }","duration":"191.921491ms","start":"2026-04-20T04:53:47.555996Z","end":"2026-04-20T04:53:47.747917Z","steps":["trace[708651926] 'agreement among raft nodes before linearized reading' (duration: 191.823059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.748034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.358591ms","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-20T04:53:47.748059Z","caller":"traceutil/trace.go:171","msg":"trace[1730782797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4794; }","duration":"240.417223ms","start":"2026-04-20T04:53:47.507635Z","end":"2026-04-20T04:53:47.748052Z","steps":["trace[1730782797] 'agreement among raft nodes before linearized reading' (duration: 240.34051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.748403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.03737ms","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-20T04:53:47.748492Z","caller":"traceutil/trace.go:171","msg":"trace[1475487774] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4794; }","duration":"819.173332ms","start":"2026-04-20T04:53:46.929298Z","end":"2026-04-20T04:53:47.748471Z","steps":["trace[1475487774] 'agreement among raft nodes before linearized reading' (duration: 818.865866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:47.748542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:46.929289Z","time spent":"819.244933ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T04:53:48.131254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.841959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:48.131473Z","caller":"traceutil/trace.go:171","msg":"trace[822086217] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"265.376987ms","start":"2026-04-20T04:53:47.866059Z","end":"2026-04-20T04:53:48.131436Z","steps":["trace[822086217] 'process raft request' (duration: 100.244583ms)","trace[822086217] 'compare' (duration: 164.538761ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:48.132063Z","caller":"traceutil/trace.go:171","msg":"trace[1556958863] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"264.648971ms","start":"2026-04-20T04:53:47.867395Z","end":"2026-04-20T04:53:48.132044Z","steps":["trace[1556958863] 'process raft request' (duration: 264.039328ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:48.131959Z","caller":"traceutil/trace.go:171","msg":"trace[1858574726] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4977; }","duration":"230.275617ms","start":"2026-04-20T04:53:47.901603Z","end":"2026-04-20T04:53:48.131878Z","steps":["trace[1858574726] 'read index received' (duration: 64.58017ms)","trace[1858574726] 'applied index is now lower than readState.Index' (duration: 165.693436ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:48.131705Z","caller":"traceutil/trace.go:171","msg":"trace[470354507] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"264.733074ms","start":"2026-04-20T04:53:47.866949Z","end":"2026-04-20T04:53:48.131682Z","steps":["trace[470354507] 'process raft request' (duration: 264.422836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:48.132424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.815108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-20T04:53:48.132548Z","caller":"traceutil/trace.go:171","msg":"trace[321600184] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4803; }","duration":"231.016653ms","start":"2026-04-20T04:53:47.901502Z","end":"2026-04-20T04:53:48.132518Z","steps":["trace[321600184] 'agreement among raft nodes before linearized reading' (duration: 230.602433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:48.132668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.750741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-74decbfe-4985-4a11-b7d1-809004dd64d7\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-20T04:53:48.132729Z","caller":"traceutil/trace.go:171","msg":"trace[2096434389] range","detail":"{range_begin:/registry/persistentvolumes/pvc-74decbfe-4985-4a11-b7d1-809004dd64d7; range_end:; response_count:1; response_revision:4803; }","duration":"227.793062ms","start":"2026-04-20T04:53:47.90491Z","end":"2026-04-20T04:53:48.132703Z","steps":["trace[2096434389] 'agreement among raft nodes before linearized reading' (duration: 227.620079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:48.132483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.204991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:53:48.13303Z","caller":"traceutil/trace.go:171","msg":"trace[1821724305] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4803; }","duration":"228.66418ms","start":"2026-04-20T04:53:47.904247Z","end":"2026-04-20T04:53:48.132911Z","steps":["trace[1821724305] 'agreement among raft nodes before linearized reading' (duration: 228.13082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:48.1332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.231168ms","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-20T04:53:48.133256Z","caller":"traceutil/trace.go:171","msg":"trace[2071574877] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4803; }","duration":"267.744569ms","start":"2026-04-20T04:53:47.865496Z","end":"2026-04-20T04:53:48.13324Z","steps":["trace[2071574877] 'agreement among raft nodes before linearized reading' (duration: 266.611014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:48.38897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.010283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:53:48.389506Z","caller":"traceutil/trace.go:171","msg":"trace[41604143] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"238.007518ms","start":"2026-04-20T04:53:48.151481Z","end":"2026-04-20T04:53:48.389488Z","steps":["trace[41604143] 'process raft request' (duration: 125.381142ms)","trace[41604143] 'compare' (duration: 111.88ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:48.389715Z","caller":"traceutil/trace.go:171","msg":"trace[2055731572] linearizableReadLoop","detail":"{readStateIndex:4981; appliedIndex:4980; }","duration":"235.811721ms","start":"2026-04-20T04:53:48.153879Z","end":"2026-04-20T04:53:48.389691Z","steps":["trace[2055731572] 'read index received' (duration: 122.856488ms)","trace[2055731572] 'applied index is now lower than readState.Index' (duration: 112.936873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:53:48.390202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.321192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-20T04:53:48.391112Z","caller":"traceutil/trace.go:171","msg":"trace[82285478] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4805; }","duration":"236.817832ms","start":"2026-04-20T04:53:48.153856Z","end":"2026-04-20T04:53:48.390674Z","steps":["trace[82285478] 'agreement among raft nodes before linearized reading' (duration: 235.946973ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:49.924887Z","caller":"traceutil/trace.go:171","msg":"trace[2025871262] linearizableReadLoop","detail":"{readStateIndex:4997; appliedIndex:4996; }","duration":"152.042021ms","start":"2026-04-20T04:53:49.772808Z","end":"2026-04-20T04:53:49.92485Z","steps":["trace[2025871262] 'read index received' (duration: 151.898678ms)","trace[2025871262] 'applied index is now lower than readState.Index' (duration: 141.713µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:53:49.924989Z","caller":"traceutil/trace.go:171","msg":"trace[1892264035] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"155.72037ms","start":"2026-04-20T04:53:49.769232Z","end":"2026-04-20T04:53:49.924953Z","steps":["trace[1892264035] 'process raft request' (duration: 155.366953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:49.92526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.392048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-20T04:53:49.925317Z","caller":"traceutil/trace.go:171","msg":"trace[2063845751] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4819; }","duration":"152.501501ms","start":"2026-04-20T04:53:49.772796Z","end":"2026-04-20T04:53:49.925298Z","steps":["trace[2063845751] 'agreement among raft nodes before linearized reading' (duration: 152.234515ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:49.93868Z","caller":"traceutil/trace.go:171","msg":"trace[1119492031] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"165.81551ms","start":"2026-04-20T04:53:49.772838Z","end":"2026-04-20T04:53:49.938653Z","steps":["trace[1119492031] 'process raft request' (duration: 165.504322ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:49.938744Z","caller":"traceutil/trace.go:171","msg":"trace[2075586583] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"160.850582ms","start":"2026-04-20T04:53:49.777864Z","end":"2026-04-20T04:53:49.938715Z","steps":["trace[2075586583] 'process raft request' (duration: 160.684488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:49.939081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.80814ms","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-20T04:53:49.939137Z","caller":"traceutil/trace.go:171","msg":"trace[1099659463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4822; }","duration":"165.907191ms","start":"2026-04-20T04:53:49.773214Z","end":"2026-04-20T04:53:49.939121Z","steps":["trace[1099659463] 'agreement among raft nodes before linearized reading' (duration: 165.692667ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:49.939106Z","caller":"traceutil/trace.go:171","msg":"trace[1585246904] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"146.156814ms","start":"2026-04-20T04:53:49.792933Z","end":"2026-04-20T04:53:49.93909Z","steps":["trace[1585246904] 'process raft request' (duration: 145.694725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:49.939304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.728476ms","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-20T04:53:49.939485Z","caller":"traceutil/trace.go:171","msg":"trace[1151477422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4822; }","duration":"166.024473ms","start":"2026-04-20T04:53:49.773447Z","end":"2026-04-20T04:53:49.939471Z","steps":["trace[1151477422] 'agreement among raft nodes before linearized reading' (duration: 165.637924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:53:51.387701Z","caller":"traceutil/trace.go:171","msg":"trace[691754356] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"574.28175ms","start":"2026-04-20T04:53:50.812628Z","end":"2026-04-20T04:53:51.38691Z","steps":["trace[691754356] 'process raft request' (duration: 574.068085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:53:51.388481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:53:50.812596Z","time spent":"575.751571ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:53:54.776606Z","caller":"traceutil/trace.go:171","msg":"trace[1957454928] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"127.186365ms","start":"2026-04-20T04:53:54.649374Z","end":"2026-04-20T04:53:54.77656Z","steps":["trace[1957454928] 'process raft request' (duration: 126.947311ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:00.15709Z","caller":"traceutil/trace.go:171","msg":"trace[1825680736] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"110.07663ms","start":"2026-04-20T04:54:00.046977Z","end":"2026-04-20T04:54:00.157054Z","steps":["trace[1825680736] 'process raft request' (duration: 78.985605ms)","trace[1825680736] 'compare' (duration: 30.899101ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:00.392324Z","caller":"traceutil/trace.go:171","msg":"trace[1767515740] linearizableReadLoop","detail":"{readStateIndex:5134; appliedIndex:5131; }","duration":"295.053431ms","start":"2026-04-20T04:54:00.097241Z","end":"2026-04-20T04:54:00.392294Z","steps":["trace[1767515740] 'read index received' (duration: 28.662845ms)","trace[1767515740] 'applied index is now lower than readState.Index' (duration: 266.389366ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:00.392348Z","caller":"traceutil/trace.go:171","msg":"trace[1760963692] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"345.315021ms","start":"2026-04-20T04:54:00.046995Z","end":"2026-04-20T04:54:00.39231Z","steps":["trace[1760963692] 'process raft request' (duration: 344.977724ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:00.392401Z","caller":"traceutil/trace.go:171","msg":"trace[1262435343] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"303.501259ms","start":"2026-04-20T04:54:00.088836Z","end":"2026-04-20T04:54:00.392338Z","steps":["trace[1262435343] 'process raft request' (duration: 303.406707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:00.392502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:00.046969Z","time spent":"345.449615ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:54:00.392654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:00.088799Z","time spent":"303.711803ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T04:54:00.392715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.389508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xzjnw.18a7f79a30d012cf\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-20T04:54:00.392773Z","caller":"traceutil/trace.go:171","msg":"trace[760249051] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-xzjnw.18a7f79a30d012cf; range_end:; response_count:1; response_revision:4952; }","duration":"295.583952ms","start":"2026-04-20T04:54:00.097172Z","end":"2026-04-20T04:54:00.392756Z","steps":["trace[760249051] 'agreement among raft nodes before linearized reading' (duration: 295.231815ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:00.538822Z","caller":"traceutil/trace.go:171","msg":"trace[1352423017] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"142.69905ms","start":"2026-04-20T04:54:00.396084Z","end":"2026-04-20T04:54:00.538783Z","steps":["trace[1352423017] 'process raft request' (duration: 107.580819ms)","trace[1352423017] 'compare' (duration: 34.719022ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:00.539528Z","caller":"traceutil/trace.go:171","msg":"trace[2022635484] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"109.409168ms","start":"2026-04-20T04:54:00.43006Z","end":"2026-04-20T04:54:00.539469Z","steps":["trace[2022635484] 'process raft request' (duration: 108.676993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:00.765812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.204695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:54:00.765968Z","caller":"traceutil/trace.go:171","msg":"trace[1656167394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4954; }","duration":"121.43192ms","start":"2026-04-20T04:54:00.644495Z","end":"2026-04-20T04:54:00.765927Z","steps":["trace[1656167394] 'range keys from in-memory index tree' (duration: 121.079763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:02.019884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.627493ms","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-20T04:54:02.020294Z","caller":"traceutil/trace.go:171","msg":"trace[1554019424] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4969; }","duration":"338.053703ms","start":"2026-04-20T04:54:01.682217Z","end":"2026-04-20T04:54:02.020271Z","steps":["trace[1554019424] 'range keys from in-memory index tree' (duration: 337.579973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:02.022878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.394047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T04:54:02.022972Z","caller":"traceutil/trace.go:171","msg":"trace[514379419] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"126.807548ms","start":"2026-04-20T04:54:01.896149Z","end":"2026-04-20T04:54:02.022957Z","steps":["trace[514379419] 'read index received' (duration: 43.601µs)","trace[514379419] 'applied index is now lower than readState.Index' (duration: 126.762357ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:02.023087Z","caller":"traceutil/trace.go:171","msg":"trace[213155098] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"574.941037ms","start":"2026-04-20T04:54:01.44813Z","end":"2026-04-20T04:54:02.023071Z","steps":["trace[213155098] 'process raft request' (duration: 162.270844ms)","trace[213155098] 'compare' (duration: 412.243794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:02.024091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.911231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"info","ts":"2026-04-20T04:54:02.024207Z","caller":"traceutil/trace.go:171","msg":"trace[1215815669] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4970; }","duration":"128.041594ms","start":"2026-04-20T04:54:01.896136Z","end":"2026-04-20T04:54:02.024178Z","steps":["trace[1215815669] 'agreement among raft nodes before linearized reading' (duration: 127.770108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:02.02549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T04:54:01.44808Z","time spent":"575.03526ms","remote":"127.0.0.1:45608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T04:54:05.202816Z","caller":"traceutil/trace.go:171","msg":"trace[628077579] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"130.78542ms","start":"2026-04-20T04:54:05.071948Z","end":"2026-04-20T04:54:05.202734Z","steps":["trace[628077579] 'process raft request' (duration: 128.063383ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:13.029411Z","caller":"traceutil/trace.go:171","msg":"trace[1518985416] linearizableReadLoop","detail":"{readStateIndex:5248; appliedIndex:5247; }","duration":"124.327759ms","start":"2026-04-20T04:54:12.905058Z","end":"2026-04-20T04:54:13.029386Z","steps":["trace[1518985416] 'read index received' (duration: 79.388625ms)","trace[1518985416] 'applied index is now lower than readState.Index' (duration: 44.937834ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:13.029571Z","caller":"traceutil/trace.go:171","msg":"trace[1417758423] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"130.913395ms","start":"2026-04-20T04:54:12.898596Z","end":"2026-04-20T04:54:13.029509Z","steps":["trace[1417758423] 'process raft request' (duration: 85.923439ms)","trace[1417758423] 'compare' (duration: 44.663109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:54:13.029881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.795639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:54:13.029961Z","caller":"traceutil/trace.go:171","msg":"trace[1997020943] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5065; }","duration":"124.911241ms","start":"2026-04-20T04:54:12.905027Z","end":"2026-04-20T04:54:13.029938Z","steps":["trace[1997020943] 'agreement among raft nodes before linearized reading' (duration: 124.509762ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:36.845111Z","caller":"traceutil/trace.go:171","msg":"trace[1669842337] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"115.363526ms","start":"2026-04-20T04:54:36.729559Z","end":"2026-04-20T04:54:36.844923Z","steps":["trace[1669842337] 'process raft request' (duration: 26.903542ms)","trace[1669842337] 'compare' (duration: 87.557186ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:36.98324Z","caller":"traceutil/trace.go:171","msg":"trace[2081028012] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5464; }","duration":"110.142312ms","start":"2026-04-20T04:54:36.873066Z","end":"2026-04-20T04:54:36.983208Z","steps":["trace[2081028012] 'read index received' (duration: 105.847127ms)","trace[2081028012] 'applied index is now lower than readState.Index' (duration: 4.294405ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:54:36.983306Z","caller":"traceutil/trace.go:171","msg":"trace[1295997568] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"121.874255ms","start":"2026-04-20T04:54:36.861401Z","end":"2026-04-20T04:54:36.983275Z","steps":["trace[1295997568] 'process raft request' (duration: 117.6397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:54:36.984355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.264954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-20T04:54:36.984397Z","caller":"traceutil/trace.go:171","msg":"trace[1231052809] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5275; }","duration":"111.372276ms","start":"2026-04-20T04:54:36.873015Z","end":"2026-04-20T04:54:36.984388Z","steps":["trace[1231052809] 'agreement among raft nodes before linearized reading' (duration: 110.311186ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:54:37.311423Z","caller":"traceutil/trace.go:171","msg":"trace[889145617] transaction","detail":"{read_only:false; response_revision:5282; number_of_response:1; }","duration":"103.499968ms","start":"2026-04-20T04:54:37.207877Z","end":"2026-04-20T04:54:37.311377Z","steps":["trace[889145617] 'process raft request' (duration: 16.223869ms)","trace[889145617] 'compare' (duration: 87.003044ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:05.276872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2858} {"level":"info","ts":"2026-04-20T04:55:05.345859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2858,"took":"67.249589ms","hash":2083033455,"current-db-size-bytes":53788672,"current-db-size":"54 MB","current-db-size-in-use-bytes":28651520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T04:55:05.345944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2083033455,"revision":2858,"compact-revision":-1} {"level":"info","ts":"2026-04-20T04:55:26.673213Z","caller":"traceutil/trace.go:171","msg":"trace[1983225813] transaction","detail":"{read_only:false; response_revision:5639; number_of_response:1; }","duration":"119.359023ms","start":"2026-04-20T04:55:26.553807Z","end":"2026-04-20T04:55:26.673166Z","steps":["trace[1983225813] 'process raft request' (duration: 118.754082ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:26.813617Z","caller":"traceutil/trace.go:171","msg":"trace[1551444721] transaction","detail":"{read_only:false; response_revision:5641; number_of_response:1; }","duration":"130.209962ms","start":"2026-04-20T04:55:26.683361Z","end":"2026-04-20T04:55:26.813571Z","steps":["trace[1551444721] 'process raft request' (duration: 104.824425ms)","trace[1551444721] 'compare' (duration: 25.115781ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:26.993265Z","caller":"traceutil/trace.go:171","msg":"trace[745944472] transaction","detail":"{read_only:false; response_revision:5643; number_of_response:1; }","duration":"167.171981ms","start":"2026-04-20T04:55:26.825995Z","end":"2026-04-20T04:55:26.993167Z","steps":["trace[745944472] 'process raft request' (duration: 166.994408ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:27.172449Z","caller":"traceutil/trace.go:171","msg":"trace[517991137] linearizableReadLoop","detail":"{readStateIndex:5847; appliedIndex:5846; }","duration":"165.505286ms","start":"2026-04-20T04:55:27.006894Z","end":"2026-04-20T04:55:27.172399Z","steps":["trace[517991137] 'read index received' (duration: 164.562129ms)","trace[517991137] 'applied index is now lower than readState.Index' (duration: 941.167µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:27.172468Z","caller":"traceutil/trace.go:171","msg":"trace[1953242738] transaction","detail":"{read_only:false; response_revision:5645; number_of_response:1; }","duration":"173.058254ms","start":"2026-04-20T04:55:26.999358Z","end":"2026-04-20T04:55:27.172416Z","steps":["trace[1953242738] 'process raft request' (duration: 172.135667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:27.172636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.726789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:55:27.17272Z","caller":"traceutil/trace.go:171","msg":"trace[1234524611] range","detail":"{range_begin:/registry/jobs/openstack/keystone-rabbit-init; range_end:; response_count:0; response_revision:5645; }","duration":"165.868263ms","start":"2026-04-20T04:55:27.006833Z","end":"2026-04-20T04:55:27.172701Z","steps":["trace[1234524611] 'agreement among raft nodes before linearized reading' (duration: 165.670069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T04:55:27.173231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.21991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T04:55:27.173352Z","caller":"traceutil/trace.go:171","msg":"trace[1243552588] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5645; }","duration":"105.414753ms","start":"2026-04-20T04:55:27.067909Z","end":"2026-04-20T04:55:27.173323Z","steps":["trace[1243552588] 'agreement among raft nodes before linearized reading' (duration: 105.085508ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:33.551411Z","caller":"traceutil/trace.go:171","msg":"trace[753831815] linearizableReadLoop","detail":"{readStateIndex:5920; appliedIndex:5919; }","duration":"100.580413ms","start":"2026-04-20T04:55:33.450798Z","end":"2026-04-20T04:55:33.551379Z","steps":["trace[753831815] 'read index received' (duration: 74.958326ms)","trace[753831815] 'applied index is now lower than readState.Index' (duration: 25.620417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:55:33.551859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.01588ms","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-20T04:55:33.552014Z","caller":"traceutil/trace.go:171","msg":"trace[606836602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5716; }","duration":"101.237215ms","start":"2026-04-20T04:55:33.45074Z","end":"2026-04-20T04:55:33.551978Z","steps":["trace[606836602] 'agreement among raft nodes before linearized reading' (duration: 100.772226ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:34.238726Z","caller":"traceutil/trace.go:171","msg":"trace[446815860] linearizableReadLoop","detail":"{readStateIndex:5927; appliedIndex:5926; }","duration":"123.949116ms","start":"2026-04-20T04:55:34.114748Z","end":"2026-04-20T04:55:34.238698Z","steps":["trace[446815860] 'read index received' (duration: 46.6701ms)","trace[446815860] 'applied index is now lower than readState.Index' (duration: 77.278046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T04:55:34.238908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.135839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T04:55:34.238946Z","caller":"traceutil/trace.go:171","msg":"trace[1725176198] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5723; }","duration":"124.247801ms","start":"2026-04-20T04:55:34.114685Z","end":"2026-04-20T04:55:34.238933Z","steps":["trace[1725176198] 'agreement among raft nodes before linearized reading' (duration: 124.127089ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:55:34.238937Z","caller":"traceutil/trace.go:171","msg":"trace[1430217439] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"186.949702ms","start":"2026-04-20T04:55:34.051954Z","end":"2026-04-20T04:55:34.238903Z","steps":["trace[1430217439] 'process raft request' (duration: 109.623994ms)","trace[1430217439] 'compare' (duration: 76.94155ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T04:55:39.39767Z","caller":"traceutil/trace.go:171","msg":"trace[802329586] transaction","detail":"{read_only:false; response_revision:5749; number_of_response:1; }","duration":"113.135536ms","start":"2026-04-20T04:55:39.284501Z","end":"2026-04-20T04:55:39.397637Z","steps":["trace[802329586] 'process raft request' (duration: 112.954363ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:57:31.483672Z","caller":"traceutil/trace.go:171","msg":"trace[2133261756] transaction","detail":"{read_only:false; response_revision:6512; number_of_response:1; }","duration":"110.426273ms","start":"2026-04-20T04:57:31.373215Z","end":"2026-04-20T04:57:31.483642Z","steps":["trace[2133261756] 'process raft request' (duration: 110.268591ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:57:42.605008Z","caller":"traceutil/trace.go:171","msg":"trace[510264521] transaction","detail":"{read_only:false; response_revision:6574; number_of_response:1; }","duration":"162.983592ms","start":"2026-04-20T04:57:42.441989Z","end":"2026-04-20T04:57:42.604973Z","steps":["trace[510264521] 'process raft request' (duration: 156.957242ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:57:42.605479Z","caller":"traceutil/trace.go:171","msg":"trace[965319283] transaction","detail":"{read_only:false; response_revision:6575; number_of_response:1; }","duration":"163.455879ms","start":"2026-04-20T04:57:42.441992Z","end":"2026-04-20T04:57:42.605448Z","steps":["trace[965319283] 'process raft request' (duration: 162.919901ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:58:02.656773Z","caller":"traceutil/trace.go:171","msg":"trace[1094693881] transaction","detail":"{read_only:false; response_revision:6733; number_of_response:1; }","duration":"108.001979ms","start":"2026-04-20T04:58:02.548731Z","end":"2026-04-20T04:58:02.656733Z","steps":["trace[1094693881] 'process raft request' (duration: 107.667603ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T04:59:32.783649Z","caller":"traceutil/trace.go:171","msg":"trace[1538063616] transaction","detail":"{read_only:false; response_revision:7215; number_of_response:1; }","duration":"128.534803ms","start":"2026-04-20T04:59:32.655089Z","end":"2026-04-20T04:59:32.783623Z","steps":["trace[1538063616] 'process raft request' (duration: 128.377367ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:00:05.388996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5495} {"level":"info","ts":"2026-04-20T05:00:05.479424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5495,"took":"88.495309ms","hash":105555991,"current-db-size-bytes":53788672,"current-db-size":"54 MB","current-db-size-in-use-bytes":18362368,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T05:00:05.479501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":105555991,"revision":5495,"compact-revision":2858} {"level":"info","ts":"2026-04-20T05:00:34.554582Z","caller":"traceutil/trace.go:171","msg":"trace[2133511172] linearizableReadLoop","detail":"{readStateIndex:7800; appliedIndex:7799; }","duration":"146.49011ms","start":"2026-04-20T05:00:34.408071Z","end":"2026-04-20T05:00:34.554561Z","steps":["trace[2133511172] 'read index received' (duration: 146.245353ms)","trace[2133511172] 'applied index is now lower than readState.Index' (duration: 243.767µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:00:34.554658Z","caller":"traceutil/trace.go:171","msg":"trace[525888628] transaction","detail":"{read_only:false; response_revision:7526; number_of_response:1; }","duration":"148.73222ms","start":"2026-04-20T05:00:34.405893Z","end":"2026-04-20T05:00:34.554625Z","steps":["trace[525888628] 'process raft request' (duration: 148.520664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.554914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.812441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/local-path-storage/\" range_end:\"/registry/rolebindings/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:00:34.55497Z","caller":"traceutil/trace.go:171","msg":"trace[1382547445] range","detail":"{range_begin:/registry/rolebindings/local-path-storage/; range_end:/registry/rolebindings/local-path-storage0; response_count:0; response_revision:7526; }","duration":"146.924634ms","start":"2026-04-20T05:00:34.408029Z","end":"2026-04-20T05:00:34.554953Z","steps":["trace[1382547445] 'agreement among raft nodes before linearized reading' (duration: 146.663485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.555108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.433269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/ingress-nginx/ingress-nginx-controller-metrics\" ","response":"range_response_count:1 size:1167"} {"level":"info","ts":"2026-04-20T05:00:34.555202Z","caller":"traceutil/trace.go:171","msg":"trace[348059367] range","detail":"{range_begin:/registry/services/endpoints/ingress-nginx/ingress-nginx-controller-metrics; range_end:; response_count:1; response_revision:7526; }","duration":"123.548103ms","start":"2026-04-20T05:00:34.431632Z","end":"2026-04-20T05:00:34.55518Z","steps":["trace[348059367] 'agreement among raft nodes before linearized reading' (duration: 123.332346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.555173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.891104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/\" range_end:\"/registry/ingress/monitoring0\" limit:500 ","response":"range_response_count:3 size:4661"} {"level":"info","ts":"2026-04-20T05:00:34.5553Z","caller":"traceutil/trace.go:171","msg":"trace[128031933] range","detail":"{range_begin:/registry/ingress/monitoring/; range_end:/registry/ingress/monitoring0; response_count:3; response_revision:7526; }","duration":"101.043379ms","start":"2026-04-20T05:00:34.454211Z","end":"2026-04-20T05:00:34.555255Z","steps":["trace[128031933] 'agreement among raft nodes before linearized reading' (duration: 100.687958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.555636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.877786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/ingress-nginx/ingress-nginx-defaultbackend\" ","response":"range_response_count:1 size:1180"} {"level":"info","ts":"2026-04-20T05:00:34.555687Z","caller":"traceutil/trace.go:171","msg":"trace[806256202] range","detail":"{range_begin:/registry/services/endpoints/ingress-nginx/ingress-nginx-defaultbackend; range_end:; response_count:1; response_revision:7526; }","duration":"133.959768ms","start":"2026-04-20T05:00:34.421712Z","end":"2026-04-20T05:00:34.555672Z","steps":["trace[806256202] 'agreement among raft nodes before linearized reading' (duration: 133.147003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:00:34.88089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.816813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-xdf2p\" ","response":"range_response_count:1 size:7120"} {"level":"info","ts":"2026-04-20T05:00:34.881019Z","caller":"traceutil/trace.go:171","msg":"trace[1241670656] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-xdf2p; range_end:; response_count:1; response_revision:7528; }","duration":"101.965868ms","start":"2026-04-20T05:00:34.779025Z","end":"2026-04-20T05:00:34.880991Z","steps":["trace[1241670656] 'range keys from in-memory index tree' (duration: 101.628747ms)"],"step_count":1}