{"level":"warn","ts":"2026-05-21T19:25:40.519842Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:25:40.519999Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.43:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.43:2380","--initial-cluster=instance=https://199.19.213.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.43: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-05-21T19:25:40.520231Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:25:40.520333Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.43:2380"]} {"level":"info","ts":"2026-05-21T19:25:40.520525Z","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-05-21T19:25:40.521676Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"]} {"level":"info","ts":"2026-05-21T19:25:40.52177Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.43:2380"],"listen-peer-urls":["https://199.19.213.43:2380"],"advertise-client-urls":["https://199.19.213.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.43: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-05-21T19:25:40.52655Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.509909ms"} {"level":"info","ts":"2026-05-21T19:25:40.532933Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a8d0274548ffaee4","cluster-id":"858b677d1d9175b1"} {"level":"info","ts":"2026-05-21T19:25:40.533029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T19:25:40.53305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became follower at term 0"} {"level":"info","ts":"2026-05-21T19:25:40.533064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a8d0274548ffaee4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T19:25:40.533074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became follower at term 1"} {"level":"info","ts":"2026-05-21T19:25:40.533117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=(12164265772058652388)"} {"level":"warn","ts":"2026-05-21T19:25:40.543513Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T19:25:40.54508Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T19:25:40.547001Z","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-05-21T19:25:40.549243Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a8d0274548ffaee4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T19:25:40.549495Z","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-05-21T19:25:40.549548Z","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-05-21T19:25:40.549557Z","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-05-21T19:25:40.549523Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a8d0274548ffaee4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T19:25:40.552164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 switched to configuration voters=(12164265772058652388)"} {"level":"info","ts":"2026-05-21T19:25:40.55264Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"858b677d1d9175b1","local-member-id":"a8d0274548ffaee4","added-peer-id":"a8d0274548ffaee4","added-peer-peer-urls":["https://199.19.213.43:2380"]} {"level":"info","ts":"2026-05-21T19:25:40.553688Z","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-05-21T19:25:40.553936Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a8d0274548ffaee4","initial-advertise-peer-urls":["https://199.19.213.43:2380"],"listen-peer-urls":["https://199.19.213.43:2380"],"advertise-client-urls":["https://199.19.213.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T19:25:40.553974Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T19:25:40.554217Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.43:2380"} {"level":"info","ts":"2026-05-21T19:25:40.554246Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.43:2380"} {"level":"info","ts":"2026-05-21T19:25:40.634702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T19:25:40.634785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T19:25:40.634894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 received MsgPreVoteResp from a8d0274548ffaee4 at term 1"} {"level":"info","ts":"2026-05-21T19:25:40.634928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became candidate at term 2"} {"level":"info","ts":"2026-05-21T19:25:40.634945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 received MsgVoteResp from a8d0274548ffaee4 at term 2"} {"level":"info","ts":"2026-05-21T19:25:40.634999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a8d0274548ffaee4 became leader at term 2"} {"level":"info","ts":"2026-05-21T19:25:40.635023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a8d0274548ffaee4 elected leader a8d0274548ffaee4 at term 2"} {"level":"info","ts":"2026-05-21T19:25:40.636065Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a8d0274548ffaee4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.43:2379]}","request-path":"/0/members/a8d0274548ffaee4/attributes","cluster-id":"858b677d1d9175b1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T19:25:40.636249Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:25:40.636283Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:25:40.636605Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:25:40.638047Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"858b677d1d9175b1","local-member-id":"a8d0274548ffaee4","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:25:40.638109Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T19:25:40.638139Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T19:25:40.638182Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:25:40.638243Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:25:40.63896Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.43:2379"} {"level":"info","ts":"2026-05-21T19:25:40.639571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T19:25:42.473658Z","caller":"traceutil/trace.go:171","msg":"trace[1828559059] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"412.91523ms","start":"2026-05-21T19:25:42.060718Z","end":"2026-05-21T19:25:42.473633Z","steps":["trace[1828559059] 'process raft request' (duration: 412.703696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.47433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.060709Z","time spent":"413.067919ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:25:42.671586Z","caller":"traceutil/trace.go:171","msg":"trace[1506729202] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"528.126388ms","start":"2026-05-21T19:25:42.143424Z","end":"2026-05-21T19:25:42.67155Z","steps":["trace[1506729202] 'process raft request' (duration: 528.027821ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.671695Z","caller":"traceutil/trace.go:171","msg":"trace[1285809684] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"610.001288ms","start":"2026-05-21T19:25:42.061629Z","end":"2026-05-21T19:25:42.671631Z","steps":["trace[1285809684] 'process raft request' (duration: 609.680407ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.671699Z","caller":"traceutil/trace.go:171","msg":"trace[2036878658] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"608.732154ms","start":"2026-05-21T19:25:42.062944Z","end":"2026-05-21T19:25:42.671676Z","steps":["trace[2036878658] 'read index received' (duration: 410.625876ms)","trace[2036878658] 'applied index is now lower than readState.Index' (duration: 198.105238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:25:42.671859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.143408Z","time spent":"528.321981ms","remote":"127.0.0.1:43238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:25:42.671892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.061618Z","time spent":"610.187621ms","remote":"127.0.0.1:42864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:25:42.672151Z","caller":"traceutil/trace.go:171","msg":"trace[2026381944] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"514.561615ms","start":"2026-05-21T19:25:42.157575Z","end":"2026-05-21T19:25:42.672136Z","steps":["trace[2026381944] 'process raft request' (duration: 514.047071ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.672156Z","caller":"traceutil/trace.go:171","msg":"trace[1797938843] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"514.50421ms","start":"2026-05-21T19:25:42.157569Z","end":"2026-05-21T19:25:42.672073Z","steps":["trace[1797938843] 'process raft request' (duration: 514.028819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.672252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.157554Z","time spent":"514.638849ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:25:42.672383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.780226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T19:25:42.672378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.429402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:25:42.672462Z","caller":"traceutil/trace.go:171","msg":"trace[2099682780] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"597.866122ms","start":"2026-05-21T19:25:42.074582Z","end":"2026-05-21T19:25:42.672448Z","steps":["trace[2099682780] 'agreement among raft nodes before linearized reading' (duration: 597.744554ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.672484Z","caller":"traceutil/trace.go:171","msg":"trace[1230861261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"609.55622ms","start":"2026-05-21T19:25:42.062908Z","end":"2026-05-21T19:25:42.672464Z","steps":["trace[1230861261] 'agreement among raft nodes before linearized reading' (duration: 609.346375ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.672427Z","caller":"traceutil/trace.go:171","msg":"trace[530315754] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"514.810452ms","start":"2026-05-21T19:25:42.157603Z","end":"2026-05-21T19:25:42.672414Z","steps":["trace[530315754] 'process raft request' (duration: 514.048961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.672565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.059021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T19:25:42.672576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.615844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:25:42.672597Z","caller":"traceutil/trace.go:171","msg":"trace[686294167] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"526.104543ms","start":"2026-05-21T19:25:42.146483Z","end":"2026-05-21T19:25:42.672587Z","steps":["trace[686294167] 'agreement among raft nodes before linearized reading' (duration: 526.064391ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.672613Z","caller":"traceutil/trace.go:171","msg":"trace[642647321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"609.673187ms","start":"2026-05-21T19:25:42.062929Z","end":"2026-05-21T19:25:42.672603Z","steps":["trace[642647321] 'agreement among raft nodes before linearized reading' (duration: 609.603453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.672631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.146472Z","time spent":"526.152427ms","remote":"127.0.0.1:42880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-21T19:25:42.672645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.062924Z","time spent":"609.715081ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:25:42.672642Z","caller":"traceutil/trace.go:171","msg":"trace[1474242777] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"520.377793ms","start":"2026-05-21T19:25:42.152085Z","end":"2026-05-21T19:25:42.672463Z","steps":["trace[1474242777] 'process raft request' (duration: 519.400158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.672705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.157562Z","time spent":"515.014016ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:25:42.672784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.160245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T19:25:42.672531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.010118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:25:42.672819Z","caller":"traceutil/trace.go:171","msg":"trace[1132352640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"530.221709ms","start":"2026-05-21T19:25:42.142588Z","end":"2026-05-21T19:25:42.67281Z","steps":["trace[1132352640] 'agreement among raft nodes before linearized reading' (duration: 530.165065ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.672847Z","caller":"traceutil/trace.go:171","msg":"trace[1002108543] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"479.353261ms","start":"2026-05-21T19:25:42.193484Z","end":"2026-05-21T19:25:42.672837Z","steps":["trace[1002108543] 'agreement among raft nodes before linearized reading' (duration: 479.03127ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.672849Z","caller":"traceutil/trace.go:171","msg":"trace[657737442] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"517.979703ms","start":"2026-05-21T19:25:42.154848Z","end":"2026-05-21T19:25:42.672827Z","steps":["trace[657737442] 'process raft request' (duration: 516.668596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.672803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.15207Z","time spent":"520.659972ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:25:42.672918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.957448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T19:25:42.672933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.154828Z","time spent":"518.058878ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:25:42.672409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.157556Z","time spent":"514.720405ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:25:42.673078Z","caller":"traceutil/trace.go:171","msg":"trace[1793368623] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"515.53629ms","start":"2026-05-21T19:25:42.157532Z","end":"2026-05-21T19:25:42.673068Z","steps":["trace[1793368623] 'process raft request' (duration: 514.04146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.672882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.193471Z","time spent":"479.402974ms","remote":"127.0.0.1:42918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-21T19:25:42.672851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.142572Z","time spent":"530.274163ms","remote":"127.0.0.1:42918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-21T19:25:42.673215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.157515Z","time spent":"515.595964ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:25:42.672885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.172602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.43\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:25:42.673269Z","caller":"traceutil/trace.go:171","msg":"trace[1511223245] range","detail":"{range_begin:/registry/masterleases/199.19.213.43; range_end:; response_count:0; response_revision:11; }","duration":"531.568418ms","start":"2026-05-21T19:25:42.141687Z","end":"2026-05-21T19:25:42.673255Z","steps":["trace[1511223245] 'agreement among raft nodes before linearized reading' (duration: 531.183072ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:25:42.673301Z","caller":"traceutil/trace.go:171","msg":"trace[1411672434] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"515.98294ms","start":"2026-05-21T19:25:42.157309Z","end":"2026-05-21T19:25:42.673292Z","steps":["trace[1411672434] 'process raft request' (duration: 514.237234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.673313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.141677Z","time spent":"531.627222ms","remote":"127.0.0.1:42828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.43\" "} {"level":"warn","ts":"2026-05-21T19:25:42.673339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.157286Z","time spent":"516.035253ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:25:42.672943Z","caller":"traceutil/trace.go:171","msg":"trace[1522528347] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"560.001ms","start":"2026-05-21T19:25:42.112935Z","end":"2026-05-21T19:25:42.672936Z","steps":["trace[1522528347] 'agreement among raft nodes before linearized reading' (duration: 559.969708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:25:42.672535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.062901Z","time spent":"609.624244ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T19:25:42.672496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.074561Z","time spent":"597.928126ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-21T19:25:42.67349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:25:42.112919Z","time spent":"560.559327ms","remote":"127.0.0.1:42918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-21T19:26:14.030911Z","caller":"traceutil/trace.go:171","msg":"trace[1701474513] transaction","detail":"{read_only:false; number_of_response:1; response_revision:487; }","duration":"161.573754ms","start":"2026-05-21T19:26:13.869314Z","end":"2026-05-21T19:26:14.030887Z","steps":["trace[1701474513] 'process raft request' (duration: 161.502678ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.031178Z","caller":"traceutil/trace.go:171","msg":"trace[1755577244] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"167.797914ms","start":"2026-05-21T19:26:13.863353Z","end":"2026-05-21T19:26:14.031151Z","steps":["trace[1755577244] 'process raft request' (duration: 167.433205ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.031366Z","caller":"traceutil/trace.go:171","msg":"trace[1164184784] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"168.074749ms","start":"2026-05-21T19:26:13.86328Z","end":"2026-05-21T19:26:14.031355Z","steps":["trace[1164184784] 'process raft request' (duration: 167.453124ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.031514Z","caller":"traceutil/trace.go:171","msg":"trace[1029772665] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"161.399624ms","start":"2026-05-21T19:26:13.870106Z","end":"2026-05-21T19:26:14.031506Z","steps":["trace[1029772665] 'process raft request' (duration: 160.744031ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.031608Z","caller":"traceutil/trace.go:171","msg":"trace[879235987] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"167.914097ms","start":"2026-05-21T19:26:13.863101Z","end":"2026-05-21T19:26:14.031015Z","steps":["trace[879235987] 'process raft request' (duration: 167.50293ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.038838Z","caller":"traceutil/trace.go:171","msg":"trace[558458373] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"164.266712ms","start":"2026-05-21T19:26:13.874553Z","end":"2026-05-21T19:26:14.03882Z","steps":["trace[558458373] 'process raft request' (duration: 163.851905ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.038859Z","caller":"traceutil/trace.go:171","msg":"trace[884506403] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"164.13858ms","start":"2026-05-21T19:26:13.874707Z","end":"2026-05-21T19:26:14.038845Z","steps":["trace[884506403] 'process raft request' (duration: 163.77858ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.038953Z","caller":"traceutil/trace.go:171","msg":"trace[512453013] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"161.855541ms","start":"2026-05-21T19:26:13.877091Z","end":"2026-05-21T19:26:14.038946Z","steps":["trace[512453013] 'process raft request' (duration: 161.414416ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.039011Z","caller":"traceutil/trace.go:171","msg":"trace[1624603544] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"164.396715ms","start":"2026-05-21T19:26:13.874602Z","end":"2026-05-21T19:26:14.038998Z","steps":["trace[1624603544] 'process raft request' (duration: 163.863655ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.039045Z","caller":"traceutil/trace.go:171","msg":"trace[1522879884] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"158.762851ms","start":"2026-05-21T19:26:13.880277Z","end":"2026-05-21T19:26:14.03904Z","steps":["trace[1522879884] 'process raft request' (duration: 158.273698ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.039086Z","caller":"traceutil/trace.go:171","msg":"trace[757727342] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"154.633729ms","start":"2026-05-21T19:26:13.884449Z","end":"2026-05-21T19:26:14.039083Z","steps":["trace[757727342] 'process raft request' (duration: 154.135027ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.039135Z","caller":"traceutil/trace.go:171","msg":"trace[939510002] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"134.670774ms","start":"2026-05-21T19:26:13.904459Z","end":"2026-05-21T19:26:14.03913Z","steps":["trace[939510002] 'process raft request' (duration: 134.184941ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:14.039029Z","caller":"traceutil/trace.go:171","msg":"trace[1888869021] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"160.66322ms","start":"2026-05-21T19:26:13.878361Z","end":"2026-05-21T19:26:14.039024Z","steps":["trace[1888869021] 'process raft request' (duration: 160.164518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:15.645437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.550487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:26:15.645585Z","caller":"traceutil/trace.go:171","msg":"trace[875658631] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"120.046509ms","start":"2026-05-21T19:26:15.525526Z","end":"2026-05-21T19:26:15.645573Z","steps":["trace[875658631] 'process raft request' (duration: 120.003431ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:15.645969Z","caller":"traceutil/trace.go:171","msg":"trace[649159726] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"344.576754ms","start":"2026-05-21T19:26:15.301371Z","end":"2026-05-21T19:26:15.645948Z","steps":["trace[649159726] 'read index received' (duration: 90.144414ms)","trace[649159726] 'applied index is now lower than readState.Index' (duration: 254.43058ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:26:15.64605Z","caller":"traceutil/trace.go:171","msg":"trace[171199546] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"430.495256ms","start":"2026-05-21T19:26:15.215547Z","end":"2026-05-21T19:26:15.646042Z","steps":["trace[171199546] 'process raft request' (duration: 176.187ms)","trace[171199546] 'compare' (duration: 253.435793ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:26:15.646095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:15.215538Z","time spent":"430.527665ms","remote":"127.0.0.1:42842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:26:15.64618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.671255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:15.646267Z","caller":"traceutil/trace.go:171","msg":"trace[910206592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"347.793108ms","start":"2026-05-21T19:26:15.298451Z","end":"2026-05-21T19:26:15.646244Z","steps":["trace[910206592] 'agreement among raft nodes before linearized reading' (duration: 347.657886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:15.646307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:15.298434Z","time spent":"347.864824ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:26:15.646459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.660472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-05-21T19:26:15.646574Z","caller":"traceutil/trace.go:171","msg":"trace[433500078] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:561; }","duration":"327.817724ms","start":"2026-05-21T19:26:15.318734Z","end":"2026-05-21T19:26:15.646552Z","steps":["trace[433500078] 'agreement among raft nodes before linearized reading' (duration: 327.384707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:15.646676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:15.318718Z","time spent":"327.936497ms","remote":"127.0.0.1:42842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":98737,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-21T19:26:19.844386Z","caller":"traceutil/trace.go:171","msg":"trace[1959931550] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"149.61109ms","start":"2026-05-21T19:26:19.694751Z","end":"2026-05-21T19:26:19.844362Z","steps":["trace[1959931550] 'read index received' (duration: 148.707293ms)","trace[1959931550] 'applied index is now lower than readState.Index' (duration: 902.987µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:26:19.845157Z","caller":"traceutil/trace.go:171","msg":"trace[527284177] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"152.443356ms","start":"2026-05-21T19:26:19.692702Z","end":"2026-05-21T19:26:19.845145Z","steps":["trace[527284177] 'process raft request' (duration: 151.116768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:19.845433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.665479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:19.845476Z","caller":"traceutil/trace.go:171","msg":"trace[2064317523] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"150.728267ms","start":"2026-05-21T19:26:19.694738Z","end":"2026-05-21T19:26:19.845466Z","steps":["trace[2064317523] 'agreement among raft nodes before linearized reading' (duration: 150.604672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:19.845657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.494688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:19.845694Z","caller":"traceutil/trace.go:171","msg":"trace[1591839547] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"150.533206ms","start":"2026-05-21T19:26:19.69515Z","end":"2026-05-21T19:26:19.845683Z","steps":["trace[1591839547] 'agreement among raft nodes before linearized reading' (duration: 150.481979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:19.847139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.878362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:19.847186Z","caller":"traceutil/trace.go:171","msg":"trace[354206171] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"152.106812ms","start":"2026-05-21T19:26:19.695069Z","end":"2026-05-21T19:26:19.847176Z","steps":["trace[354206171] 'agreement among raft nodes before linearized reading' (duration: 152.034945ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:20.026439Z","caller":"traceutil/trace.go:171","msg":"trace[653857604] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"113.941155ms","start":"2026-05-21T19:26:19.912478Z","end":"2026-05-21T19:26:20.02642Z","steps":["trace[653857604] 'process raft request' (duration: 106.491248ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:20.752635Z","caller":"traceutil/trace.go:171","msg":"trace[1331333733] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"453.967018ms","start":"2026-05-21T19:26:20.298637Z","end":"2026-05-21T19:26:20.752604Z","steps":["trace[1331333733] 'process raft request' (duration: 453.721839ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:20.75262Z","caller":"traceutil/trace.go:171","msg":"trace[1297275374] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"410.487637ms","start":"2026-05-21T19:26:20.342062Z","end":"2026-05-21T19:26:20.75255Z","steps":["trace[1297275374] 'read index received' (duration: 410.311646ms)","trace[1297275374] 'applied index is now lower than readState.Index' (duration: 174.891µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:26:20.752745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.65673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.752785Z","caller":"traceutil/trace.go:171","msg":"trace[647304591] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"410.766875ms","start":"2026-05-21T19:26:20.342009Z","end":"2026-05-21T19:26:20.752776Z","steps":["trace[647304591] 'agreement among raft nodes before linearized reading' (duration: 410.680359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.75282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.341993Z","time spent":"410.819672ms","remote":"127.0.0.1:39788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-21T19:26:20.752885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.298619Z","time spent":"454.099023ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:26:20.785292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.119877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.785371Z","caller":"traceutil/trace.go:171","msg":"trace[1949501379] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:589; }","duration":"443.240892ms","start":"2026-05-21T19:26:20.342111Z","end":"2026-05-21T19:26:20.785352Z","steps":["trace[1949501379] 'agreement among raft nodes before linearized reading' (duration: 443.098288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.785415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.342093Z","time spent":"443.312098ms","remote":"127.0.0.1:39788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:26:20.785676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.737156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:26:20.78568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.908675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.785717Z","caller":"traceutil/trace.go:171","msg":"trace[1576986418] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:589; }","duration":"343.793984ms","start":"2026-05-21T19:26:20.441911Z","end":"2026-05-21T19:26:20.785704Z","steps":["trace[1576986418] 'agreement among raft nodes before linearized reading' (duration: 343.730957ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:20.785749Z","caller":"traceutil/trace.go:171","msg":"trace[1536728109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:589; }","duration":"343.01365ms","start":"2026-05-21T19:26:20.442719Z","end":"2026-05-21T19:26:20.785732Z","steps":["trace[1536728109] 'agreement among raft nodes before linearized reading' (duration: 342.891206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.785763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.441904Z","time spent":"343.846931ms","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:26:20.785796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.442708Z","time spent":"343.078678ms","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-05-21T19:26:20.789361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.313291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.78947Z","caller":"traceutil/trace.go:171","msg":"trace[627190430] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:589; }","duration":"369.425156ms","start":"2026-05-21T19:26:20.420006Z","end":"2026-05-21T19:26:20.789431Z","steps":["trace[627190430] 'agreement among raft nodes before linearized reading' (duration: 369.273003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.789509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.420001Z","time spent":"369.498373ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-21T19:26:20.789517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.515032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.789656Z","caller":"traceutil/trace.go:171","msg":"trace[666353301] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:589; }","duration":"347.688675ms","start":"2026-05-21T19:26:20.441907Z","end":"2026-05-21T19:26:20.789596Z","steps":["trace[666353301] 'agreement among raft nodes before linearized reading' (duration: 344.11233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.789721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.441898Z","time spent":"347.806519ms","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-05-21T19:26:20.789787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.450068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.789815Z","caller":"traceutil/trace.go:171","msg":"trace[2084703573] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:589; }","duration":"370.517085ms","start":"2026-05-21T19:26:20.419289Z","end":"2026-05-21T19:26:20.789806Z","steps":["trace[2084703573] 'agreement among raft nodes before linearized reading' (duration: 370.451558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.789976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.419268Z","time spent":"370.699937ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:26:20.790224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.871809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.790254Z","caller":"traceutil/trace.go:171","msg":"trace[1825262224] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:589; }","duration":"370.953114ms","start":"2026-05-21T19:26:20.419286Z","end":"2026-05-21T19:26:20.790239Z","steps":["trace[1825262224] 'agreement among raft nodes before linearized reading' (duration: 370.805431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.790463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.419228Z","time spent":"371.227392ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-21T19:26:20.790665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.118118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.790743Z","caller":"traceutil/trace.go:171","msg":"trace[258480328] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:589; }","duration":"396.206794ms","start":"2026-05-21T19:26:20.39452Z","end":"2026-05-21T19:26:20.790727Z","steps":["trace[258480328] 'agreement among raft nodes before linearized reading' (duration: 396.032702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.790876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.394512Z","time spent":"396.245722ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-21T19:26:20.79129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.994785ms","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-05-21T19:26:20.791428Z","caller":"traceutil/trace.go:171","msg":"trace[1213224217] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:589; }","duration":"401.088231ms","start":"2026-05-21T19:26:20.390225Z","end":"2026-05-21T19:26:20.791313Z","steps":["trace[1213224217] 'agreement among raft nodes before linearized reading' (duration: 401.004955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.791458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.390221Z","time spent":"401.225155ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:26:20.791849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.627565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.791903Z","caller":"traceutil/trace.go:171","msg":"trace[627241955] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:589; }","duration":"401.653944ms","start":"2026-05-21T19:26:20.390209Z","end":"2026-05-21T19:26:20.791862Z","steps":["trace[627241955] 'agreement among raft nodes before linearized reading' (duration: 401.486382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.792062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.390177Z","time spent":"401.74599ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-21T19:26:20.792438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.381939ms","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-05-21T19:26:20.792499Z","caller":"traceutil/trace.go:171","msg":"trace[1068064364] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:589; }","duration":"424.417058ms","start":"2026-05-21T19:26:20.368035Z","end":"2026-05-21T19:26:20.792452Z","steps":["trace[1068064364] 'agreement among raft nodes before linearized reading' (duration: 424.183529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.792638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.36803Z","time spent":"424.483654ms","remote":"127.0.0.1:39796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:26:20.793047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.033599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.793081Z","caller":"traceutil/trace.go:171","msg":"trace[275413257] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:589; }","duration":"425.106625ms","start":"2026-05-21T19:26:20.367964Z","end":"2026-05-21T19:26:20.793071Z","steps":["trace[275413257] 'agreement among raft nodes before linearized reading' (duration: 424.965512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.793138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.367873Z","time spent":"425.22197ms","remote":"127.0.0.1:39796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-21T19:26:20.794215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.310768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.794258Z","caller":"traceutil/trace.go:171","msg":"trace[1228190240] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:589; }","duration":"316.374245ms","start":"2026-05-21T19:26:20.477873Z","end":"2026-05-21T19:26:20.794247Z","steps":["trace[1228190240] 'agreement among raft nodes before linearized reading' (duration: 316.313577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.794288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.477866Z","time spent":"316.408573ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-05-21T19:26:20.794423Z","caller":"traceutil/trace.go:171","msg":"trace[1245626170] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"366.093399ms","start":"2026-05-21T19:26:20.428311Z","end":"2026-05-21T19:26:20.794405Z","steps":["trace[1245626170] 'process raft request' (duration: 337.224286ms)","trace[1245626170] 'compare' (duration: 18.899221ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:26:20.794483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.428289Z","time spent":"366.151717ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:26:20.794662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.109469ms","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-05-21T19:26:20.794689Z","caller":"traceutil/trace.go:171","msg":"trace[2021692859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"232.164326ms","start":"2026-05-21T19:26:20.562511Z","end":"2026-05-21T19:26:20.794675Z","steps":["trace[2021692859] 'agreement among raft nodes before linearized reading' (duration: 232.07466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.794809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.85942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.794829Z","caller":"traceutil/trace.go:171","msg":"trace[452015338] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:589; }","duration":"298.908188ms","start":"2026-05-21T19:26:20.495914Z","end":"2026-05-21T19:26:20.794822Z","steps":["trace[452015338] 'agreement among raft nodes before linearized reading' (duration: 298.86961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.794958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.821045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.794979Z","caller":"traceutil/trace.go:171","msg":"trace[1546311747] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:589; }","duration":"299.858664ms","start":"2026-05-21T19:26:20.495113Z","end":"2026-05-21T19:26:20.794972Z","steps":["trace[1546311747] 'agreement among raft nodes before linearized reading' (duration: 299.821306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.795234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.516336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.795271Z","caller":"traceutil/trace.go:171","msg":"trace[1334467138] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:589; }","duration":"333.578694ms","start":"2026-05-21T19:26:20.461684Z","end":"2026-05-21T19:26:20.795263Z","steps":["trace[1334467138] 'agreement among raft nodes before linearized reading' (duration: 333.528026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.795295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.461669Z","time spent":"333.616451ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-21T19:26:20.797116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.351022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.797157Z","caller":"traceutil/trace.go:171","msg":"trace[389855819] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:589; }","duration":"335.424489ms","start":"2026-05-21T19:26:20.461723Z","end":"2026-05-21T19:26:20.797147Z","steps":["trace[389855819] 'agreement among raft nodes before linearized reading' (duration: 333.428861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.797181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.461709Z","time spent":"335.465347ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:26:20.797383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.798467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.797426Z","caller":"traceutil/trace.go:171","msg":"trace[1059465328] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:589; }","duration":"319.873604ms","start":"2026-05-21T19:26:20.477539Z","end":"2026-05-21T19:26:20.797413Z","steps":["trace[1059465328] 'agreement among raft nodes before linearized reading' (duration: 317.622847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.797451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.477528Z","time spent":"319.915351ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-21T19:26:20.797661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.56309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.797682Z","caller":"traceutil/trace.go:171","msg":"trace[1779809598] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:589; }","duration":"302.623567ms","start":"2026-05-21T19:26:20.495052Z","end":"2026-05-21T19:26:20.797675Z","steps":["trace[1779809598] 'agreement among raft nodes before linearized reading' (duration: 300.122822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.797708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.495025Z","time spent":"302.678074ms","remote":"127.0.0.1:39854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:26:20.797869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.17151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:20.797908Z","caller":"traceutil/trace.go:171","msg":"trace[213064546] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:589; }","duration":"320.269125ms","start":"2026-05-21T19:26:20.477625Z","end":"2026-05-21T19:26:20.797894Z","steps":["trace[213064546] 'agreement among raft nodes before linearized reading' (duration: 317.453705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:20.79793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:20.4776Z","time spent":"320.322842ms","remote":"127.0.0.1:39852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:26:22.629182Z","caller":"traceutil/trace.go:171","msg":"trace[1630625225] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"150.39927ms","start":"2026-05-21T19:26:22.47876Z","end":"2026-05-21T19:26:22.629159Z","steps":["trace[1630625225] 'process raft request' (duration: 150.282315ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:26.063369Z","caller":"traceutil/trace.go:171","msg":"trace[1958355500] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"343.256133ms","start":"2026-05-21T19:26:25.720089Z","end":"2026-05-21T19:26:26.063345Z","steps":["trace[1958355500] 'process raft request' (duration: 343.027472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:26.063537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:25.72007Z","time spent":"343.402917ms","remote":"127.0.0.1:42842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:26:26.088394Z","caller":"traceutil/trace.go:171","msg":"trace[466754831] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"204.770359ms","start":"2026-05-21T19:26:25.8836Z","end":"2026-05-21T19:26:26.088371Z","steps":["trace[466754831] 'read index received' (duration: 179.349615ms)","trace[466754831] 'applied index is now lower than readState.Index' (duration: 25.419834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:26:26.088751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.127566ms","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-05-21T19:26:26.0888Z","caller":"traceutil/trace.go:171","msg":"trace[450746000] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:672; }","duration":"205.189844ms","start":"2026-05-21T19:26:25.883596Z","end":"2026-05-21T19:26:26.088785Z","steps":["trace[450746000] 'agreement among raft nodes before linearized reading' (duration: 204.873136ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:26.088781Z","caller":"traceutil/trace.go:171","msg":"trace[1995677678] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"351.678928ms","start":"2026-05-21T19:26:25.737034Z","end":"2026-05-21T19:26:26.088713Z","steps":["trace[1995677678] 'process raft request' (duration: 351.172556ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:26:26.088882Z","caller":"traceutil/trace.go:171","msg":"trace[610152521] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"155.436977ms","start":"2026-05-21T19:26:25.933418Z","end":"2026-05-21T19:26:26.088855Z","steps":["trace[610152521] 'process raft request' (duration: 154.891058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:26.088927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:25.737015Z","time spent":"351.850572ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:26:26.330348Z","caller":"traceutil/trace.go:171","msg":"trace[1629874911] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"221.21791ms","start":"2026-05-21T19:26:26.109111Z","end":"2026-05-21T19:26:26.330329Z","steps":["trace[1629874911] 'process raft request' (duration: 184.031778ms)","trace[1629874911] 'compare' (duration: 36.024875ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:26:26.330425Z","caller":"traceutil/trace.go:171","msg":"trace[1063179099] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"199.578286ms","start":"2026-05-21T19:26:26.130825Z","end":"2026-05-21T19:26:26.330404Z","steps":["trace[1063179099] 'process raft request' (duration: 199.087644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:28.452787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.019425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:28.45288Z","caller":"traceutil/trace.go:171","msg":"trace[1640841318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"154.16329ms","start":"2026-05-21T19:26:28.298697Z","end":"2026-05-21T19:26:28.452861Z","steps":["trace[1640841318] 'range keys from in-memory index tree' (duration: 153.945948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:37.513467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371706547157809,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:26:37.858719Z","caller":"traceutil/trace.go:171","msg":"trace[425565823] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"973.674199ms","start":"2026-05-21T19:26:36.885023Z","end":"2026-05-21T19:26:37.858698Z","steps":["trace[425565823] 'process raft request' (duration: 973.531533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:37.858972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:36.884994Z","time spent":"973.797087ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:26:38.115401Z","caller":"traceutil/trace.go:171","msg":"trace[1863133608] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:775; }","duration":"1.102598869s","start":"2026-05-21T19:26:37.012755Z","end":"2026-05-21T19:26:38.115354Z","steps":["trace[1863133608] 'read index received' (duration: 845.859717ms)","trace[1863133608] 'applied index is now lower than readState.Index' (duration: 256.737713ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:26:38.115822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103057189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-21T19:26:38.115921Z","caller":"traceutil/trace.go:171","msg":"trace[544781853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"1.103211276s","start":"2026-05-21T19:26:37.012697Z","end":"2026-05-21T19:26:38.115908Z","steps":["trace[544781853] 'agreement among raft nodes before linearized reading' (duration: 1.10299962s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:38.116015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.03248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:26:38.116151Z","caller":"traceutil/trace.go:171","msg":"trace[1043728065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"817.254155ms","start":"2026-05-21T19:26:37.298864Z","end":"2026-05-21T19:26:38.116118Z","steps":["trace[1043728065] 'agreement among raft nodes before linearized reading' (duration: 816.984421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:38.116169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.964793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6zc59\" ","response":"range_response_count:1 size:5046"} {"level":"warn","ts":"2026-05-21T19:26:38.116152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086947985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T19:26:38.116257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:37.298835Z","time spent":"817.407982ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:26:38.116278Z","caller":"traceutil/trace.go:171","msg":"trace[1415274649] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-6zc59; range_end:; response_count:1; response_revision:753; }","duration":"482.09787ms","start":"2026-05-21T19:26:37.63416Z","end":"2026-05-21T19:26:38.116258Z","steps":["trace[1415274649] 'agreement among raft nodes before linearized reading' (duration: 481.721418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:38.116068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:37.012676Z","time spent":"1.103377473s","remote":"127.0.0.1:43088","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":"info","ts":"2026-05-21T19:26:38.11635Z","caller":"traceutil/trace.go:171","msg":"trace[779739579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"1.087177371s","start":"2026-05-21T19:26:37.029119Z","end":"2026-05-21T19:26:38.116296Z","steps":["trace[779739579] 'agreement among raft nodes before linearized reading' (duration: 1.086693601s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:38.116652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:37.029108Z","time spent":"1.087528954s","remote":"127.0.0.1:43088","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-05-21T19:26:38.116337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:37.634102Z","time spent":"482.220398ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5070,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-6zc59\" "} {"level":"info","ts":"2026-05-21T19:26:38.115786Z","caller":"traceutil/trace.go:171","msg":"trace[208467499] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"717.237922ms","start":"2026-05-21T19:26:37.398437Z","end":"2026-05-21T19:26:38.115675Z","steps":["trace[208467499] 'process raft request' (duration: 716.791241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:38.116469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01327141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T19:26:38.116993Z","caller":"traceutil/trace.go:171","msg":"trace[17825881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"1.013828469s","start":"2026-05-21T19:26:37.103145Z","end":"2026-05-21T19:26:38.116973Z","steps":["trace[17825881] 'agreement among raft nodes before linearized reading' (duration: 1.013242351s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:26:38.116998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:37.398409Z","time spent":"718.471306ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:26:38.117051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:26:37.103122Z","time spent":"1.013913128s","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T19:27:30.103406Z","caller":"traceutil/trace.go:171","msg":"trace[1128655513] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"118.56014ms","start":"2026-05-21T19:27:29.984778Z","end":"2026-05-21T19:27:30.103338Z","steps":["trace[1128655513] 'process raft request' (duration: 118.422627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:56.401249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.005593ms","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-05-21T19:27:56.401348Z","caller":"traceutil/trace.go:171","msg":"trace[621932187] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1825; }","duration":"735.113217ms","start":"2026-05-21T19:27:55.666215Z","end":"2026-05-21T19:27:56.401328Z","steps":["trace[621932187] 'range keys from in-memory index tree' (duration: 734.825827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:56.401329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.946633ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:27:56.401434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.742912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-05-21T19:27:56.401455Z","caller":"traceutil/trace.go:171","msg":"trace[1407546853] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1825; }","duration":"673.092878ms","start":"2026-05-21T19:27:55.728344Z","end":"2026-05-21T19:27:56.401437Z","steps":["trace[1407546853] 'range keys from in-memory index tree' (duration: 672.928203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:56.401467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.100703ms","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-05-21T19:27:56.401547Z","caller":"traceutil/trace.go:171","msg":"trace[744347872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1825; }","duration":"151.239938ms","start":"2026-05-21T19:27:56.250289Z","end":"2026-05-21T19:27:56.401529Z","steps":["trace[744347872] 'range keys from in-memory index tree' (duration: 150.854985ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:56.401493Z","caller":"traceutil/trace.go:171","msg":"trace[1148331082] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1825; }","duration":"354.842714ms","start":"2026-05-21T19:27:56.046635Z","end":"2026-05-21T19:27:56.401478Z","steps":["trace[1148331082] 'range keys from in-memory index tree' (duration: 354.580116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:56.401657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.87779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8a6a40f-4a65-4932-bd53-3e158399c629\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-21T19:27:56.401709Z","caller":"traceutil/trace.go:171","msg":"trace[312920830] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c8a6a40f-4a65-4932-bd53-3e158399c629; range_end:; response_count:1; response_revision:1825; }","duration":"668.959783ms","start":"2026-05-21T19:27:55.732734Z","end":"2026-05-21T19:27:56.401693Z","steps":["trace[312920830] 'range keys from in-memory index tree' (duration: 668.723905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:56.4014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:55.666149Z","time spent":"735.238911ms","remote":"127.0.0.1:37940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-21T19:27:56.401717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:56.046616Z","time spent":"355.063212ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T19:27:56.401883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.272699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8a6a40f-4a65-4932-bd53-3e158399c629\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-21T19:27:56.401913Z","caller":"traceutil/trace.go:171","msg":"trace[76809411] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c8a6a40f-4a65-4932-bd53-3e158399c629; range_end:; response_count:1; response_revision:1825; }","duration":"349.34186ms","start":"2026-05-21T19:27:56.05256Z","end":"2026-05-21T19:27:56.401901Z","steps":["trace[76809411] 'range keys from in-memory index tree' (duration: 349.159475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:56.401991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.8282ms","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":"warn","ts":"2026-05-21T19:27:56.40206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:56.052539Z","time spent":"349.507986ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8a6a40f-4a65-4932-bd53-3e158399c629\" "} {"level":"info","ts":"2026-05-21T19:27:56.402179Z","caller":"traceutil/trace.go:171","msg":"trace[576708544] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1825; }","duration":"468.456841ms","start":"2026-05-21T19:27:55.933701Z","end":"2026-05-21T19:27:56.402158Z","steps":["trace[576708544] 'range keys from in-memory index tree' (duration: 467.457418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:56.402351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:55.933675Z","time spent":"468.660908ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T19:27:56.40175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:27:55.732713Z","time spent":"669.025005ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c8a6a40f-4a65-4932-bd53-3e158399c629\" "} {"level":"info","ts":"2026-05-21T19:27:56.638893Z","caller":"traceutil/trace.go:171","msg":"trace[401449463] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"230.898958ms","start":"2026-05-21T19:27:56.40796Z","end":"2026-05-21T19:27:56.638859Z","steps":["trace[401449463] 'process raft request' (duration: 183.685532ms)","trace[401449463] 'compare' (duration: 46.991959ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:27:56.639146Z","caller":"traceutil/trace.go:171","msg":"trace[1577885498] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"228.8689ms","start":"2026-05-21T19:27:56.41026Z","end":"2026-05-21T19:27:56.639129Z","steps":["trace[1577885498] 'process raft request' (duration: 228.533769ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:56.639307Z","caller":"traceutil/trace.go:171","msg":"trace[152041285] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"228.930232ms","start":"2026-05-21T19:27:56.410364Z","end":"2026-05-21T19:27:56.639295Z","steps":["trace[152041285] 'process raft request' (duration: 228.678003ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:27:56.9153Z","caller":"traceutil/trace.go:171","msg":"trace[408614685] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"103.598208ms","start":"2026-05-21T19:27:56.811633Z","end":"2026-05-21T19:27:56.915231Z","steps":["trace[408614685] 'process raft request' (duration: 103.337909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:57.489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.141279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:57.489065Z","caller":"traceutil/trace.go:171","msg":"trace[1227902752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1834; }","duration":"189.244362ms","start":"2026-05-21T19:27:57.299801Z","end":"2026-05-21T19:27:57.489045Z","steps":["trace[1227902752] 'range keys from in-memory index tree' (duration: 189.039526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:27:57.489427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.618994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:27:57.489524Z","caller":"traceutil/trace.go:171","msg":"trace[324204987] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1904; }","duration":"158.368531ms","start":"2026-05-21T19:27:57.331139Z","end":"2026-05-21T19:27:57.489508Z","steps":["trace[324204987] 'read index received' (duration: 57.534449ms)","trace[324204987] 'applied index is now lower than readState.Index' (duration: 100.831261ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:27:57.489697Z","caller":"traceutil/trace.go:171","msg":"trace[326166545] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"181.16987ms","start":"2026-05-21T19:27:57.308514Z","end":"2026-05-21T19:27:57.489684Z","steps":["trace[326166545] 'process raft request' (duration: 80.235536ms)","trace[326166545] 'compare' (duration: 100.479729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:27:57.489956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.798517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:27:57.490017Z","caller":"traceutil/trace.go:171","msg":"trace[198918266] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1835; }","duration":"158.89085ms","start":"2026-05-21T19:27:57.331107Z","end":"2026-05-21T19:27:57.489998Z","steps":["trace[198918266] 'agreement among raft nodes before linearized reading' (duration: 158.788916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:05.092348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.790336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-21T19:28:05.092477Z","caller":"traceutil/trace.go:171","msg":"trace[1260559400] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1914; }","duration":"247.956592ms","start":"2026-05-21T19:28:04.844494Z","end":"2026-05-21T19:28:05.092451Z","steps":["trace[1260559400] 'range keys from in-memory index tree' (duration: 247.531137ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.092696Z","caller":"traceutil/trace.go:171","msg":"trace[762462735] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"148.459807ms","start":"2026-05-21T19:28:04.94421Z","end":"2026-05-21T19:28:05.09267Z","steps":["trace[762462735] 'process raft request' (duration: 148.094934ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.092722Z","caller":"traceutil/trace.go:171","msg":"trace[339384590] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1987; }","duration":"120.931405ms","start":"2026-05-21T19:28:04.971773Z","end":"2026-05-21T19:28:05.092704Z","steps":["trace[339384590] 'read index received' (duration: 120.924474ms)","trace[339384590] 'applied index is now lower than readState.Index' (duration: 5.631µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:05.092838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.06554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:28:05.092893Z","caller":"traceutil/trace.go:171","msg":"trace[1456389791] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1915; }","duration":"121.178455ms","start":"2026-05-21T19:28:04.971705Z","end":"2026-05-21T19:28:05.092883Z","steps":["trace[1456389791] 'agreement among raft nodes before linearized reading' (duration: 121.07886ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:05.096696Z","caller":"traceutil/trace.go:171","msg":"trace[1953976254] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"102.051436ms","start":"2026-05-21T19:28:04.994625Z","end":"2026-05-21T19:28:05.096676Z","steps":["trace[1953976254] 'process raft request' (duration: 101.634991ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:11.390365Z","caller":"traceutil/trace.go:171","msg":"trace[42976897] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"103.100768ms","start":"2026-05-21T19:28:11.287238Z","end":"2026-05-21T19:28:11.390339Z","steps":["trace[42976897] 'process raft request' (duration: 102.935382ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:16.267843Z","caller":"traceutil/trace.go:171","msg":"trace[821131342] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"102.138569ms","start":"2026-05-21T19:28:16.165672Z","end":"2026-05-21T19:28:16.267811Z","steps":["trace[821131342] 'process raft request' (duration: 101.94332ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:26.305449Z","caller":"traceutil/trace.go:171","msg":"trace[1267300481] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"195.271518ms","start":"2026-05-21T19:28:26.11014Z","end":"2026-05-21T19:28:26.305412Z","steps":["trace[1267300481] 'process raft request' (duration: 195.040727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:32.104376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.557228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:32.104606Z","caller":"traceutil/trace.go:171","msg":"trace[1051969184] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"334.553039ms","start":"2026-05-21T19:28:31.770034Z","end":"2026-05-21T19:28:32.104587Z","steps":["trace[1051969184] 'process raft request' (duration: 334.459925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:32.104695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:31.770002Z","time spent":"334.644542ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:32.104896Z","caller":"traceutil/trace.go:171","msg":"trace[122629433] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"725.429402ms","start":"2026-05-21T19:28:31.379454Z","end":"2026-05-21T19:28:32.104883Z","steps":["trace[122629433] 'process raft request' (duration: 477.27826ms)","trace[122629433] 'compare' (duration: 247.323416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:32.104948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:31.379426Z","time spent":"725.500715ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:28:32.105083Z","caller":"traceutil/trace.go:171","msg":"trace[1915677665] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2155; }","duration":"363.95647ms","start":"2026-05-21T19:28:31.741118Z","end":"2026-05-21T19:28:32.105074Z","steps":["trace[1915677665] 'read index received' (duration: 115.628209ms)","trace[1915677665] 'applied index is now lower than readState.Index' (duration: 248.327151ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:32.105272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.155989ms","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-05-21T19:28:32.105309Z","caller":"traceutil/trace.go:171","msg":"trace[948147178] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2080; }","duration":"364.233711ms","start":"2026-05-21T19:28:31.741064Z","end":"2026-05-21T19:28:32.105298Z","steps":["trace[948147178] 'agreement among raft nodes before linearized reading' (duration: 364.048583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:32.105338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:31.741042Z","time spent":"364.289274ms","remote":"127.0.0.1:37940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-21T19:28:32.794036Z","caller":"traceutil/trace.go:171","msg":"trace[1166253588] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"140.093507ms","start":"2026-05-21T19:28:32.653916Z","end":"2026-05-21T19:28:32.79401Z","steps":["trace[1166253588] 'process raft request' (duration: 139.92411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:33.981785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.522891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-21T19:28:33.981815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.939067ms","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":"warn","ts":"2026-05-21T19:28:33.981917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.085509ms","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-05-21T19:28:33.98197Z","caller":"traceutil/trace.go:171","msg":"trace[138433756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2082; }","duration":"928.181172ms","start":"2026-05-21T19:28:33.053776Z","end":"2026-05-21T19:28:33.981957Z","steps":["trace[138433756] 'range keys from in-memory index tree' (duration: 927.932631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:33.98109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.723082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:28:33.982012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.053689Z","time spent":"928.314918ms","remote":"127.0.0.1:43088","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":"info","ts":"2026-05-21T19:28:33.981875Z","caller":"traceutil/trace.go:171","msg":"trace[1620794224] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2082; }","duration":"923.637536ms","start":"2026-05-21T19:28:33.058213Z","end":"2026-05-21T19:28:33.981851Z","steps":["trace[1620794224] 'range keys from in-memory index tree' (duration: 923.294031ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:33.981888Z","caller":"traceutil/trace.go:171","msg":"trace[602410025] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2082; }","duration":"869.047942ms","start":"2026-05-21T19:28:33.11282Z","end":"2026-05-21T19:28:33.981868Z","steps":["trace[602410025] 'range keys from in-memory index tree' (duration: 868.747188ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:33.982069Z","caller":"traceutil/trace.go:171","msg":"trace[762658402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2082; }","duration":"682.743198ms","start":"2026-05-21T19:28:33.299288Z","end":"2026-05-21T19:28:33.982031Z","steps":["trace[762658402] 'range keys from in-memory index tree' (duration: 681.601697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:33.981944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.163855ms","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":"warn","ts":"2026-05-21T19:28:33.98214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.112809Z","time spent":"869.297413ms","remote":"127.0.0.1:37940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-21T19:28:33.982215Z","caller":"traceutil/trace.go:171","msg":"trace[798085942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2082; }","duration":"932.51639ms","start":"2026-05-21T19:28:33.049644Z","end":"2026-05-21T19:28:33.982161Z","steps":["trace[798085942] 'range keys from in-memory index tree' (duration: 932.035999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:33.98212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.29926Z","time spent":"682.845482ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:28:33.982329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.54465ms","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-05-21T19:28:33.98238Z","caller":"traceutil/trace.go:171","msg":"trace[843152035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2082; }","duration":"941.616974ms","start":"2026-05-21T19:28:33.040743Z","end":"2026-05-21T19:28:33.98236Z","steps":["trace[843152035] 'range keys from in-memory index tree' (duration: 941.394674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:33.982227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.303228ms","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-05-21T19:28:33.982428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.040722Z","time spent":"941.698597ms","remote":"127.0.0.1:43088","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-05-21T19:28:33.982444Z","caller":"traceutil/trace.go:171","msg":"trace[1850229882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2082; }","duration":"937.538238ms","start":"2026-05-21T19:28:33.044893Z","end":"2026-05-21T19:28:33.982432Z","steps":["trace[1850229882] 'range keys from in-memory index tree' (duration: 936.772044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:33.982484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.04483Z","time spent":"937.642152ms","remote":"127.0.0.1:43088","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-05-21T19:28:33.982307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.049634Z","time spent":"932.655346ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:28:33.982598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.058185Z","time spent":"923.907717ms","remote":"127.0.0.1:43088","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-05-21T19:28:33.982677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.018314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:28:33.982763Z","caller":"traceutil/trace.go:171","msg":"trace[615372200] linearizableReadLoop","detail":"{readStateIndex:2160; appliedIndex:2159; }","duration":"429.66906ms","start":"2026-05-21T19:28:33.553083Z","end":"2026-05-21T19:28:33.982752Z","steps":["trace[615372200] 'read index received' (duration: 209.418615ms)","trace[615372200] 'applied index is now lower than readState.Index' (duration: 220.249215ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:33.982879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.791396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:28:33.982905Z","caller":"traceutil/trace.go:171","msg":"trace[916906156] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2083; }","duration":"429.854978ms","start":"2026-05-21T19:28:33.553042Z","end":"2026-05-21T19:28:33.982897Z","steps":["trace[916906156] 'agreement among raft nodes before linearized reading' (duration: 429.802456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:33.982935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.553023Z","time spent":"429.90444ms","remote":"127.0.0.1:43172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:28:33.983002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.398118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T19:28:33.98304Z","caller":"traceutil/trace.go:171","msg":"trace[1229032977] range","detail":"{range_begin:/registry/masterleases/199.19.213.43; range_end:; response_count:1; response_revision:2083; }","duration":"292.47177ms","start":"2026-05-21T19:28:33.690555Z","end":"2026-05-21T19:28:33.983027Z","steps":["trace[1229032977] 'agreement among raft nodes before linearized reading' (duration: 292.376996ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:33.985183Z","caller":"traceutil/trace.go:171","msg":"trace[531631190] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"666.347318ms","start":"2026-05-21T19:28:33.318738Z","end":"2026-05-21T19:28:33.985086Z","steps":["trace[531631190] 'process raft request' (duration: 443.831965ms)","trace[531631190] 'compare' (duration: 218.929138ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:28:33.985299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.318709Z","time spent":"666.544826ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:34.352912Z","caller":"traceutil/trace.go:171","msg":"trace[1649699274] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"364.527997ms","start":"2026-05-21T19:28:33.988349Z","end":"2026-05-21T19:28:34.352877Z","steps":["trace[1649699274] 'process raft request' (duration: 364.159221ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:34.35341Z","caller":"traceutil/trace.go:171","msg":"trace[2135048008] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"364.697024ms","start":"2026-05-21T19:28:33.988682Z","end":"2026-05-21T19:28:34.353379Z","steps":["trace[2135048008] 'process raft request' (duration: 364.1347ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:34.353496Z","caller":"traceutil/trace.go:171","msg":"trace[1354372012] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"364.286237ms","start":"2026-05-21T19:28:33.989167Z","end":"2026-05-21T19:28:34.353453Z","steps":["trace[1354372012] 'process raft request' (duration: 364.163801ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:34.353526Z","caller":"traceutil/trace.go:171","msg":"trace[2068335970] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"364.418212ms","start":"2026-05-21T19:28:33.989073Z","end":"2026-05-21T19:28:34.353491Z","steps":["trace[2068335970] 'process raft request' (duration: 364.089348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.353596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.988659Z","time spent":"364.838731ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:34.353689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.98916Z","time spent":"364.427532ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:34.353759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.988319Z","time spent":"365.037428ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:34.353886Z","caller":"traceutil/trace.go:171","msg":"trace[348351477] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"364.657024ms","start":"2026-05-21T19:28:33.989216Z","end":"2026-05-21T19:28:34.353873Z","steps":["trace[348351477] 'process raft request' (duration: 364.169312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.354182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.989211Z","time spent":"364.892984ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:34.35487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:33.989063Z","time spent":"364.532918ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:28:34.365667Z","caller":"traceutil/trace.go:171","msg":"trace[2035163057] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"253.621429ms","start":"2026-05-21T19:28:34.112031Z","end":"2026-05-21T19:28:34.365652Z","steps":["trace[2035163057] 'process raft request' (duration: 253.571127ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:28:34.365826Z","caller":"traceutil/trace.go:171","msg":"trace[2136964808] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"339.807357ms","start":"2026-05-21T19:28:34.025999Z","end":"2026-05-21T19:28:34.365806Z","steps":["trace[2136964808] 'process raft request' (duration: 339.526705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.36591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:34.025984Z","time spent":"339.883539ms","remote":"127.0.0.1:42828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:28:34.869751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.348667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b1ab53dcb417fd\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-21T19:28:34.869835Z","caller":"traceutil/trace.go:171","msg":"trace[459286499] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b1ab53dcb417fd; range_end:; response_count:1; response_revision:2090; }","duration":"405.467662ms","start":"2026-05-21T19:28:34.464342Z","end":"2026-05-21T19:28:34.86981Z","steps":["trace[459286499] 'range keys from in-memory index tree' (duration: 405.19801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.869863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.594239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-05-21T19:28:34.870004Z","caller":"traceutil/trace.go:171","msg":"trace[1453072058] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2090; }","duration":"279.774466ms","start":"2026-05-21T19:28:34.590211Z","end":"2026-05-21T19:28:34.869986Z","steps":["trace[1453072058] 'range keys from in-memory index tree' (duration: 279.370849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:28:34.869887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:28:34.464322Z","time spent":"405.555896ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":870,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b1ab53dcb417fd\" "} {"level":"info","ts":"2026-05-21T19:30:15.454783Z","caller":"traceutil/trace.go:171","msg":"trace[2090472207] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"128.930573ms","start":"2026-05-21T19:30:15.325826Z","end":"2026-05-21T19:30:15.454756Z","steps":["trace[2090472207] 'process raft request' (duration: 123.818302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.536657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.639346ms","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-05-21T19:30:16.536764Z","caller":"traceutil/trace.go:171","msg":"trace[1908596967] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2734; }","duration":"807.759083ms","start":"2026-05-21T19:30:15.728987Z","end":"2026-05-21T19:30:16.536746Z","steps":["trace[1908596967] 'range keys from in-memory index tree' (duration: 807.621255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.538158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.972583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:30:16.538225Z","caller":"traceutil/trace.go:171","msg":"trace[541930214] linearizableReadLoop","detail":"{readStateIndex:2836; appliedIndex:2835; }","duration":"1.076208682s","start":"2026-05-21T19:30:15.462009Z","end":"2026-05-21T19:30:16.538218Z","steps":["trace[541930214] 'read index received' (duration: 253.012647ms)","trace[541930214] 'applied index is now lower than readState.Index' (duration: 823.170973ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:30:16.538283Z","caller":"traceutil/trace.go:171","msg":"trace[443570633] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"1.079195741s","start":"2026-05-21T19:30:15.459051Z","end":"2026-05-21T19:30:16.538246Z","steps":["trace[443570633] 'process raft request' (duration: 256.084611ms)","trace[443570633] 'compare' (duration: 822.591773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:30:16.538387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076360921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b1ab690cb7ffe4\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-21T19:30:16.538443Z","caller":"traceutil/trace.go:171","msg":"trace[502253724] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b1ab690cb7ffe4; range_end:; response_count:1; response_revision:2735; }","duration":"1.076441965s","start":"2026-05-21T19:30:15.461989Z","end":"2026-05-21T19:30:16.538431Z","steps":["trace[502253724] 'agreement among raft nodes before linearized reading' (duration: 1.076267206s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.538473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.46198Z","time spent":"1.076487427s","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b1ab690cb7ffe4\" "} {"level":"warn","ts":"2026-05-21T19:30:16.538448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.459031Z","time spent":"1.079336339s","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:30:16.565801Z","caller":"traceutil/trace.go:171","msg":"trace[1438463927] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"159.112759ms","start":"2026-05-21T19:30:16.406661Z","end":"2026-05-21T19:30:16.565774Z","steps":["trace[1438463927] 'process raft request' (duration: 159.001233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.566003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.254807ms","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-05-21T19:30:16.566055Z","caller":"traceutil/trace.go:171","msg":"trace[1712289485] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2736; }","duration":"611.31136ms","start":"2026-05-21T19:30:15.954724Z","end":"2026-05-21T19:30:16.566035Z","steps":["trace[1712289485] 'agreement among raft nodes before linearized reading' (duration: 611.232766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.566102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:15.954703Z","time spent":"611.375953ms","remote":"127.0.0.1:43280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":3,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:30:16.566194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.076144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-05-21T19:30:16.56625Z","caller":"traceutil/trace.go:171","msg":"trace[536157550] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2736; }","duration":"397.200591ms","start":"2026-05-21T19:30:16.169036Z","end":"2026-05-21T19:30:16.566237Z","steps":["trace[536157550] 'agreement among raft nodes before linearized reading' (duration: 396.99664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:30:16.56628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:30:16.169018Z","time spent":"397.257344ms","remote":"127.0.0.1:42986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-21T19:33:18.016446Z","caller":"traceutil/trace.go:171","msg":"trace[153736616] transaction","detail":"{read_only:false; response_revision:3856; number_of_response:1; }","duration":"120.534022ms","start":"2026-05-21T19:33:17.895882Z","end":"2026-05-21T19:33:18.016416Z","steps":["trace[153736616] 'process raft request' (duration: 120.356847ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:24.858896Z","caller":"traceutil/trace.go:171","msg":"trace[462260720] transaction","detail":"{read_only:false; response_revision:3894; number_of_response:1; }","duration":"164.395249ms","start":"2026-05-21T19:33:24.694476Z","end":"2026-05-21T19:33:24.858871Z","steps":["trace[462260720] 'process raft request' (duration: 164.155071ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:25.70426Z","caller":"traceutil/trace.go:171","msg":"trace[823572905] transaction","detail":"{read_only:false; response_revision:3898; number_of_response:1; }","duration":"114.065188ms","start":"2026-05-21T19:33:25.59017Z","end":"2026-05-21T19:33:25.704235Z","steps":["trace[823572905] 'process raft request' (duration: 106.409007ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:46.880815Z","caller":"traceutil/trace.go:171","msg":"trace[399763871] linearizableReadLoop","detail":"{readStateIndex:4182; appliedIndex:4181; }","duration":"108.009232ms","start":"2026-05-21T19:33:46.772785Z","end":"2026-05-21T19:33:46.880794Z","steps":["trace[399763871] 'read index received' (duration: 107.572947ms)","trace[399763871] 'applied index is now lower than readState.Index' (duration: 435.595µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:46.880962Z","caller":"traceutil/trace.go:171","msg":"trace[140094606] transaction","detail":"{read_only:false; response_revision:4019; number_of_response:1; }","duration":"116.469899ms","start":"2026-05-21T19:33:46.764411Z","end":"2026-05-21T19:33:46.880881Z","steps":["trace[140094606] 'process raft request' (duration: 116.058185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:46.881191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.369685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:33:46.88128Z","caller":"traceutil/trace.go:171","msg":"trace[7842383] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4019; }","duration":"108.498939ms","start":"2026-05-21T19:33:46.772764Z","end":"2026-05-21T19:33:46.881263Z","steps":["trace[7842383] 'agreement among raft nodes before linearized reading' (duration: 108.312583ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:47.325403Z","caller":"traceutil/trace.go:171","msg":"trace[1691458974] transaction","detail":"{read_only:false; response_revision:4021; number_of_response:1; }","duration":"169.660283ms","start":"2026-05-21T19:33:47.155721Z","end":"2026-05-21T19:33:47.325381Z","steps":["trace[1691458974] 'process raft request' (duration: 169.259419ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:47.468873Z","caller":"traceutil/trace.go:171","msg":"trace[916293737] transaction","detail":"{read_only:false; response_revision:4022; number_of_response:1; }","duration":"113.019769ms","start":"2026-05-21T19:33:47.355822Z","end":"2026-05-21T19:33:47.468842Z","steps":["trace[916293737] 'process raft request' (duration: 77.006891ms)","trace[916293737] 'compare' (duration: 35.77441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:50.268995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.937408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-21T19:33:50.269084Z","caller":"traceutil/trace.go:171","msg":"trace[1271305005] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4044; }","duration":"552.064703ms","start":"2026-05-21T19:33:49.716992Z","end":"2026-05-21T19:33:50.269057Z","steps":["trace[1271305005] 'range keys from in-memory index tree' (duration: 551.746491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:50.269262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.290457ms","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-05-21T19:33:50.269361Z","caller":"traceutil/trace.go:171","msg":"trace[1101601932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4044; }","duration":"359.446854ms","start":"2026-05-21T19:33:49.909894Z","end":"2026-05-21T19:33:50.269341Z","steps":["trace[1101601932] 'range keys from in-memory index tree' (duration: 358.886103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:50.269514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.141696ms","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-05-21T19:33:50.269529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.640417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-21T19:33:50.269569Z","caller":"traceutil/trace.go:171","msg":"trace[975016813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4044; }","duration":"319.232289ms","start":"2026-05-21T19:33:49.950325Z","end":"2026-05-21T19:33:50.269557Z","steps":["trace[975016813] 'range keys from in-memory index tree' (duration: 318.923448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:50.2696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:49.950307Z","time spent":"319.287181ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:33:50.269615Z","caller":"traceutil/trace.go:171","msg":"trace[34214357] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4044; }","duration":"318.99658ms","start":"2026-05-21T19:33:49.950593Z","end":"2026-05-21T19:33:50.269589Z","steps":["trace[34214357] 'range keys from in-memory index tree' (duration: 318.496992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:50.269413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:49.90987Z","time spent":"359.532707ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:33:50.269673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:49.950588Z","time spent":"319.068414ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T19:33:50.269687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.761879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T19:33:50.269802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:49.716966Z","time spent":"552.151056ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-21T19:33:50.269765Z","caller":"traceutil/trace.go:171","msg":"trace[49133617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4044; }","duration":"319.870813ms","start":"2026-05-21T19:33:49.949875Z","end":"2026-05-21T19:33:50.269746Z","steps":["trace[49133617] 'range keys from in-memory index tree' (duration: 319.579031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:50.269914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:49.949815Z","time spent":"320.08524ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:33:50.270481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.889173ms","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-05-21T19:33:50.270605Z","caller":"traceutil/trace.go:171","msg":"trace[627344768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4044; }","duration":"320.038477ms","start":"2026-05-21T19:33:49.950545Z","end":"2026-05-21T19:33:50.270584Z","steps":["trace[627344768] 'range keys from in-memory index tree' (duration: 318.985501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:50.270673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:49.950539Z","time spent":"320.12185ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T19:33:50.377279Z","caller":"traceutil/trace.go:171","msg":"trace[1506353678] transaction","detail":"{read_only:false; response_revision:4045; number_of_response:1; }","duration":"102.722498ms","start":"2026-05-21T19:33:50.274539Z","end":"2026-05-21T19:33:50.377261Z","steps":["trace[1506353678] 'process raft request' (duration: 102.597813ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:50.560415Z","caller":"traceutil/trace.go:171","msg":"trace[1567893630] transaction","detail":"{read_only:false; response_revision:4046; number_of_response:1; }","duration":"285.815677ms","start":"2026-05-21T19:33:50.27458Z","end":"2026-05-21T19:33:50.560395Z","steps":["trace[1567893630] 'process raft request' (duration: 235.895716ms)","trace[1567893630] 'compare' (duration: 49.757046ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:50.560676Z","caller":"traceutil/trace.go:171","msg":"trace[634833814] transaction","detail":"{read_only:false; response_revision:4047; number_of_response:1; }","duration":"285.913532ms","start":"2026-05-21T19:33:50.274738Z","end":"2026-05-21T19:33:50.560651Z","steps":["trace[634833814] 'process raft request' (duration: 285.618631ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:50.560814Z","caller":"traceutil/trace.go:171","msg":"trace[1698889488] transaction","detail":"{read_only:false; response_revision:4048; number_of_response:1; }","duration":"285.606991ms","start":"2026-05-21T19:33:50.275193Z","end":"2026-05-21T19:33:50.5608Z","steps":["trace[1698889488] 'process raft request' (duration: 285.382382ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:50.561226Z","caller":"traceutil/trace.go:171","msg":"trace[2011418919] transaction","detail":"{read_only:false; response_revision:4049; number_of_response:1; }","duration":"285.87227ms","start":"2026-05-21T19:33:50.275332Z","end":"2026-05-21T19:33:50.561204Z","steps":["trace[2011418919] 'process raft request' (duration: 285.428344ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:50.561269Z","caller":"traceutil/trace.go:171","msg":"trace[393992478] linearizableReadLoop","detail":"{readStateIndex:4214; appliedIndex:4208; }","duration":"261.402572ms","start":"2026-05-21T19:33:50.299851Z","end":"2026-05-21T19:33:50.561253Z","steps":["trace[393992478] 'read index received' (duration: 77.347817ms)","trace[393992478] 'applied index is now lower than readState.Index' (duration: 184.053795ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:50.561449Z","caller":"traceutil/trace.go:171","msg":"trace[1164310137] transaction","detail":"{read_only:false; response_revision:4050; number_of_response:1; }","duration":"283.687603ms","start":"2026-05-21T19:33:50.277629Z","end":"2026-05-21T19:33:50.561317Z","steps":["trace[1164310137] 'process raft request' (duration: 283.548618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:50.561804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.908669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:50.562446Z","caller":"traceutil/trace.go:171","msg":"trace[1578037904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4050; }","duration":"262.624785ms","start":"2026-05-21T19:33:50.299801Z","end":"2026-05-21T19:33:50.562426Z","steps":["trace[1578037904] 'agreement among raft nodes before linearized reading' (duration: 261.506896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.48078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.681649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:34:00.480897Z","caller":"traceutil/trace.go:171","msg":"trace[273411973] linearizableReadLoop","detail":"{readStateIndex:4710; appliedIndex:4709; }","duration":"247.756492ms","start":"2026-05-21T19:34:00.233121Z","end":"2026-05-21T19:34:00.480878Z","steps":["trace[273411973] 'read index received' (duration: 10.885427ms)","trace[273411973] 'applied index is now lower than readState.Index' (duration: 236.869665ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:00.481089Z","caller":"traceutil/trace.go:171","msg":"trace[916603167] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"248.53801ms","start":"2026-05-21T19:34:00.232424Z","end":"2026-05-21T19:34:00.480962Z","steps":["trace[916603167] 'process raft request' (duration: 11.590532ms)","trace[916603167] 'compare' (duration: 236.566035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:00.481479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.334393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-tls.18b1aba13fa09c6f\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-05-21T19:34:00.481598Z","caller":"traceutil/trace.go:171","msg":"trace[1730532163] range","detail":"{range_begin:/registry/events/monitoring/prometheus-tls.18b1aba13fa09c6f; range_end:; response_count:1; response_revision:4538; }","duration":"248.444487ms","start":"2026-05-21T19:34:00.233101Z","end":"2026-05-21T19:34:00.481545Z","steps":["trace[1730532163] 'agreement among raft nodes before linearized reading' (duration: 247.987871ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:00.560162Z","caller":"traceutil/trace.go:171","msg":"trace[1466783841] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"265.832831ms","start":"2026-05-21T19:34:00.294303Z","end":"2026-05-21T19:34:00.560136Z","steps":["trace[1466783841] 'process raft request' (duration: 265.598973ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:00.672301Z","caller":"traceutil/trace.go:171","msg":"trace[668879645] transaction","detail":"{read_only:false; response_revision:4540; number_of_response:1; }","duration":"277.155793ms","start":"2026-05-21T19:34:00.395092Z","end":"2026-05-21T19:34:00.672247Z","steps":["trace[668879645] 'process raft request' (duration: 246.208465ms)","trace[668879645] 'compare' (duration: 30.750361ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:00.672324Z","caller":"traceutil/trace.go:171","msg":"trace[1153823064] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4540; }","duration":"227.355318ms","start":"2026-05-21T19:34:00.444933Z","end":"2026-05-21T19:34:00.672288Z","steps":["trace[1153823064] 'process raft request' (duration: 227.230713ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:00.672337Z","caller":"traceutil/trace.go:171","msg":"trace[1643943553] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"188.540932ms","start":"2026-05-21T19:34:00.483771Z","end":"2026-05-21T19:34:00.672312Z","steps":["trace[1643943553] 'process raft request' (duration: 188.433549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.672584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.116252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:7 size:61050"} {"level":"info","ts":"2026-05-21T19:34:00.67264Z","caller":"traceutil/trace.go:171","msg":"trace[1870567055] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:7; response_revision:4541; }","duration":"432.198735ms","start":"2026-05-21T19:34:00.240432Z","end":"2026-05-21T19:34:00.672631Z","steps":["trace[1870567055] 'agreement among raft nodes before linearized reading' (duration: 431.916075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.672674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:00.240425Z","time spent":"432.239536ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":7,"response size":61074,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-05-21T19:34:00.672655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.318058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26c8798b-fbf2-482c-9fa6-3ee6dd86053e\" ","response":"range_response_count:1 size:3773"} {"level":"warn","ts":"2026-05-21T19:34:00.672649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.661642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:00.67275Z","caller":"traceutil/trace.go:171","msg":"trace[596772486] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26c8798b-fbf2-482c-9fa6-3ee6dd86053e; range_end:; response_count:1; response_revision:4541; }","duration":"331.436812ms","start":"2026-05-21T19:34:00.341296Z","end":"2026-05-21T19:34:00.672733Z","steps":["trace[596772486] 'agreement among raft nodes before linearized reading' (duration: 331.167102ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:00.672842Z","caller":"traceutil/trace.go:171","msg":"trace[643499864] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4541; }","duration":"260.897961ms","start":"2026-05-21T19:34:00.41193Z","end":"2026-05-21T19:34:00.672828Z","steps":["trace[643499864] 'agreement among raft nodes before linearized reading' (duration: 260.671173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.672985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:00.34129Z","time spent":"331.65949ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3797,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26c8798b-fbf2-482c-9fa6-3ee6dd86053e\" "} {"level":"warn","ts":"2026-05-21T19:34:00.673011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.411842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/grafana-tls-lrhxs\" ","response":"range_response_count:1 size:2444"} {"level":"info","ts":"2026-05-21T19:34:00.673044Z","caller":"traceutil/trace.go:171","msg":"trace[849984851] range","detail":"{range_begin:/registry/secrets/monitoring/grafana-tls-lrhxs; range_end:; response_count:1; response_revision:4541; }","duration":"339.488386ms","start":"2026-05-21T19:34:00.333547Z","end":"2026-05-21T19:34:00.673035Z","steps":["trace[849984851] 'agreement among raft nodes before linearized reading' (duration: 339.372301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.673079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:00.333523Z","time spent":"339.549378ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":2468,"request content":"key:\"/registry/secrets/monitoring/grafana-tls-lrhxs\" "} {"level":"warn","ts":"2026-05-21T19:34:00.673124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.518915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:00.673222Z","caller":"traceutil/trace.go:171","msg":"trace[498670996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4541; }","duration":"376.65897ms","start":"2026-05-21T19:34:00.296539Z","end":"2026-05-21T19:34:00.673198Z","steps":["trace[498670996] 'agreement among raft nodes before linearized reading' (duration: 376.415662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.673272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:00.296524Z","time spent":"376.739314ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:34:00.673365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.4475ms","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-05-21T19:34:00.673413Z","caller":"traceutil/trace.go:171","msg":"trace[1682421718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4541; }","duration":"230.627667ms","start":"2026-05-21T19:34:00.442774Z","end":"2026-05-21T19:34:00.673402Z","steps":["trace[1682421718] 'agreement among raft nodes before linearized reading' (duration: 230.405349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.673463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.935396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26c8798b-fbf2-482c-9fa6-3ee6dd86053e\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-05-21T19:34:00.673511Z","caller":"traceutil/trace.go:171","msg":"trace[2034433911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26c8798b-fbf2-482c-9fa6-3ee6dd86053e; range_end:; response_count:1; response_revision:4541; }","duration":"243.015448ms","start":"2026-05-21T19:34:00.430483Z","end":"2026-05-21T19:34:00.673498Z","steps":["trace[2034433911] 'agreement among raft nodes before linearized reading' (duration: 242.912405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.673734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.934897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-21T19:34:00.673782Z","caller":"traceutil/trace.go:171","msg":"trace[2087403688] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4541; }","duration":"223.01596ms","start":"2026-05-21T19:34:00.450755Z","end":"2026-05-21T19:34:00.673771Z","steps":["trace[2087403688] 'agreement among raft nodes before linearized reading' (duration: 222.876304ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:00.672302Z","caller":"traceutil/trace.go:171","msg":"trace[918475498] linearizableReadLoop","detail":"{readStateIndex:4713; appliedIndex:4710; }","duration":"191.349245ms","start":"2026-05-21T19:34:00.480935Z","end":"2026-05-21T19:34:00.672284Z","steps":["trace[918475498] 'read index received' (duration: 78.989279ms)","trace[918475498] 'applied index is now lower than readState.Index' (duration: 112.358926ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:00.674983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.863019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362287"} {"level":"info","ts":"2026-05-21T19:34:00.675057Z","caller":"traceutil/trace.go:171","msg":"trace[996422078] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4541; }","duration":"246.972893ms","start":"2026-05-21T19:34:00.428068Z","end":"2026-05-21T19:34:00.67504Z","steps":["trace[996422078] 'agreement among raft nodes before linearized reading' (duration: 244.351827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.825485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.584596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-05-21T19:34:00.825541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.713292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/grafana-tls\" ","response":"range_response_count:1 size:3049"} {"level":"info","ts":"2026-05-21T19:34:00.825615Z","caller":"traceutil/trace.go:171","msg":"trace[1612566578] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/grafana-tls; range_end:; response_count:1; response_revision:4551; }","duration":"114.826806ms","start":"2026-05-21T19:34:00.71077Z","end":"2026-05-21T19:34:00.825597Z","steps":["trace[1612566578] 'agreement among raft nodes before linearized reading' (duration: 114.613288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:00.825671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.107086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-21T19:34:00.825741Z","caller":"traceutil/trace.go:171","msg":"trace[1419137844] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4551; }","duration":"118.155178ms","start":"2026-05-21T19:34:00.707552Z","end":"2026-05-21T19:34:00.825708Z","steps":["trace[1419137844] 'agreement among raft nodes before linearized reading' (duration: 118.039073ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:00.825755Z","caller":"traceutil/trace.go:171","msg":"trace[1638961329] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"120.829894ms","start":"2026-05-21T19:34:00.704876Z","end":"2026-05-21T19:34:00.825706Z","steps":["trace[1638961329] 'process raft request' (duration: 98.699438ms)","trace[1638961329] 'compare' (duration: 21.621108ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:00.825783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.560605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-21T19:34:00.825813Z","caller":"traceutil/trace.go:171","msg":"trace[842442605] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4551; }","duration":"103.613247ms","start":"2026-05-21T19:34:00.722191Z","end":"2026-05-21T19:34:00.825804Z","steps":["trace[842442605] 'agreement among raft nodes before linearized reading' (duration: 103.527454ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:00.825564Z","caller":"traceutil/trace.go:171","msg":"trace[294729783] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4551; }","duration":"120.70336ms","start":"2026-05-21T19:34:00.704842Z","end":"2026-05-21T19:34:00.825545Z","steps":["trace[294729783] 'agreement among raft nodes before linearized reading' (duration: 120.456131ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:06.962033Z","caller":"traceutil/trace.go:171","msg":"trace[1031661071] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"157.738544ms","start":"2026-05-21T19:34:06.804276Z","end":"2026-05-21T19:34:06.962014Z","steps":["trace[1031661071] 'process raft request' (duration: 157.690502ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:06.962048Z","caller":"traceutil/trace.go:171","msg":"trace[1994993559] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"157.88468ms","start":"2026-05-21T19:34:06.804133Z","end":"2026-05-21T19:34:06.962017Z","steps":["trace[1994993559] 'process raft request' (duration: 157.692963ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:06.962333Z","caller":"traceutil/trace.go:171","msg":"trace[693048011] linearizableReadLoop","detail":"{readStateIndex:4858; appliedIndex:4855; }","duration":"107.770156ms","start":"2026-05-21T19:34:06.854539Z","end":"2026-05-21T19:34:06.962309Z","steps":["trace[693048011] 'read index received' (duration: 107.279798ms)","trace[693048011] 'applied index is now lower than readState.Index' (duration: 488.838µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:06.962344Z","caller":"traceutil/trace.go:171","msg":"trace[1027107621] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"158.060616ms","start":"2026-05-21T19:34:06.804269Z","end":"2026-05-21T19:34:06.96233Z","steps":["trace[1027107621] 'process raft request' (duration: 157.725274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:06.96255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.997574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T19:34:06.962577Z","caller":"traceutil/trace.go:171","msg":"trace[1995347239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4680; }","duration":"108.059216ms","start":"2026-05-21T19:34:06.854511Z","end":"2026-05-21T19:34:06.96257Z","steps":["trace[1995347239] 'agreement among raft nodes before linearized reading' (duration: 107.946372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:06.962747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.844857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-21T19:34:06.962796Z","caller":"traceutil/trace.go:171","msg":"trace[244265911] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4680; }","duration":"101.9315ms","start":"2026-05-21T19:34:06.860852Z","end":"2026-05-21T19:34:06.962783Z","steps":["trace[244265911] 'agreement among raft nodes before linearized reading' (duration: 101.694511ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:07.528605Z","caller":"traceutil/trace.go:171","msg":"trace[11319620] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"120.141133ms","start":"2026-05-21T19:34:07.408437Z","end":"2026-05-21T19:34:07.528578Z","steps":["trace[11319620] 'process raft request' (duration: 119.977197ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:08.163575Z","caller":"traceutil/trace.go:171","msg":"trace[409050995] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"746.75771ms","start":"2026-05-21T19:34:07.416792Z","end":"2026-05-21T19:34:08.16355Z","steps":["trace[409050995] 'process raft request' (duration: 746.532142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.163794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.416765Z","time spent":"746.953697ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9749,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:08.21183Z","caller":"traceutil/trace.go:171","msg":"trace[25365242] linearizableReadLoop","detail":"{readStateIndex:4864; appliedIndex:4861; }","duration":"316.538983ms","start":"2026-05-21T19:34:07.895245Z","end":"2026-05-21T19:34:08.211784Z","steps":["trace[25365242] 'read index received' (duration: 268.176726ms)","trace[25365242] 'applied index is now lower than readState.Index' (duration: 48.361067ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:08.211902Z","caller":"traceutil/trace.go:171","msg":"trace[686694700] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"572.920798ms","start":"2026-05-21T19:34:07.638946Z","end":"2026-05-21T19:34:08.211867Z","steps":["trace[686694700] 'process raft request' (duration: 572.633478ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:08.212071Z","caller":"traceutil/trace.go:171","msg":"trace[1995148800] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"573.022192ms","start":"2026-05-21T19:34:07.639028Z","end":"2026-05-21T19:34:08.21205Z","steps":["trace[1995148800] 'process raft request' (duration: 572.70335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.212175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.639004Z","time spent":"573.106385ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:08.212257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.638881Z","time spent":"573.182988ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:08.212015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.737551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:08.212424Z","caller":"traceutil/trace.go:171","msg":"trace[1907635262] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4686; }","duration":"317.204439ms","start":"2026-05-21T19:34:07.895191Z","end":"2026-05-21T19:34:08.212395Z","steps":["trace[1907635262] 'agreement among raft nodes before linearized reading' (duration: 316.73029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.2125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:07.895167Z","time spent":"317.309043ms","remote":"127.0.0.1:47874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":21,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:08.52174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.371552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:08.521829Z","caller":"traceutil/trace.go:171","msg":"trace[162080468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4686; }","duration":"223.509176ms","start":"2026-05-21T19:34:08.2983Z","end":"2026-05-21T19:34:08.521809Z","steps":["trace[162080468] 'range keys from in-memory index tree' (duration: 223.297819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:08.522044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.323756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw.18b1aba3b5d1c310\" ","response":"range_response_count:1 size:919"} {"level":"info","ts":"2026-05-21T19:34:08.522366Z","caller":"traceutil/trace.go:171","msg":"trace[532152736] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw.18b1aba3b5d1c310; range_end:; response_count:1; response_revision:4686; }","duration":"108.71126ms","start":"2026-05-21T19:34:08.413632Z","end":"2026-05-21T19:34:08.522343Z","steps":["trace[532152736] 'range keys from in-memory index tree' (duration: 107.731934ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:08.777341Z","caller":"traceutil/trace.go:171","msg":"trace[1061650477] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"252.606237ms","start":"2026-05-21T19:34:08.524704Z","end":"2026-05-21T19:34:08.77731Z","steps":["trace[1061650477] 'process raft request' (duration: 252.437301ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:08.777703Z","caller":"traceutil/trace.go:171","msg":"trace[1169744583] linearizableReadLoop","detail":"{readStateIndex:4865; appliedIndex:4865; }","duration":"185.824056ms","start":"2026-05-21T19:34:08.591851Z","end":"2026-05-21T19:34:08.777675Z","steps":["trace[1169744583] 'read index received' (duration: 185.813395ms)","trace[1169744583] 'applied index is now lower than readState.Index' (duration: 8.28µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:08.777918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.045464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-21T19:34:08.778213Z","caller":"traceutil/trace.go:171","msg":"trace[205225503] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4687; }","duration":"186.400318ms","start":"2026-05-21T19:34:08.591801Z","end":"2026-05-21T19:34:08.778202Z","steps":["trace[205225503] 'agreement among raft nodes before linearized reading' (duration: 185.972441ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:09.091627Z","caller":"traceutil/trace.go:171","msg":"trace[280936317] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"121.205412ms","start":"2026-05-21T19:34:08.970393Z","end":"2026-05-21T19:34:09.091598Z","steps":["trace[280936317] 'process raft request' (duration: 120.998865ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:09.091656Z","caller":"traceutil/trace.go:171","msg":"trace[1135668653] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"120.869169ms","start":"2026-05-21T19:34:08.970761Z","end":"2026-05-21T19:34:09.09163Z","steps":["trace[1135668653] 'process raft request' (duration: 120.738735ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:09.091847Z","caller":"traceutil/trace.go:171","msg":"trace[1939108065] linearizableReadLoop","detail":"{readStateIndex:4870; appliedIndex:4867; }","duration":"106.629351ms","start":"2026-05-21T19:34:08.985199Z","end":"2026-05-21T19:34:09.091828Z","steps":["trace[1939108065] 'read index received' (duration: 51.203577ms)","trace[1939108065] 'applied index is now lower than readState.Index' (duration: 55.162604ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:09.091885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.664272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-21T19:34:09.091963Z","caller":"traceutil/trace.go:171","msg":"trace[1704187695] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4692; }","duration":"106.772337ms","start":"2026-05-21T19:34:08.985173Z","end":"2026-05-21T19:34:09.091945Z","steps":["trace[1704187695] 'agreement among raft nodes before linearized reading' (duration: 106.582499ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:11.305644Z","caller":"traceutil/trace.go:171","msg":"trace[1556166923] linearizableReadLoop","detail":"{readStateIndex:4879; appliedIndex:4878; }","duration":"349.469201ms","start":"2026-05-21T19:34:10.956156Z","end":"2026-05-21T19:34:11.305625Z","steps":["trace[1556166923] 'read index received' (duration: 349.277894ms)","trace[1556166923] 'applied index is now lower than readState.Index' (duration: 190.257µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:11.305761Z","caller":"traceutil/trace.go:171","msg":"trace[483143264] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"349.95672ms","start":"2026-05-21T19:34:10.955786Z","end":"2026-05-21T19:34:11.305743Z","steps":["trace[483143264] 'process raft request' (duration: 349.70043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:11.30588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.70484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw-tls\" ","response":"range_response_count:1 size:4427"} {"level":"warn","ts":"2026-05-21T19:34:11.305937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.065048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:11.305951Z","caller":"traceutil/trace.go:171","msg":"trace[222694774] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw-tls; range_end:; response_count:1; response_revision:4700; }","duration":"349.808934ms","start":"2026-05-21T19:34:10.956125Z","end":"2026-05-21T19:34:11.305933Z","steps":["trace[222694774] 'agreement among raft nodes before linearized reading' (duration: 349.620457ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:11.30598Z","caller":"traceutil/trace.go:171","msg":"trace[623235890] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4700; }","duration":"326.156452ms","start":"2026-05-21T19:34:10.979809Z","end":"2026-05-21T19:34:11.305965Z","steps":["trace[623235890] 'agreement among raft nodes before linearized reading' (duration: 326.089179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:11.305985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:10.956113Z","time spent":"349.865986ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":4451,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw-tls\" "} {"level":"warn","ts":"2026-05-21T19:34:11.306017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:10.97979Z","time spent":"326.207133ms","remote":"127.0.0.1:37744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:11.30607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.706438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T19:34:11.306128Z","caller":"traceutil/trace.go:171","msg":"trace[1938747721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4700; }","duration":"185.800291ms","start":"2026-05-21T19:34:11.120313Z","end":"2026-05-21T19:34:11.306114Z","steps":["trace[1938747721] 'agreement among raft nodes before linearized reading' (duration: 185.638665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:11.306203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.713716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-21T19:34:11.30623Z","caller":"traceutil/trace.go:171","msg":"trace[1991207044] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4700; }","duration":"209.747118ms","start":"2026-05-21T19:34:11.096475Z","end":"2026-05-21T19:34:11.306222Z","steps":["trace[1991207044] 'agreement among raft nodes before linearized reading' (duration: 209.682546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:11.306319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.483994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-21T19:34:11.306357Z","caller":"traceutil/trace.go:171","msg":"trace[540453063] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4700; }","duration":"172.544596ms","start":"2026-05-21T19:34:11.133801Z","end":"2026-05-21T19:34:11.306345Z","steps":["trace[540453063] 'agreement among raft nodes before linearized reading' (duration: 172.466443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:11.306368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.727082ms","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-05-21T19:34:11.306386Z","caller":"traceutil/trace.go:171","msg":"trace[79849033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4700; }","duration":"261.744913ms","start":"2026-05-21T19:34:11.044636Z","end":"2026-05-21T19:34:11.306381Z","steps":["trace[79849033] 'agreement among raft nodes before linearized reading' (duration: 261.698442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:11.306324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.022588ms","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-05-21T19:34:11.306424Z","caller":"traceutil/trace.go:171","msg":"trace[219105294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4700; }","duration":"210.147393ms","start":"2026-05-21T19:34:11.09627Z","end":"2026-05-21T19:34:11.306418Z","steps":["trace[219105294] 'agreement among raft nodes before linearized reading' (duration: 210.004077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:11.307005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:10.955767Z","time spent":"350.107824ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:14.834142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371706547167372,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:15.334228Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371706547167372,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:15.436298Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.197649043s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:34:15.436762Z","caller":"traceutil/trace.go:171","msg":"trace[634122142] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"1.198181283s","start":"2026-05-21T19:34:14.238555Z","end":"2026-05-21T19:34:15.436736Z","steps":["trace[634122142] 'process raft request' (duration: 1.197949794s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.436963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:14.238539Z","time spent":"1.198311798s","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:15.572906Z","caller":"traceutil/trace.go:171","msg":"trace[69852849] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4953; }","duration":"1.23895404s","start":"2026-05-21T19:34:14.333929Z","end":"2026-05-21T19:34:15.572883Z","steps":["trace[69852849] 'read index received' (duration: 1.102534421s)","trace[69852849] 'applied index is now lower than readState.Index' (duration: 136.418329ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:15.572941Z","caller":"traceutil/trace.go:171","msg":"trace[1759263527] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"1.334237189s","start":"2026-05-21T19:34:14.238672Z","end":"2026-05-21T19:34:15.57291Z","steps":["trace[1759263527] 'process raft request' (duration: 1.276818062s)","trace[1759263527] 'compare' (duration: 57.23039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:15.573195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:14.238643Z","time spent":"1.334477208s","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:15.573344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239409198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0dba8f4-15a4-4bc0-bad1-c40791d13a8d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-21T19:34:15.5734Z","caller":"traceutil/trace.go:171","msg":"trace[1517287708] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b0dba8f4-15a4-4bc0-bad1-c40791d13a8d; range_end:; response_count:1; response_revision:4776; }","duration":"1.239504241s","start":"2026-05-21T19:34:14.33388Z","end":"2026-05-21T19:34:15.573385Z","steps":["trace[1517287708] 'agreement among raft nodes before linearized reading' (duration: 1.239110136s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.0351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-21T19:34:15.573491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:14.333862Z","time spent":"1.239598075s","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0dba8f4-15a4-4bc0-bad1-c40791d13a8d\" "} {"level":"warn","ts":"2026-05-21T19:34:15.573613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.186876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-21T19:34:15.573644Z","caller":"traceutil/trace.go:171","msg":"trace[373023299] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4776; }","duration":"240.227477ms","start":"2026-05-21T19:34:15.333407Z","end":"2026-05-21T19:34:15.573634Z","steps":["trace[373023299] 'agreement among raft nodes before linearized reading' (duration: 240.147614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.874936ms","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-05-21T19:34:15.573617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1014125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0dba8f4-15a4-4bc0-bad1-c40791d13a8d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-21T19:34:15.57369Z","caller":"traceutil/trace.go:171","msg":"trace[425508734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4776; }","duration":"605.929988ms","start":"2026-05-21T19:34:14.967749Z","end":"2026-05-21T19:34:15.573679Z","steps":["trace[425508734] 'agreement among raft nodes before linearized reading' (duration: 605.855645ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:15.573718Z","caller":"traceutil/trace.go:171","msg":"trace[1770686834] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"668.461394ms","start":"2026-05-21T19:34:14.904492Z","end":"2026-05-21T19:34:15.572954Z","steps":["trace[1770686834] 'process raft request' (duration: 668.34764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:14.967735Z","time spent":"605.988791ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T19:34:15.573744Z","caller":"traceutil/trace.go:171","msg":"trace[1851453084] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b0dba8f4-15a4-4bc0-bad1-c40791d13a8d; range_end:; response_count:1; response_revision:4776; }","duration":"1.101587537s","start":"2026-05-21T19:34:14.472112Z","end":"2026-05-21T19:34:15.573699Z","steps":["trace[1851453084] 'agreement among raft nodes before linearized reading' (duration: 1.101201362s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.490813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-21T19:34:15.573799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:14.472095Z","time spent":"1.101691741s","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0dba8f4-15a4-4bc0-bad1-c40791d13a8d\" "} {"level":"warn","ts":"2026-05-21T19:34:15.573852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.635375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-21T19:34:15.573867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:14.904466Z","time spent":"669.311396ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:15.57389Z","caller":"traceutil/trace.go:171","msg":"trace[1177885720] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4776; }","duration":"961.706999ms","start":"2026-05-21T19:34:14.612175Z","end":"2026-05-21T19:34:15.573882Z","steps":["trace[1177885720] 'agreement among raft nodes before linearized reading' (duration: 961.626926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:14.612158Z","time spent":"961.75838ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-21T19:34:15.573529Z","caller":"traceutil/trace.go:171","msg":"trace[69359722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4776; }","duration":"240.140535ms","start":"2026-05-21T19:34:15.333371Z","end":"2026-05-21T19:34:15.573511Z","steps":["trace[69359722] 'agreement among raft nodes before linearized reading' (duration: 239.936807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.088202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-21T19:34:15.573445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.259352ms","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-05-21T19:34:15.574102Z","caller":"traceutil/trace.go:171","msg":"trace[1791505743] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4776; }","duration":"287.948098ms","start":"2026-05-21T19:34:15.286126Z","end":"2026-05-21T19:34:15.574074Z","steps":["trace[1791505743] 'agreement among raft nodes before linearized reading' (duration: 287.19471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.295211ms","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-05-21T19:34:15.574158Z","caller":"traceutil/trace.go:171","msg":"trace[1111588728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4776; }","duration":"240.932344ms","start":"2026-05-21T19:34:15.333215Z","end":"2026-05-21T19:34:15.574148Z","steps":["trace[1111588728] 'agreement among raft nodes before linearized reading' (duration: 240.27282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.582481ms","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-05-21T19:34:15.574235Z","caller":"traceutil/trace.go:171","msg":"trace[892750486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4776; }","duration":"232.379471ms","start":"2026-05-21T19:34:15.341843Z","end":"2026-05-21T19:34:15.574222Z","steps":["trace[892750486] 'agreement among raft nodes before linearized reading' (duration: 231.492078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.573578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.667163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:15.574341Z","caller":"traceutil/trace.go:171","msg":"trace[1702704257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4776; }","duration":"277.448762ms","start":"2026-05-21T19:34:15.296884Z","end":"2026-05-21T19:34:15.574332Z","steps":["trace[1702704257] 'agreement among raft nodes before linearized reading' (duration: 276.676804ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:15.574059Z","caller":"traceutil/trace.go:171","msg":"trace[1030974147] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4776; }","duration":"240.616132ms","start":"2026-05-21T19:34:15.333421Z","end":"2026-05-21T19:34:15.574037Z","steps":["trace[1030974147] 'agreement among raft nodes before linearized reading' (duration: 239.923826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:15.574355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.694634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T19:34:15.574448Z","caller":"traceutil/trace.go:171","msg":"trace[1680935237] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4776; }","duration":"178.872802ms","start":"2026-05-21T19:34:15.395564Z","end":"2026-05-21T19:34:15.574437Z","steps":["trace[1680935237] 'agreement among raft nodes before linearized reading' (duration: 178.004329ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:15.573839Z","caller":"traceutil/trace.go:171","msg":"trace[1034266714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4776; }","duration":"228.560147ms","start":"2026-05-21T19:34:15.345271Z","end":"2026-05-21T19:34:15.573831Z","steps":["trace[1034266714] 'agreement among raft nodes before linearized reading' (duration: 228.481444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:16.102842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.222712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:34:16.102943Z","caller":"traceutil/trace.go:171","msg":"trace[456871371] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4966; }","duration":"312.971172ms","start":"2026-05-21T19:34:15.789954Z","end":"2026-05-21T19:34:16.102925Z","steps":["trace[456871371] 'read index received' (duration: 146.673377ms)","trace[456871371] 'applied index is now lower than readState.Index' (duration: 166.296255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:16.103096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.145088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-05-21T19:34:16.103131Z","caller":"traceutil/trace.go:171","msg":"trace[913250508] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4786; }","duration":"313.203341ms","start":"2026-05-21T19:34:15.789917Z","end":"2026-05-21T19:34:16.10312Z","steps":["trace[913250508] 'agreement among raft nodes before linearized reading' (duration: 313.053495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:16.103164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:15.789898Z","time spent":"313.257733ms","remote":"127.0.0.1:42958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1475,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-21T19:34:16.103513Z","caller":"traceutil/trace.go:171","msg":"trace[865595784] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"317.785833ms","start":"2026-05-21T19:34:15.78569Z","end":"2026-05-21T19:34:16.103476Z","steps":["trace[865595784] 'process raft request' (duration: 150.844374ms)","trace[865595784] 'compare' (duration: 165.770065ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:16.103732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:15.785663Z","time spent":"317.98867ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:16.385497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.851583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:34:16.385644Z","caller":"traceutil/trace.go:171","msg":"trace[581034430] linearizableReadLoop","detail":"{readStateIndex:4969; appliedIndex:4967; }","duration":"278.567617ms","start":"2026-05-21T19:34:16.107047Z","end":"2026-05-21T19:34:16.385615Z","steps":["trace[581034430] 'read index received' (duration: 139.59996ms)","trace[581034430] 'applied index is now lower than readState.Index' (duration: 138.966787ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:16.38571Z","caller":"traceutil/trace.go:171","msg":"trace[1713039745] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"595.065672ms","start":"2026-05-21T19:34:15.790637Z","end":"2026-05-21T19:34:16.385703Z","steps":["trace[1713039745] 'process raft request' (duration: 455.948839ms)","trace[1713039745] 'compare' (duration: 138.76851ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:16.385766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:15.790619Z","time spent":"595.123294ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:16.385931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.874039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18b1aba5178d87aa\" ","response":"range_response_count:1 size:740"} {"level":"warn","ts":"2026-05-21T19:34:16.385978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.08143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-05-21T19:34:16.386Z","caller":"traceutil/trace.go:171","msg":"trace[963115893] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4788; }","duration":"273.134552ms","start":"2026-05-21T19:34:16.112858Z","end":"2026-05-21T19:34:16.385993Z","steps":["trace[963115893] 'agreement among raft nodes before linearized reading' (duration: 273.042538ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:16.386005Z","caller":"traceutil/trace.go:171","msg":"trace[1806250909] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18b1aba5178d87aa; range_end:; response_count:1; response_revision:4788; }","duration":"279.022315ms","start":"2026-05-21T19:34:16.106965Z","end":"2026-05-21T19:34:16.385987Z","steps":["trace[1806250909] 'agreement among raft nodes before linearized reading' (duration: 278.817257ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:16.386033Z","caller":"traceutil/trace.go:171","msg":"trace[188548422] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"528.192338ms","start":"2026-05-21T19:34:15.857806Z","end":"2026-05-21T19:34:16.385999Z","steps":["trace[188548422] 'process raft request' (duration: 527.762442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:16.386214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:15.857773Z","time spent":"528.344783ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10681,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:16.615069Z","caller":"traceutil/trace.go:171","msg":"trace[791505007] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"226.114964ms","start":"2026-05-21T19:34:16.38892Z","end":"2026-05-21T19:34:16.615035Z","steps":["trace[791505007] 'process raft request' (duration: 173.381389ms)","trace[791505007] 'compare' (duration: 52.518777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:16.966382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.801335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw\" ","response":"range_response_count:1 size:9866"} {"level":"info","ts":"2026-05-21T19:34:16.966471Z","caller":"traceutil/trace.go:171","msg":"trace[2135643688] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw; range_end:; response_count:1; response_revision:4790; }","duration":"168.995942ms","start":"2026-05-21T19:34:16.797454Z","end":"2026-05-21T19:34:16.96645Z","steps":["trace[2135643688] 'range keys from in-memory index tree' (duration: 168.574196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:16.966623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.716096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw.18b1aba3799c3ba1\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-05-21T19:34:16.966696Z","caller":"traceutil/trace.go:171","msg":"trace[699478305] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-854xw.18b1aba3799c3ba1; range_end:; response_count:1; response_revision:4790; }","duration":"166.834471ms","start":"2026-05-21T19:34:16.799842Z","end":"2026-05-21T19:34:16.966676Z","steps":["trace[699478305] 'range keys from in-memory index tree' (duration: 166.476727ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:18.434164Z","caller":"traceutil/trace.go:171","msg":"trace[2098258106] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"136.231004ms","start":"2026-05-21T19:34:18.297905Z","end":"2026-05-21T19:34:18.434136Z","steps":["trace[2098258106] 'read index received' (duration: 136.12907ms)","trace[2098258106] 'applied index is now lower than readState.Index' (duration: 100.534µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:18.434355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.427632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:18.434547Z","caller":"traceutil/trace.go:171","msg":"trace[684772655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4823; }","duration":"136.67272ms","start":"2026-05-21T19:34:18.297855Z","end":"2026-05-21T19:34:18.434527Z","steps":["trace[684772655] 'agreement among raft nodes before linearized reading' (duration: 136.39661ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:18.434799Z","caller":"traceutil/trace.go:171","msg":"trace[828493395] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"308.413265ms","start":"2026-05-21T19:34:18.12579Z","end":"2026-05-21T19:34:18.434203Z","steps":["trace[828493395] 'process raft request' (duration: 308.177486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:18.434935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:18.125772Z","time spent":"309.094782ms","remote":"127.0.0.1:42872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:34:21.237757Z","caller":"traceutil/trace.go:171","msg":"trace[2077771491] linearizableReadLoop","detail":"{readStateIndex:5053; appliedIndex:5052; }","duration":"232.874701ms","start":"2026-05-21T19:34:21.004846Z","end":"2026-05-21T19:34:21.237721Z","steps":["trace[2077771491] 'read index received' (duration: 230.407937ms)","trace[2077771491] 'applied index is now lower than readState.Index' (duration: 2.464934ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:21.237819Z","caller":"traceutil/trace.go:171","msg":"trace[2124250716] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"563.425199ms","start":"2026-05-21T19:34:20.674355Z","end":"2026-05-21T19:34:21.237781Z","steps":["trace[2124250716] 'process raft request' (duration: 560.953685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:21.23795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.082288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:21.238222Z","caller":"traceutil/trace.go:171","msg":"trace[126786466] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4870; }","duration":"233.412501ms","start":"2026-05-21T19:34:21.004788Z","end":"2026-05-21T19:34:21.238201Z","steps":["trace[126786466] 'agreement among raft nodes before linearized reading' (duration: 233.062058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:21.238185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:20.674335Z","time spent":"563.683999ms","remote":"127.0.0.1:47920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2131,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:34:30.393186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.539044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:30.393305Z","caller":"traceutil/trace.go:171","msg":"trace[1253668603] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4979; }","duration":"232.754913ms","start":"2026-05-21T19:34:30.160531Z","end":"2026-05-21T19:34:30.393286Z","steps":["trace[1253668603] 'count revisions from in-memory index tree' (duration: 232.42979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.179035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12602371706547167831,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:34:31.557446Z","caller":"traceutil/trace.go:171","msg":"trace[1807907221] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5166; }","duration":"879.376282ms","start":"2026-05-21T19:34:30.678046Z","end":"2026-05-21T19:34:31.557423Z","steps":["trace[1807907221] 'read index received' (duration: 879.34158ms)","trace[1807907221] 'applied index is now lower than readState.Index' (duration: 33.752µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:31.557561Z","caller":"traceutil/trace.go:171","msg":"trace[1097076359] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"934.782627ms","start":"2026-05-21T19:34:30.622742Z","end":"2026-05-21T19:34:31.557524Z","steps":["trace[1097076359] 'process raft request' (duration: 934.496796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.557658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.602491ms","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-05-21T19:34:31.557692Z","caller":"traceutil/trace.go:171","msg":"trace[564228275] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4980; }","duration":"879.687334ms","start":"2026-05-21T19:34:30.677995Z","end":"2026-05-21T19:34:31.557682Z","steps":["trace[564228275] 'agreement among raft nodes before linearized reading' (duration: 879.546318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.557727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:30.677975Z","time spent":"879.744216ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-21T19:34:31.557726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:30.622721Z","time spent":"934.914972ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:31.564737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.185003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-21T19:34:31.564809Z","caller":"traceutil/trace.go:171","msg":"trace[1143815638] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4981; }","duration":"862.312188ms","start":"2026-05-21T19:34:30.70248Z","end":"2026-05-21T19:34:31.564792Z","steps":["trace[1143815638] 'agreement among raft nodes before linearized reading' (duration: 862.129461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.564848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:30.702462Z","time spent":"862.37714ms","remote":"127.0.0.1:42958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-21T19:34:31.565085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.442555ms","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-05-21T19:34:31.565116Z","caller":"traceutil/trace.go:171","msg":"trace[1626826252] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4981; }","duration":"167.511997ms","start":"2026-05-21T19:34:31.397595Z","end":"2026-05-21T19:34:31.565107Z","steps":["trace[1626826252] 'agreement among raft nodes before linearized reading' (duration: 167.416564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.56523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.25223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-21T19:34:31.565288Z","caller":"traceutil/trace.go:171","msg":"trace[1608879378] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4981; }","duration":"174.345955ms","start":"2026-05-21T19:34:31.390928Z","end":"2026-05-21T19:34:31.565274Z","steps":["trace[1608879378] 'agreement among raft nodes before linearized reading' (duration: 174.190269ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.746899Z","caller":"traceutil/trace.go:171","msg":"trace[1535773124] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"182.543413ms","start":"2026-05-21T19:34:31.564318Z","end":"2026-05-21T19:34:31.746861Z","steps":["trace[1535773124] 'process raft request' (duration: 95.657077ms)","trace[1535773124] 'compare' (duration: 86.623235ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:31.747217Z","caller":"traceutil/trace.go:171","msg":"trace[2133644770] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"177.913143ms","start":"2026-05-21T19:34:31.569248Z","end":"2026-05-21T19:34:31.747161Z","steps":["trace[2133644770] 'process raft request' (duration: 177.84039ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.747474Z","caller":"traceutil/trace.go:171","msg":"trace[7550241] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5170; }","duration":"179.844039ms","start":"2026-05-21T19:34:31.567611Z","end":"2026-05-21T19:34:31.747455Z","steps":["trace[7550241] 'read index received' (duration: 92.306707ms)","trace[7550241] 'applied index is now lower than readState.Index' (duration: 87.535122ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:31.747524Z","caller":"traceutil/trace.go:171","msg":"trace[1707635544] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"117.27578ms","start":"2026-05-21T19:34:31.630235Z","end":"2026-05-21T19:34:31.747511Z","steps":["trace[1707635544] 'process raft request' (duration: 116.894985ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.747582Z","caller":"traceutil/trace.go:171","msg":"trace[2135425367] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"181.741422ms","start":"2026-05-21T19:34:31.565829Z","end":"2026-05-21T19:34:31.74757Z","steps":["trace[2135425367] 'process raft request' (duration: 180.999202ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.747591Z","caller":"traceutil/trace.go:171","msg":"trace[1157256310] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"183.153877ms","start":"2026-05-21T19:34:31.564419Z","end":"2026-05-21T19:34:31.747573Z","steps":["trace[1157256310] 'process raft request' (duration: 182.334545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.747818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.553108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-05-21T19:34:31.747855Z","caller":"traceutil/trace.go:171","msg":"trace[158497260] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4988; }","duration":"178.616311ms","start":"2026-05-21T19:34:31.569229Z","end":"2026-05-21T19:34:31.747846Z","steps":["trace[158497260] 'agreement among raft nodes before linearized reading' (duration: 178.444934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.747843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.221703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b0dba8f4-15a4-4bc0-bad1-c40791d13a8d\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-21T19:34:31.747892Z","caller":"traceutil/trace.go:171","msg":"trace[315664503] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b0dba8f4-15a4-4bc0-bad1-c40791d13a8d; range_end:; response_count:1; response_revision:4988; }","duration":"180.296126ms","start":"2026-05-21T19:34:31.56758Z","end":"2026-05-21T19:34:31.747877Z","steps":["trace[315664503] 'agreement among raft nodes before linearized reading' (duration: 180.176921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.747987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.466952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:31.748046Z","caller":"traceutil/trace.go:171","msg":"trace[1043309989] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4988; }","duration":"103.566846ms","start":"2026-05-21T19:34:31.644458Z","end":"2026-05-21T19:34:31.748025Z","steps":["trace[1043309989] 'agreement among raft nodes before linearized reading' (duration: 103.472962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.748075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.582956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:31.748103Z","caller":"traceutil/trace.go:171","msg":"trace[617057734] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:4988; }","duration":"161.648969ms","start":"2026-05-21T19:34:31.586445Z","end":"2026-05-21T19:34:31.748093Z","steps":["trace[617057734] 'agreement among raft nodes before linearized reading' (duration: 161.598737ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.925159Z","caller":"traceutil/trace.go:171","msg":"trace[573240424] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5176; }","duration":"160.536825ms","start":"2026-05-21T19:34:31.76461Z","end":"2026-05-21T19:34:31.925147Z","steps":["trace[573240424] 'read index received' (duration: 8.892326ms)","trace[573240424] 'applied index is now lower than readState.Index' (duration: 151.643839ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:31.925164Z","caller":"traceutil/trace.go:171","msg":"trace[1926898452] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"160.686211ms","start":"2026-05-21T19:34:31.76445Z","end":"2026-05-21T19:34:31.925137Z","steps":["trace[1926898452] 'process raft request' (duration: 153.015951ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.925318Z","caller":"traceutil/trace.go:171","msg":"trace[327566491] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"160.848567ms","start":"2026-05-21T19:34:31.764463Z","end":"2026-05-21T19:34:31.925312Z","steps":["trace[327566491] 'process raft request' (duration: 160.621099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.925471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.242294ms","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-05-21T19:34:31.925622Z","caller":"traceutil/trace.go:171","msg":"trace[1880869089] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"160.895009ms","start":"2026-05-21T19:34:31.76472Z","end":"2026-05-21T19:34:31.925615Z","steps":["trace[1880869089] 'process raft request' (duration: 160.40221ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:31.925657Z","caller":"traceutil/trace.go:171","msg":"trace[1337434666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4993; }","duration":"160.450022ms","start":"2026-05-21T19:34:31.765189Z","end":"2026-05-21T19:34:31.925639Z","steps":["trace[1337434666] 'agreement among raft nodes before linearized reading' (duration: 160.14002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.925599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.983232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-05-21T19:34:31.925712Z","caller":"traceutil/trace.go:171","msg":"trace[1851554756] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4993; }","duration":"161.107407ms","start":"2026-05-21T19:34:31.764598Z","end":"2026-05-21T19:34:31.925706Z","steps":["trace[1851554756] 'agreement among raft nodes before linearized reading' (duration: 160.93371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.925898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.705215ms","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-05-21T19:34:31.925937Z","caller":"traceutil/trace.go:171","msg":"trace[6937636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4993; }","duration":"157.757056ms","start":"2026-05-21T19:34:31.768173Z","end":"2026-05-21T19:34:31.92593Z","steps":["trace[6937636] 'agreement among raft nodes before linearized reading' (duration: 157.683103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:31.926017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.798729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-21T19:34:31.92604Z","caller":"traceutil/trace.go:171","msg":"trace[962618737] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4993; }","duration":"156.835031ms","start":"2026-05-21T19:34:31.769199Z","end":"2026-05-21T19:34:31.926034Z","steps":["trace[962618737] 'agreement among raft nodes before linearized reading' (duration: 156.780099ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:39.945281Z","caller":"traceutil/trace.go:171","msg":"trace[1646923476] linearizableReadLoop","detail":"{readStateIndex:5243; appliedIndex:5242; }","duration":"149.29175ms","start":"2026-05-21T19:34:39.795961Z","end":"2026-05-21T19:34:39.945252Z","steps":["trace[1646923476] 'read index received' (duration: 149.03755ms)","trace[1646923476] 'applied index is now lower than readState.Index' (duration: 252.48µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:39.945558Z","caller":"traceutil/trace.go:171","msg":"trace[26323833] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"159.144779ms","start":"2026-05-21T19:34:39.786382Z","end":"2026-05-21T19:34:39.945527Z","steps":["trace[26323833] 'process raft request' (duration: 158.64058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:39.945864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.697815ms","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-05-21T19:34:39.945932Z","caller":"traceutil/trace.go:171","msg":"trace[284583142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5054; }","duration":"149.988567ms","start":"2026-05-21T19:34:39.795925Z","end":"2026-05-21T19:34:39.945914Z","steps":["trace[284583142] 'agreement among raft nodes before linearized reading' (duration: 149.441085ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.160407Z","caller":"traceutil/trace.go:171","msg":"trace[1348993580] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"150.624792ms","start":"2026-05-21T19:34:40.009756Z","end":"2026-05-21T19:34:40.160381Z","steps":["trace[1348993580] 'process raft request' (duration: 149.867432ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.229203Z","caller":"traceutil/trace.go:171","msg":"trace[608206963] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"218.904466ms","start":"2026-05-21T19:34:40.010261Z","end":"2026-05-21T19:34:40.229165Z","steps":["trace[608206963] 'process raft request' (duration: 218.75206ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.229343Z","caller":"traceutil/trace.go:171","msg":"trace[773252007] transaction","detail":"{read_only:false; response_revision:5059; number_of_response:1; }","duration":"218.392235ms","start":"2026-05-21T19:34:40.01088Z","end":"2026-05-21T19:34:40.229272Z","steps":["trace[773252007] 'process raft request' (duration: 218.26194ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.229228Z","caller":"traceutil/trace.go:171","msg":"trace[626802931] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"218.329683ms","start":"2026-05-21T19:34:40.010883Z","end":"2026-05-21T19:34:40.229212Z","steps":["trace[626802931] 'process raft request' (duration: 218.293041ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:40.229241Z","caller":"traceutil/trace.go:171","msg":"trace[2123763518] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"218.380145ms","start":"2026-05-21T19:34:40.010834Z","end":"2026-05-21T19:34:40.229214Z","steps":["trace[2123763518] 'process raft request' (duration: 218.26654ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:11.858957Z","caller":"traceutil/trace.go:171","msg":"trace[1569986395] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"106.683269ms","start":"2026-05-21T19:35:11.752251Z","end":"2026-05-21T19:35:11.858935Z","steps":["trace[1569986395] 'process raft request' (duration: 106.499101ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:12.010445Z","caller":"traceutil/trace.go:171","msg":"trace[1165420045] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"113.535903ms","start":"2026-05-21T19:35:11.896871Z","end":"2026-05-21T19:35:12.010407Z","steps":["trace[1165420045] 'process raft request' (duration: 18.670794ms)","trace[1165420045] 'compare' (duration: 94.670811ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:14.002728Z","caller":"traceutil/trace.go:171","msg":"trace[1861996178] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"109.692485ms","start":"2026-05-21T19:35:13.893007Z","end":"2026-05-21T19:35:14.0027Z","steps":["trace[1861996178] 'process raft request' (duration: 77.656145ms)","trace[1861996178] 'compare' (duration: 31.929576ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:20.713987Z","caller":"traceutil/trace.go:171","msg":"trace[442984120] linearizableReadLoop","detail":"{readStateIndex:5602; appliedIndex:5601; }","duration":"183.786436ms","start":"2026-05-21T19:35:20.530162Z","end":"2026-05-21T19:35:20.713948Z","steps":["trace[442984120] 'read index received' (duration: 130.943023ms)","trace[442984120] 'applied index is now lower than readState.Index' (duration: 52.841743ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:20.714377Z","caller":"traceutil/trace.go:171","msg":"trace[1659103542] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"186.0466ms","start":"2026-05-21T19:35:20.528282Z","end":"2026-05-21T19:35:20.714329Z","steps":["trace[1659103542] 'process raft request' (duration: 132.760978ms)","trace[1659103542] 'compare' (duration: 52.697627ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:35:20.714457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.269826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-21T19:35:20.714539Z","caller":"traceutil/trace.go:171","msg":"trace[86185417] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5403; }","duration":"184.378301ms","start":"2026-05-21T19:35:20.530138Z","end":"2026-05-21T19:35:20.714516Z","steps":["trace[86185417] 'agreement among raft nodes before linearized reading' (duration: 184.139271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:20.728045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.814431ms","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-05-21T19:35:20.728184Z","caller":"traceutil/trace.go:171","msg":"trace[737824845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5404; }","duration":"196.974678ms","start":"2026-05-21T19:35:20.53119Z","end":"2026-05-21T19:35:20.728165Z","steps":["trace[737824845] 'agreement among raft nodes before linearized reading' (duration: 196.727218ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:30.579238Z","caller":"traceutil/trace.go:171","msg":"trace[2058633852] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"107.344975ms","start":"2026-05-21T19:35:30.471869Z","end":"2026-05-21T19:35:30.579214Z","steps":["trace[2058633852] 'process raft request' (duration: 107.22053ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:35.847541Z","caller":"traceutil/trace.go:171","msg":"trace[367241423] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"109.23616ms","start":"2026-05-21T19:35:35.738276Z","end":"2026-05-21T19:35:35.847512Z","steps":["trace[367241423] 'process raft request' (duration: 104.815392ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:40.957257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2867} {"level":"info","ts":"2026-05-21T19:35:41.035961Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2867,"took":"77.283558ms","hash":690959142,"current-db-size-bytes":54755328,"current-db-size":"55 MB","current-db-size-in-use-bytes":29704192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-21T19:35:41.036018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":690959142,"revision":2867,"compact-revision":-1} {"level":"info","ts":"2026-05-21T19:35:43.145819Z","caller":"traceutil/trace.go:171","msg":"trace[1678347458] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"121.140207ms","start":"2026-05-21T19:35:43.024651Z","end":"2026-05-21T19:35:43.145791Z","steps":["trace[1678347458] 'process raft request' (duration: 120.928638ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:43.145977Z","caller":"traceutil/trace.go:171","msg":"trace[1840360470] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"121.291173ms","start":"2026-05-21T19:35:43.024652Z","end":"2026-05-21T19:35:43.145944Z","steps":["trace[1840360470] 'process raft request' (duration: 121.088715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:43.447707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.832745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:43.447805Z","caller":"traceutil/trace.go:171","msg":"trace[743743485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5578; }","duration":"148.975991ms","start":"2026-05-21T19:35:43.298805Z","end":"2026-05-21T19:35:43.447781Z","steps":["trace[743743485] 'range keys from in-memory index tree' (duration: 148.71426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:43.447973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.123719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:35:43.448132Z","caller":"traceutil/trace.go:171","msg":"trace[1388085021] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5578; }","duration":"160.332469ms","start":"2026-05-21T19:35:43.287782Z","end":"2026-05-21T19:35:43.448114Z","steps":["trace[1388085021] 'count revisions from in-memory index tree' (duration: 160.028536ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:21.508822Z","caller":"traceutil/trace.go:171","msg":"trace[1396369154] transaction","detail":"{read_only:false; response_revision:5825; number_of_response:1; }","duration":"159.852122ms","start":"2026-05-21T19:36:21.348932Z","end":"2026-05-21T19:36:21.508784Z","steps":["trace[1396369154] 'process raft request' (duration: 120.678319ms)","trace[1396369154] 'compare' (duration: 39.020216ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:40:40.963414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5566} {"level":"info","ts":"2026-05-21T19:40:41.043173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5566,"took":"78.123485ms","hash":4137185736,"current-db-size-bytes":54755328,"current-db-size":"55 MB","current-db-size-in-use-bytes":17125376,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-05-21T19:40:41.043254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4137185736,"revision":5566,"compact-revision":2867}