{"level":"warn","ts":"2026-05-19T18:21:51.018002Z","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-19T18:21:51.022216Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.195: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.195:2380","--initial-cluster=instance=https://199.19.213.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.195: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-19T18:21:51.022305Z","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-19T18:21:51.022321Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-05-19T18:21:51.022344Z","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-19T18:21:51.023169Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"]} {"level":"info","ts":"2026-05-19T18:21:51.023257Z","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.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.195: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-19T18:21:51.028599Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.062657ms"} {"level":"info","ts":"2026-05-19T18:21:51.038937Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2808ca5e53a916c4","cluster-id":"b7ef986abcd4d0b8"} {"level":"info","ts":"2026-05-19T18:21:51.039002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T18:21:51.039023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 0"} {"level":"info","ts":"2026-05-19T18:21:51.039034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2808ca5e53a916c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T18:21:51.039049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 1"} {"level":"info","ts":"2026-05-19T18:21:51.039085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"warn","ts":"2026-05-19T18:21:51.048229Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T18:21:51.051845Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T18:21:51.053238Z","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-19T18:21:51.055661Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2808ca5e53a916c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T18:21:51.055885Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2808ca5e53a916c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T18:21:51.056141Z","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-19T18:21:51.056288Z","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-19T18:21:51.05638Z","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-19T18:21:51.057174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"info","ts":"2026-05-19T18:21:51.057353Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","added-peer-id":"2808ca5e53a916c4","added-peer-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-05-19T18:21:51.058691Z","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-19T18:21:51.058914Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-05-19T18:21:51.058976Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-05-19T18:21:51.05917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2808ca5e53a916c4","initial-advertise-peer-urls":["https://199.19.213.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T18:21:51.059205Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T18:21:51.540157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T18:21:51.540238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T18:21:51.540288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgPreVoteResp from 2808ca5e53a916c4 at term 1"} {"level":"info","ts":"2026-05-19T18:21:51.540303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became candidate at term 2"} {"level":"info","ts":"2026-05-19T18:21:51.540315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgVoteResp from 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-05-19T18:21:51.540327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became leader at term 2"} {"level":"info","ts":"2026-05-19T18:21:51.54034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2808ca5e53a916c4 elected leader 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-05-19T18:21:51.542227Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2808ca5e53a916c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.195:2379]}","request-path":"/0/members/2808ca5e53a916c4/attributes","cluster-id":"b7ef986abcd4d0b8","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T18:21:51.542369Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:21:51.542443Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T18:21:51.54267Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:21:51.543965Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:21:51.544082Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:21:51.544118Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T18:21:51.544457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.195:2379"} {"level":"info","ts":"2026-05-19T18:21:51.545038Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T18:21:51.545136Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T18:21:51.547421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T18:21:56.645131Z","caller":"traceutil/trace.go:171","msg":"trace[1101911884] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:258; }","duration":"100.198903ms","start":"2026-05-19T18:21:56.544902Z","end":"2026-05-19T18:21:56.645101Z","steps":["trace[1101911884] 'read index received' (duration: 11.895017ms)","trace[1101911884] 'applied index is now lower than readState.Index' (duration: 88.302556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:21:56.64531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.384534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-19T18:21:56.645296Z","caller":"traceutil/trace.go:171","msg":"trace[813440436] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"119.286614ms","start":"2026-05-19T18:21:56.525978Z","end":"2026-05-19T18:21:56.645264Z","steps":["trace[813440436] 'process raft request' (duration: 73.577451ms)","trace[813440436] 'compare' (duration: 45.396402ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:21:56.645378Z","caller":"traceutil/trace.go:171","msg":"trace[608685181] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:252; }","duration":"100.502184ms","start":"2026-05-19T18:21:56.544864Z","end":"2026-05-19T18:21:56.645366Z","steps":["trace[608685181] 'agreement among raft nodes before linearized reading' (duration: 100.359224ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.377637Z","caller":"traceutil/trace.go:171","msg":"trace[60968335] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"231.1502ms","start":"2026-05-19T18:22:22.146468Z","end":"2026-05-19T18:22:22.377618Z","steps":["trace[60968335] 'process raft request' (duration: 231.04518ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387455Z","caller":"traceutil/trace.go:171","msg":"trace[316187694] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"234.315247ms","start":"2026-05-19T18:22:22.153113Z","end":"2026-05-19T18:22:22.387428Z","steps":["trace[316187694] 'process raft request' (duration: 234.178237ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387553Z","caller":"traceutil/trace.go:171","msg":"trace[469528516] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"225.941975ms","start":"2026-05-19T18:22:22.161605Z","end":"2026-05-19T18:22:22.387547Z","steps":["trace[469528516] 'process raft request' (duration: 225.825405ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387634Z","caller":"traceutil/trace.go:171","msg":"trace[2110746504] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"228.028771ms","start":"2026-05-19T18:22:22.159585Z","end":"2026-05-19T18:22:22.387613Z","steps":["trace[2110746504] 'process raft request' (duration: 227.80037ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387678Z","caller":"traceutil/trace.go:171","msg":"trace[1795771390] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"224.256122ms","start":"2026-05-19T18:22:22.163394Z","end":"2026-05-19T18:22:22.38765Z","steps":["trace[1795771390] 'process raft request' (duration: 224.114691ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387466Z","caller":"traceutil/trace.go:171","msg":"trace[1998411256] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"234.351217ms","start":"2026-05-19T18:22:22.153042Z","end":"2026-05-19T18:22:22.387393Z","steps":["trace[1998411256] 'process raft request' (duration: 234.160177ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387788Z","caller":"traceutil/trace.go:171","msg":"trace[1984915954] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"210.530898ms","start":"2026-05-19T18:22:22.17725Z","end":"2026-05-19T18:22:22.387781Z","steps":["trace[1984915954] 'process raft request' (duration: 210.320876ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387838Z","caller":"traceutil/trace.go:171","msg":"trace[292220925] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"195.538879ms","start":"2026-05-19T18:22:22.192287Z","end":"2026-05-19T18:22:22.387826Z","steps":["trace[292220925] 'process raft request' (duration: 195.339798ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387854Z","caller":"traceutil/trace.go:171","msg":"trace[264532721] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"217.076653ms","start":"2026-05-19T18:22:22.170604Z","end":"2026-05-19T18:22:22.387681Z","steps":["trace[264532721] 'process raft request' (duration: 216.935732ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387873Z","caller":"traceutil/trace.go:171","msg":"trace[1531809044] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"224.923874ms","start":"2026-05-19T18:22:22.16294Z","end":"2026-05-19T18:22:22.387864Z","steps":["trace[1531809044] 'process raft request' (duration: 224.529582ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:22.387488Z","caller":"traceutil/trace.go:171","msg":"trace[2076279301] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"228.621482ms","start":"2026-05-19T18:22:22.158832Z","end":"2026-05-19T18:22:22.387453Z","steps":["trace[2076279301] 'process raft request' (duration: 228.504732ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:29.285597Z","caller":"traceutil/trace.go:171","msg":"trace[176506048] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"147.991544ms","start":"2026-05-19T18:22:29.13756Z","end":"2026-05-19T18:22:29.285552Z","steps":["trace[176506048] 'read index received' (duration: 147.202853ms)","trace[176506048] 'applied index is now lower than readState.Index' (duration: 787.351µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:22:29.285633Z","caller":"traceutil/trace.go:171","msg":"trace[583942297] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"364.167727ms","start":"2026-05-19T18:22:28.921432Z","end":"2026-05-19T18:22:29.285599Z","steps":["trace[583942297] 'process raft request' (duration: 363.511706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:29.285808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.240565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:29.285861Z","caller":"traceutil/trace.go:171","msg":"trace[1844280239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"148.347365ms","start":"2026-05-19T18:22:29.137498Z","end":"2026-05-19T18:22:29.285846Z","steps":["trace[1844280239] 'agreement among raft nodes before linearized reading' (duration: 148.227274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:29.286037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.892345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T18:22:29.286123Z","caller":"traceutil/trace.go:171","msg":"trace[1390962824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"128.040805ms","start":"2026-05-19T18:22:29.158066Z","end":"2026-05-19T18:22:29.286106Z","steps":["trace[1390962824] 'agreement among raft nodes before linearized reading' (duration: 127.831695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:29.28619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.072185ms","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-19T18:22:29.286278Z","caller":"traceutil/trace.go:171","msg":"trace[1557277696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"104.205557ms","start":"2026-05-19T18:22:29.18205Z","end":"2026-05-19T18:22:29.286255Z","steps":["trace[1557277696] 'agreement among raft nodes before linearized reading' (duration: 104.001236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:29.286444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:28.921405Z","time spent":"364.305127ms","remote":"127.0.0.1:51406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:22:29.534868Z","caller":"traceutil/trace.go:171","msg":"trace[1934131193] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"100.873218ms","start":"2026-05-19T18:22:29.433971Z","end":"2026-05-19T18:22:29.534844Z","steps":["trace[1934131193] 'read index received' (duration: 100.742128ms)","trace[1934131193] 'applied index is now lower than readState.Index' (duration: 129.98µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:22:29.534904Z","caller":"traceutil/trace.go:171","msg":"trace[1218862847] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"192.255883ms","start":"2026-05-19T18:22:29.342613Z","end":"2026-05-19T18:22:29.534869Z","steps":["trace[1218862847] 'process raft request' (duration: 192.089133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:29.535282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.291629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wm6ww\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T18:22:29.535409Z","caller":"traceutil/trace.go:171","msg":"trace[2137718545] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wm6ww; range_end:; response_count:1; response_revision:583; }","duration":"101.46089ms","start":"2026-05-19T18:22:29.433929Z","end":"2026-05-19T18:22:29.53539Z","steps":["trace[2137718545] 'agreement among raft nodes before linearized reading' (duration: 101.022559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.090591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640610168310219743,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:22:30.777377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.31929Z","time spent":"458.081821ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T18:22:30.777806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.458651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:22:30.778039Z","caller":"traceutil/trace.go:171","msg":"trace[1207872029] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.226133277s","start":"2026-05-19T18:22:29.551881Z","end":"2026-05-19T18:22:30.778014Z","steps":["trace[1207872029] 'process raft request' (duration: 776.277945ms)","trace[1207872029] 'compare' (duration: 449.36695ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:22:30.77816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:29.551861Z","time spent":"1.226227919s","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:22:30.785275Z","caller":"traceutil/trace.go:171","msg":"trace[323167611] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"1.195268002s","start":"2026-05-19T18:22:29.589984Z","end":"2026-05-19T18:22:30.785252Z","steps":["trace[323167611] 'read index received' (duration: 738.168372ms)","trace[323167611] 'applied index is now lower than readState.Index' (duration: 457.09825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:22:30.785436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195435512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.785526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.185649ms","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-19T18:22:30.785528Z","caller":"traceutil/trace.go:171","msg":"trace[1823829438] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:585; }","duration":"1.195556463s","start":"2026-05-19T18:22:29.589941Z","end":"2026-05-19T18:22:30.785498Z","steps":["trace[1823829438] 'agreement among raft nodes before linearized reading' (duration: 1.195424753s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.785571Z","caller":"traceutil/trace.go:171","msg":"trace[646103380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"722.281129ms","start":"2026-05-19T18:22:30.063279Z","end":"2026-05-19T18:22:30.78556Z","steps":["trace[646103380] 'agreement among raft nodes before linearized reading' (duration: 722.118489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.785603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:29.589925Z","time spent":"1.195659493s","remote":"127.0.0.1:51440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-19T18:22:30.785661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.595837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.785695Z","caller":"traceutil/trace.go:171","msg":"trace[1405009064] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"264.638847ms","start":"2026-05-19T18:22:30.521048Z","end":"2026-05-19T18:22:30.785687Z","steps":["trace[1405009064] 'agreement among raft nodes before linearized reading' (duration: 264.587137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.785616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.063259Z","time spent":"722.347719ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T18:22:30.785813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.827344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.785805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.215091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.785938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.350362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.785918Z","caller":"traceutil/trace.go:171","msg":"trace[1859854009] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:585; }","duration":"115.961624ms","start":"2026-05-19T18:22:30.669939Z","end":"2026-05-19T18:22:30.7859Z","steps":["trace[1859854009] 'agreement among raft nodes before linearized reading' (duration: 115.789533ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.785976Z","caller":"traceutil/trace.go:171","msg":"trace[218672131] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"454.409902ms","start":"2026-05-19T18:22:30.331558Z","end":"2026-05-19T18:22:30.785968Z","steps":["trace[218672131] 'agreement among raft nodes before linearized reading' (duration: 454.348762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.785971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.006761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.786006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.331546Z","time spent":"454.451883ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T18:22:30.786041Z","caller":"traceutil/trace.go:171","msg":"trace[239033191] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"307.102931ms","start":"2026-05-19T18:22:30.478914Z","end":"2026-05-19T18:22:30.786017Z","steps":["trace[239033191] 'agreement among raft nodes before linearized reading' (duration: 307.027521ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.786005Z","caller":"traceutil/trace.go:171","msg":"trace[1362530354] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:585; }","duration":"286.399471ms","start":"2026-05-19T18:22:30.499555Z","end":"2026-05-19T18:22:30.785954Z","steps":["trace[1362530354] 'agreement among raft nodes before linearized reading' (duration: 286.218171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.786101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.47889Z","time spent":"307.195541ms","remote":"127.0.0.1:52432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:22:30.786141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.521323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.786188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.775557ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.786191Z","caller":"traceutil/trace.go:171","msg":"trace[1393830812] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"307.588123ms","start":"2026-05-19T18:22:30.47859Z","end":"2026-05-19T18:22:30.786179Z","steps":["trace[1393830812] 'agreement among raft nodes before linearized reading' (duration: 307.516222ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.786217Z","caller":"traceutil/trace.go:171","msg":"trace[739126131] 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:585; }","duration":"488.838077ms","start":"2026-05-19T18:22:30.297371Z","end":"2026-05-19T18:22:30.786209Z","steps":["trace[739126131] 'agreement among raft nodes before linearized reading' (duration: 488.798227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.786227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.478582Z","time spent":"307.635833ms","remote":"127.0.0.1:52432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T18:22:30.786243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.297349Z","time spent":"488.888958ms","remote":"127.0.0.1:52386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T18:22:30.786153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.546747ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.786315Z","caller":"traceutil/trace.go:171","msg":"trace[130427903] 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:585; }","duration":"488.724618ms","start":"2026-05-19T18:22:30.297581Z","end":"2026-05-19T18:22:30.786306Z","steps":["trace[130427903] 'agreement among raft nodes before linearized reading' (duration: 488.552777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.786353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.297574Z","time spent":"488.768628ms","remote":"127.0.0.1:52386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-19T18:22:30.785917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.256322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.786391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.014398ms","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-19T18:22:30.786419Z","caller":"traceutil/trace.go:171","msg":"trace[917838193] 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:585; }","duration":"489.068348ms","start":"2026-05-19T18:22:30.297344Z","end":"2026-05-19T18:22:30.786412Z","steps":["trace[917838193] 'agreement among raft nodes before linearized reading' (duration: 488.990108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.786445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.297328Z","time spent":"489.110718ms","remote":"127.0.0.1:52386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:22:30.786455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.128394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.786413Z","caller":"traceutil/trace.go:171","msg":"trace[461053396] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"454.799674ms","start":"2026-05-19T18:22:30.331598Z","end":"2026-05-19T18:22:30.786398Z","steps":["trace[461053396] 'agreement among raft nodes before linearized reading' (duration: 454.199002ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.786547Z","caller":"traceutil/trace.go:171","msg":"trace[336484853] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:585; }","duration":"324.226754ms","start":"2026-05-19T18:22:30.462272Z","end":"2026-05-19T18:22:30.786498Z","steps":["trace[336484853] 'agreement among raft nodes before linearized reading' (duration: 324.111454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.786567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.286724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.78654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.49711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.786602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.462265Z","time spent":"324.321384ms","remote":"127.0.0.1:52420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-19T18:22:30.786627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.814732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.786631Z","caller":"traceutil/trace.go:171","msg":"trace[1470265276] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"265.60263ms","start":"2026-05-19T18:22:30.52102Z","end":"2026-05-19T18:22:30.786622Z","steps":["trace[1470265276] 'agreement among raft nodes before linearized reading' (duration: 265.4371ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.78665Z","caller":"traceutil/trace.go:171","msg":"trace[1589804363] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"425.861252ms","start":"2026-05-19T18:22:30.360784Z","end":"2026-05-19T18:22:30.786645Z","steps":["trace[1589804363] 'agreement among raft nodes before linearized reading' (duration: 425.826412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.786724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.360775Z","time spent":"425.942883ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-19T18:22:30.786723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.086114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.786755Z","caller":"traceutil/trace.go:171","msg":"trace[1527169430] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:585; }","duration":"287.143234ms","start":"2026-05-19T18:22:30.499603Z","end":"2026-05-19T18:22:30.786746Z","steps":["trace[1527169430] 'agreement among raft nodes before linearized reading' (duration: 287.093103ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.786597Z","caller":"traceutil/trace.go:171","msg":"trace[699192203] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"426.375675ms","start":"2026-05-19T18:22:30.360215Z","end":"2026-05-19T18:22:30.786591Z","steps":["trace[699192203] 'agreement among raft nodes before linearized reading' (duration: 426.283865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.786823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.3602Z","time spent":"426.616756ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:22:30.786868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.921553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.786876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.470647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.786892Z","caller":"traceutil/trace.go:171","msg":"trace[99413341] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:585; }","duration":"286.959883ms","start":"2026-05-19T18:22:30.499926Z","end":"2026-05-19T18:22:30.786886Z","steps":["trace[99413341] 'agreement among raft nodes before linearized reading' (duration: 286.922793ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.786916Z","caller":"traceutil/trace.go:171","msg":"trace[1123830112] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"190.520457ms","start":"2026-05-19T18:22:30.596383Z","end":"2026-05-19T18:22:30.786904Z","steps":["trace[1123830112] 'agreement among raft nodes before linearized reading' (duration: 190.460687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.786572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.331585Z","time spent":"454.976624ms","remote":"127.0.0.1:52402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T18:22:30.787005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.874051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.787053Z","caller":"traceutil/trace.go:171","msg":"trace[364960396] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:585; }","duration":"115.015261ms","start":"2026-05-19T18:22:30.672026Z","end":"2026-05-19T18:22:30.787041Z","steps":["trace[364960396] 'agreement among raft nodes before linearized reading' (duration: 114.870881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.043166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.78711Z","caller":"traceutil/trace.go:171","msg":"trace[1514814293] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"427.073316ms","start":"2026-05-19T18:22:30.360031Z","end":"2026-05-19T18:22:30.787104Z","steps":["trace[1514814293] 'agreement among raft nodes before linearized reading' (duration: 427.031646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.444978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.787135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.360014Z","time spent":"427.115106ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-19T18:22:30.78715Z","caller":"traceutil/trace.go:171","msg":"trace[600440976] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:585; }","duration":"142.482578ms","start":"2026-05-19T18:22:30.644657Z","end":"2026-05-19T18:22:30.787139Z","steps":["trace[600440976] 'agreement among raft nodes before linearized reading' (duration: 142.442798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.522476ms","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":"warn","ts":"2026-05-19T18:22:30.787229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.205298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.785838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.672462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:22:30.785914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.60828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.787241Z","caller":"traceutil/trace.go:171","msg":"trace[1216465788] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"190.608447ms","start":"2026-05-19T18:22:30.596617Z","end":"2026-05-19T18:22:30.787226Z","steps":["trace[1216465788] 'agreement among raft nodes before linearized reading' (duration: 190.516646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.78571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.059586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.787321Z","caller":"traceutil/trace.go:171","msg":"trace[1468910153] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"265.794961ms","start":"2026-05-19T18:22:30.521516Z","end":"2026-05-19T18:22:30.787311Z","steps":["trace[1468910153] 'agreement among raft nodes before linearized reading' (duration: 263.953706ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.787276Z","caller":"traceutil/trace.go:171","msg":"trace[735812761] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"307.981234ms","start":"2026-05-19T18:22:30.479286Z","end":"2026-05-19T18:22:30.787267Z","steps":["trace[735812761] 'agreement among raft nodes before linearized reading' (duration: 306.59597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.479282Z","time spent":"308.263404ms","remote":"127.0.0.1:52432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-19T18:22:30.787079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.878077ms","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-19T18:22:30.787605Z","caller":"traceutil/trace.go:171","msg":"trace[1555129888] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:585; }","duration":"142.409518ms","start":"2026-05-19T18:22:30.645184Z","end":"2026-05-19T18:22:30.787594Z","steps":["trace[1555129888] 'agreement among raft nodes before linearized reading' (duration: 141.868377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.7864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.033023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.787698Z","caller":"traceutil/trace.go:171","msg":"trace[2052345446] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:585; }","duration":"325.693508ms","start":"2026-05-19T18:22:30.461991Z","end":"2026-05-19T18:22:30.787685Z","steps":["trace[2052345446] 'agreement among raft nodes before linearized reading' (duration: 324.034513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.461971Z","time spent":"325.751408ms","remote":"127.0.0.1:52420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T18:22:30.787322Z","caller":"traceutil/trace.go:171","msg":"trace[1374495682] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:585; }","duration":"325.193026ms","start":"2026-05-19T18:22:30.462099Z","end":"2026-05-19T18:22:30.787292Z","steps":["trace[1374495682] 'agreement among raft nodes before linearized reading' (duration: 323.658492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:22:30.462073Z","time spent":"325.738467ms","remote":"127.0.0.1:52420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-19T18:22:30.787197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.63525ms","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-19T18:22:30.78791Z","caller":"traceutil/trace.go:171","msg":"trace[963182115] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:585; }","duration":"143.364501ms","start":"2026-05-19T18:22:30.644536Z","end":"2026-05-19T18:22:30.7879Z","steps":["trace[963182115] 'agreement among raft nodes before linearized reading' (duration: 142.638749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.78733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.12925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.787999Z","caller":"traceutil/trace.go:171","msg":"trace[23521760] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:585; }","duration":"212.806261ms","start":"2026-05-19T18:22:30.575186Z","end":"2026-05-19T18:22:30.787992Z","steps":["trace[23521760] 'agreement among raft nodes before linearized reading' (duration: 212.12727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.984628ms","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-19T18:22:30.788146Z","caller":"traceutil/trace.go:171","msg":"trace[2125235600] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"191.78962ms","start":"2026-05-19T18:22:30.596351Z","end":"2026-05-19T18:22:30.788141Z","steps":["trace[2125235600] 'agreement among raft nodes before linearized reading' (duration: 190.993998ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:30.787406Z","caller":"traceutil/trace.go:171","msg":"trace[1445318347] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:585; }","duration":"117.414018ms","start":"2026-05-19T18:22:30.66998Z","end":"2026-05-19T18:22:30.787394Z","steps":["trace[1445318347] 'agreement among raft nodes before linearized reading' (duration: 117.187027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.781381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.788291Z","caller":"traceutil/trace.go:171","msg":"trace[660332908] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:585; }","duration":"213.698283ms","start":"2026-05-19T18:22:30.574584Z","end":"2026-05-19T18:22:30.788282Z","steps":["trace[660332908] 'agreement among raft nodes before linearized reading' (duration: 212.794361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:22:30.787433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.880621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:22:30.788401Z","caller":"traceutil/trace.go:171","msg":"trace[489287138] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:585; }","duration":"213.870193ms","start":"2026-05-19T18:22:30.574525Z","end":"2026-05-19T18:22:30.788395Z","steps":["trace[489287138] 'agreement among raft nodes before linearized reading' (duration: 212.883301ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:22:47.03242Z","caller":"traceutil/trace.go:171","msg":"trace[2117600231] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"153.969522ms","start":"2026-05-19T18:22:46.878428Z","end":"2026-05-19T18:22:47.032397Z","steps":["trace[2117600231] 'process raft request' (duration: 153.811323ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:23:15.772556Z","caller":"traceutil/trace.go:171","msg":"trace[1920899361] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"181.782645ms","start":"2026-05-19T18:23:15.590747Z","end":"2026-05-19T18:23:15.77253Z","steps":["trace[1920899361] 'process raft request' (duration: 181.64287ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:23:16.033831Z","caller":"traceutil/trace.go:171","msg":"trace[858100368] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"442.030933ms","start":"2026-05-19T18:23:15.591777Z","end":"2026-05-19T18:23:16.033808Z","steps":["trace[858100368] 'process raft request' (duration: 440.573725ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:23:16.033894Z","caller":"traceutil/trace.go:171","msg":"trace[1043484992] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"306.740997ms","start":"2026-05-19T18:23:15.727105Z","end":"2026-05-19T18:23:16.033846Z","steps":["trace[1043484992] 'process raft request' (duration: 306.635433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:23:16.034013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.342238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T18:23:16.034073Z","caller":"traceutil/trace.go:171","msg":"trace[1123637186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1146; }","duration":"168.445932ms","start":"2026-05-19T18:23:15.865613Z","end":"2026-05-19T18:23:16.034059Z","steps":["trace[1123637186] 'agreement among raft nodes before linearized reading' (duration: 168.279165ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:23:16.033833Z","caller":"traceutil/trace.go:171","msg":"trace[1686886845] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1177; }","duration":"168.124999ms","start":"2026-05-19T18:23:15.865669Z","end":"2026-05-19T18:23:16.033794Z","steps":["trace[1686886845] 'read index received' (duration: 166.613449ms)","trace[1686886845] 'applied index is now lower than readState.Index' (duration: 1.5105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:23:16.034199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:23:15.591754Z","time spent":"442.175519ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:23:16.034349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:23:15.727078Z","time spent":"306.900695ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:23:40.919739Z","caller":"traceutil/trace.go:171","msg":"trace[1485151639] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"118.469562ms","start":"2026-05-19T18:23:40.80121Z","end":"2026-05-19T18:23:40.919679Z","steps":["trace[1485151639] 'process raft request' (duration: 117.936115ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:23:41.08719Z","caller":"traceutil/trace.go:171","msg":"trace[1485530627] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"158.720816ms","start":"2026-05-19T18:23:40.928428Z","end":"2026-05-19T18:23:41.087149Z","steps":["trace[1485530627] 'process raft request' (duration: 78.359431ms)","trace[1485530627] 'compare' (duration: 80.215871ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:23:41.606874Z","caller":"traceutil/trace.go:171","msg":"trace[1787578537] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"112.493113ms","start":"2026-05-19T18:23:41.494347Z","end":"2026-05-19T18:23:41.60684Z","steps":["trace[1787578537] 'process raft request' (duration: 92.515658ms)","trace[1787578537] 'compare' (duration: 19.80701ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:23:41.610402Z","caller":"traceutil/trace.go:171","msg":"trace[1060573586] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"113.138623ms","start":"2026-05-19T18:23:41.497242Z","end":"2026-05-19T18:23:41.610381Z","steps":["trace[1060573586] 'process raft request' (duration: 112.955887ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:05.830719Z","caller":"traceutil/trace.go:171","msg":"trace[934030093] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"119.303596ms","start":"2026-05-19T18:24:05.711397Z","end":"2026-05-19T18:24:05.8307Z","steps":["trace[934030093] 'read index received' (duration: 119.080621ms)","trace[934030093] 'applied index is now lower than readState.Index' (duration: 222.295µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:24:05.830796Z","caller":"traceutil/trace.go:171","msg":"trace[780853459] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"219.322025ms","start":"2026-05-19T18:24:05.611446Z","end":"2026-05-19T18:24:05.830768Z","steps":["trace[780853459] 'process raft request' (duration: 219.133001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:05.830912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.509152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T18:24:05.830951Z","caller":"traceutil/trace.go:171","msg":"trace[1778980455] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1833; }","duration":"119.586444ms","start":"2026-05-19T18:24:05.711349Z","end":"2026-05-19T18:24:05.830935Z","steps":["trace[1778980455] 'agreement among raft nodes before linearized reading' (duration: 119.445951ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:06.416863Z","caller":"traceutil/trace.go:171","msg":"trace[818381709] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"215.649981ms","start":"2026-05-19T18:24:06.201187Z","end":"2026-05-19T18:24:06.416837Z","steps":["trace[818381709] 'process raft request' (duration: 207.098545ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:06.421974Z","caller":"traceutil/trace.go:171","msg":"trace[1628561308] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"155.839599ms","start":"2026-05-19T18:24:06.266124Z","end":"2026-05-19T18:24:06.421963Z","steps":["trace[1628561308] 'process raft request' (duration: 155.576853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:19.297727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.127678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:24:19.297828Z","caller":"traceutil/trace.go:171","msg":"trace[72548395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1944; }","duration":"167.314581ms","start":"2026-05-19T18:24:19.13049Z","end":"2026-05-19T18:24:19.297805Z","steps":["trace[72548395] 'range keys from in-memory index tree' (duration: 167.006225ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:22.620605Z","caller":"traceutil/trace.go:171","msg":"trace[1417343599] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"143.624833ms","start":"2026-05-19T18:24:22.47696Z","end":"2026-05-19T18:24:22.620585Z","steps":["trace[1417343599] 'process raft request' (duration: 143.48462ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:22.622555Z","caller":"traceutil/trace.go:171","msg":"trace[474247710] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2042; }","duration":"115.903225ms","start":"2026-05-19T18:24:22.50663Z","end":"2026-05-19T18:24:22.622534Z","steps":["trace[474247710] 'read index received' (duration: 113.845074ms)","trace[474247710] 'applied index is now lower than readState.Index' (duration: 2.057361ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:24:22.622649Z","caller":"traceutil/trace.go:171","msg":"trace[1277675049] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"145.681604ms","start":"2026-05-19T18:24:22.47696Z","end":"2026-05-19T18:24:22.622642Z","steps":["trace[1277675049] 'process raft request' (duration: 145.47083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:22.622798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.350014ms","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-19T18:24:22.62284Z","caller":"traceutil/trace.go:171","msg":"trace[1714731177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1971; }","duration":"113.400525ms","start":"2026-05-19T18:24:22.509429Z","end":"2026-05-19T18:24:22.62283Z","steps":["trace[1714731177] 'agreement among raft nodes before linearized reading' (duration: 113.283752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:22.622946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.325903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T18:24:22.622966Z","caller":"traceutil/trace.go:171","msg":"trace[1143815093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1971; }","duration":"116.379384ms","start":"2026-05-19T18:24:22.506582Z","end":"2026-05-19T18:24:22.622961Z","steps":["trace[1143815093] 'agreement among raft nodes before linearized reading' (duration: 116.323623ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:46.225509Z","caller":"traceutil/trace.go:171","msg":"trace[1907630839] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2180; }","duration":"118.516096ms","start":"2026-05-19T18:24:46.106939Z","end":"2026-05-19T18:24:46.225455Z","steps":["trace[1907630839] 'read index received' (duration: 118.304782ms)","trace[1907630839] 'applied index is now lower than readState.Index' (duration: 210.294µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:24:46.22557Z","caller":"traceutil/trace.go:171","msg":"trace[1025299936] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"160.608493ms","start":"2026-05-19T18:24:46.06493Z","end":"2026-05-19T18:24:46.225539Z","steps":["trace[1025299936] 'process raft request' (duration: 160.389729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:46.225774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.82343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T18:24:46.225831Z","caller":"traceutil/trace.go:171","msg":"trace[1230662385] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"118.935122ms","start":"2026-05-19T18:24:46.106879Z","end":"2026-05-19T18:24:46.225814Z","steps":["trace[1230662385] 'agreement among raft nodes before linearized reading' (duration: 118.753699ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:46.94166Z","caller":"traceutil/trace.go:171","msg":"trace[2125927279] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2182; }","duration":"216.131568ms","start":"2026-05-19T18:24:46.725509Z","end":"2026-05-19T18:24:46.94164Z","steps":["trace[2125927279] 'read index received' (duration: 216.096367ms)","trace[2125927279] 'applied index is now lower than readState.Index' (duration: 34.331µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:24:46.941751Z","caller":"traceutil/trace.go:171","msg":"trace[443637425] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"216.388242ms","start":"2026-05-19T18:24:46.725337Z","end":"2026-05-19T18:24:46.941726Z","steps":["trace[443637425] 'process raft request' (duration: 216.163928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:46.941916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.318531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:24:46.941981Z","caller":"traceutil/trace.go:171","msg":"trace[1136169595] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2105; }","duration":"216.493804ms","start":"2026-05-19T18:24:46.725469Z","end":"2026-05-19T18:24:46.941963Z","steps":["trace[1136169595] 'agreement among raft nodes before linearized reading' (duration: 216.28092ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:47.394014Z","caller":"traceutil/trace.go:171","msg":"trace[1426277219] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"663.612778ms","start":"2026-05-19T18:24:46.730378Z","end":"2026-05-19T18:24:47.393991Z","steps":["trace[1426277219] 'process raft request' (duration: 600.277261ms)","trace[1426277219] 'compare' (duration: 63.008432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:24:47.394156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:46.730369Z","time spent":"663.723709ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:24:47.394737Z","caller":"traceutil/trace.go:171","msg":"trace[1683935168] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"650.335351ms","start":"2026-05-19T18:24:46.744345Z","end":"2026-05-19T18:24:47.394681Z","steps":["trace[1683935168] 'process raft request' (duration: 649.579499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:47.394935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.246805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-19T18:24:47.394955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.992098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T18:24:47.394995Z","caller":"traceutil/trace.go:171","msg":"trace[279326187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2108; }","duration":"613.361687ms","start":"2026-05-19T18:24:46.781619Z","end":"2026-05-19T18:24:47.394981Z","steps":["trace[279326187] 'agreement among raft nodes before linearized reading' (duration: 613.123014ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:47.395007Z","caller":"traceutil/trace.go:171","msg":"trace[1781843159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2108; }","duration":"652.085409ms","start":"2026-05-19T18:24:46.74291Z","end":"2026-05-19T18:24:47.394995Z","steps":["trace[1781843159] 'agreement among raft nodes before linearized reading' (duration: 651.902676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:47.395032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:46.781608Z","time spent":"613.415828ms","remote":"127.0.0.1:51592","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-19T18:24:47.395041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:46.742898Z","time spent":"652.13523ms","remote":"127.0.0.1:51592","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-19T18:24:47.394755Z","caller":"traceutil/trace.go:171","msg":"trace[198736740] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2183; }","duration":"452.975819ms","start":"2026-05-19T18:24:46.941744Z","end":"2026-05-19T18:24:47.39472Z","steps":["trace[198736740] 'read index received' (duration: 389.001472ms)","trace[198736740] 'applied index is now lower than readState.Index' (duration: 63.973327ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:24:47.395063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.25158ms","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-19T18:24:47.394778Z","caller":"traceutil/trace.go:171","msg":"trace[429005351] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"541.402712ms","start":"2026-05-19T18:24:46.853343Z","end":"2026-05-19T18:24:47.394745Z","steps":["trace[429005351] 'process raft request' (duration: 541.231449ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:47.395098Z","caller":"traceutil/trace.go:171","msg":"trace[629931134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2108; }","duration":"647.347861ms","start":"2026-05-19T18:24:46.74774Z","end":"2026-05-19T18:24:47.395088Z","steps":["trace[629931134] 'agreement among raft nodes before linearized reading' (duration: 647.25444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:47.395126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:46.747727Z","time spent":"647.391793ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T18:24:47.395159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:46.853319Z","time spent":"541.800139ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:24:47.395486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:46.744327Z","time spent":"650.635995ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:24:47.396891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.104604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:24:47.396958Z","caller":"traceutil/trace.go:171","msg":"trace[117855346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2109; }","duration":"266.200075ms","start":"2026-05-19T18:24:47.130742Z","end":"2026-05-19T18:24:47.396942Z","steps":["trace[117855346] 'agreement among raft nodes before linearized reading' (duration: 266.082923ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:24:47.39717Z","caller":"traceutil/trace.go:171","msg":"trace[1813078262] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"323.577765ms","start":"2026-05-19T18:24:47.073579Z","end":"2026-05-19T18:24:47.397157Z","steps":["trace[1813078262] 'process raft request' (duration: 323.137028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:47.397239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:47.07355Z","time spent":"323.652006ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:24:47.397492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.097302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:24:47.397574Z","caller":"traceutil/trace.go:171","msg":"trace[2078493007] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2109; }","duration":"166.248724ms","start":"2026-05-19T18:24:47.231305Z","end":"2026-05-19T18:24:47.397554Z","steps":["trace[2078493007] 'agreement among raft nodes before linearized reading' (duration: 165.98423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:47.397579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.332999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T18:24:47.397631Z","caller":"traceutil/trace.go:171","msg":"trace[1341062731] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2109; }","duration":"165.422151ms","start":"2026-05-19T18:24:47.232194Z","end":"2026-05-19T18:24:47.397616Z","steps":["trace[1341062731] 'agreement among raft nodes before linearized reading' (duration: 165.250169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:47.816187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.345676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:24:47.816451Z","caller":"traceutil/trace.go:171","msg":"trace[1287331896] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"416.158265ms","start":"2026-05-19T18:24:47.400255Z","end":"2026-05-19T18:24:47.816414Z","steps":["trace[1287331896] 'process raft request' (duration: 104.231429ms)","trace[1287331896] 'compare' (duration: 311.030541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:24:47.8167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:47.400238Z","time spent":"416.384708ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:24:47.816654Z","caller":"traceutil/trace.go:171","msg":"trace[886450429] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"413.474222ms","start":"2026-05-19T18:24:47.40316Z","end":"2026-05-19T18:24:47.816634Z","steps":["trace[886450429] 'process raft request' (duration: 413.155637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:47.817001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:47.40314Z","time spent":"413.696025ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:24:47.817251Z","caller":"traceutil/trace.go:171","msg":"trace[2018053782] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"413.620314ms","start":"2026-05-19T18:24:47.403598Z","end":"2026-05-19T18:24:47.817218Z","steps":["trace[2018053782] 'process raft request' (duration: 412.936593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:24:47.819019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:24:47.40359Z","time spent":"415.333791ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:25:36.607476Z","caller":"traceutil/trace.go:171","msg":"trace[1420657700] transaction","detail":"{read_only:false; response_revision:2480; number_of_response:1; }","duration":"410.882195ms","start":"2026-05-19T18:25:36.19655Z","end":"2026-05-19T18:25:36.607432Z","steps":["trace[1420657700] 'process raft request' (duration: 410.654661ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:25:36.607355Z","caller":"traceutil/trace.go:171","msg":"trace[972346072] linearizableReadLoop","detail":"{readStateIndex:2570; appliedIndex:2569; }","duration":"154.104642ms","start":"2026-05-19T18:25:36.453228Z","end":"2026-05-19T18:25:36.607333Z","steps":["trace[972346072] 'read index received' (duration: 153.894018ms)","trace[972346072] 'applied index is now lower than readState.Index' (duration: 210.054µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:25:36.607675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.448946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T18:25:36.607676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:25:36.196521Z","time spent":"411.050576ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:25:36.60772Z","caller":"traceutil/trace.go:171","msg":"trace[1792075252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2480; }","duration":"154.547937ms","start":"2026-05-19T18:25:36.45316Z","end":"2026-05-19T18:25:36.607708Z","steps":["trace[1792075252] 'agreement among raft nodes before linearized reading' (duration: 154.408115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:25:36.608497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.481353ms","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-19T18:25:36.608534Z","caller":"traceutil/trace.go:171","msg":"trace[776068734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2480; }","duration":"135.583535ms","start":"2026-05-19T18:25:36.472942Z","end":"2026-05-19T18:25:36.608526Z","steps":["trace[776068734] 'agreement among raft nodes before linearized reading' (duration: 135.423433ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:26:09.33237Z","caller":"traceutil/trace.go:171","msg":"trace[1391161235] transaction","detail":"{read_only:false; response_revision:2660; number_of_response:1; }","duration":"100.498745ms","start":"2026-05-19T18:26:09.231817Z","end":"2026-05-19T18:26:09.332316Z","steps":["trace[1391161235] 'process raft request' (duration: 100.374974ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:27:14.106633Z","caller":"traceutil/trace.go:171","msg":"trace[375297957] transaction","detail":"{read_only:false; response_revision:3001; number_of_response:1; }","duration":"146.182708ms","start":"2026-05-19T18:27:13.960413Z","end":"2026-05-19T18:27:14.106596Z","steps":["trace[375297957] 'process raft request' (duration: 120.84091ms)","trace[375297957] 'compare' (duration: 25.133372ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:27:14.106696Z","caller":"traceutil/trace.go:171","msg":"trace[559199660] transaction","detail":"{read_only:false; response_revision:3002; number_of_response:1; }","duration":"144.780334ms","start":"2026-05-19T18:27:13.961875Z","end":"2026-05-19T18:27:14.106656Z","steps":["trace[559199660] 'process raft request' (duration: 144.611699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:27:14.442741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.53768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b10ac06ad4c1b6\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-19T18:27:14.442825Z","caller":"traceutil/trace.go:171","msg":"trace[1006811993] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b10ac06ad4c1b6; range_end:; response_count:1; response_revision:3003; }","duration":"110.656594ms","start":"2026-05-19T18:27:14.332143Z","end":"2026-05-19T18:27:14.442799Z","steps":["trace[1006811993] 'range keys from in-memory index tree' (duration: 110.365605ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:27.308072Z","caller":"traceutil/trace.go:171","msg":"trace[1720253154] transaction","detail":"{read_only:false; response_revision:3476; number_of_response:1; }","duration":"200.673165ms","start":"2026-05-19T18:28:27.107365Z","end":"2026-05-19T18:28:27.308038Z","steps":["trace[1720253154] 'process raft request' (duration: 132.640404ms)","trace[1720253154] 'compare' (duration: 67.822156ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:28:27.308322Z","caller":"traceutil/trace.go:171","msg":"trace[507484108] linearizableReadLoop","detail":"{readStateIndex:3606; appliedIndex:3604; }","duration":"178.683234ms","start":"2026-05-19T18:28:27.129611Z","end":"2026-05-19T18:28:27.308294Z","steps":["trace[507484108] 'read index received' (duration: 110.436918ms)","trace[507484108] 'applied index is now lower than readState.Index' (duration: 68.241446ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:28:27.308511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.868479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:28:27.30858Z","caller":"traceutil/trace.go:171","msg":"trace[1583195457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3477; }","duration":"178.977802ms","start":"2026-05-19T18:28:27.129583Z","end":"2026-05-19T18:28:27.30856Z","steps":["trace[1583195457] 'agreement among raft nodes before linearized reading' (duration: 178.827637ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:28:27.30893Z","caller":"traceutil/trace.go:171","msg":"trace[1774867733] transaction","detail":"{read_only:false; response_revision:3477; number_of_response:1; }","duration":"201.403513ms","start":"2026-05-19T18:28:27.107512Z","end":"2026-05-19T18:28:27.308916Z","steps":["trace[1774867733] 'process raft request' (duration: 200.470591ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:05.870051Z","caller":"traceutil/trace.go:171","msg":"trace[513820317] linearizableReadLoop","detail":"{readStateIndex:4855; appliedIndex:4854; }","duration":"177.51054ms","start":"2026-05-19T18:30:05.692514Z","end":"2026-05-19T18:30:05.870024Z","steps":["trace[513820317] 'read index received' (duration: 177.369107ms)","trace[513820317] 'applied index is now lower than readState.Index' (duration: 139.792µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:30:05.870104Z","caller":"traceutil/trace.go:171","msg":"trace[1383608953] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"235.759367ms","start":"2026-05-19T18:30:05.634301Z","end":"2026-05-19T18:30:05.87006Z","steps":["trace[1383608953] 'process raft request' (duration: 235.500093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:05.870283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.748553ms","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-19T18:30:05.870344Z","caller":"traceutil/trace.go:171","msg":"trace[500788393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4681; }","duration":"177.846294ms","start":"2026-05-19T18:30:05.692477Z","end":"2026-05-19T18:30:05.870324Z","steps":["trace[500788393] 'agreement among raft nodes before linearized reading' (duration: 177.655591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:06.464269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.19848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:30:06.464466Z","caller":"traceutil/trace.go:171","msg":"trace[1800823054] linearizableReadLoop","detail":"{readStateIndex:4860; appliedIndex:4858; }","duration":"342.332442ms","start":"2026-05-19T18:30:06.122113Z","end":"2026-05-19T18:30:06.464446Z","steps":["trace[1800823054] 'read index received' (duration: 12.664002ms)","trace[1800823054] 'applied index is now lower than readState.Index' (duration: 329.66737ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:30:06.464551Z","caller":"traceutil/trace.go:171","msg":"trace[1532763218] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"393.987897ms","start":"2026-05-19T18:30:06.070553Z","end":"2026-05-19T18:30:06.464541Z","steps":["trace[1532763218] 'compare' (duration: 385.067697ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:06.464594Z","caller":"traceutil/trace.go:171","msg":"trace[492046641] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"392.704046ms","start":"2026-05-19T18:30:06.07185Z","end":"2026-05-19T18:30:06.464554Z","steps":["trace[492046641] 'process raft request' (duration: 392.537843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:06.464677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.549486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T18:30:06.46462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:06.070531Z","time spent":"394.047667ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:06.464761Z","caller":"traceutil/trace.go:171","msg":"trace[684749510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4686; }","duration":"342.681928ms","start":"2026-05-19T18:30:06.122059Z","end":"2026-05-19T18:30:06.464741Z","steps":["trace[684749510] 'agreement among raft nodes before linearized reading' (duration: 342.510375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:06.464777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:06.071842Z","time spent":"392.851448ms","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:30:06.46481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:06.122043Z","time spent":"342.749959ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-19T18:30:06.478427Z","caller":"traceutil/trace.go:171","msg":"trace[1727559117] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"353.026871ms","start":"2026-05-19T18:30:06.125378Z","end":"2026-05-19T18:30:06.478405Z","steps":["trace[1727559117] 'process raft request' (duration: 352.887689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:06.478602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:06.125363Z","time spent":"353.136053ms","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10357,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:06.508523Z","caller":"traceutil/trace.go:171","msg":"trace[1877107781] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"141.308557ms","start":"2026-05-19T18:30:06.367185Z","end":"2026-05-19T18:30:06.508493Z","steps":["trace[1877107781] 'process raft request' (duration: 140.971181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:06.508659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.795766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0104cf74-babf-4e8f-a39e-105a95e52b13\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-05-19T18:30:06.508981Z","caller":"traceutil/trace.go:171","msg":"trace[112628901] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0104cf74-babf-4e8f-a39e-105a95e52b13; range_end:; response_count:1; response_revision:4689; }","duration":"111.175811ms","start":"2026-05-19T18:30:06.397765Z","end":"2026-05-19T18:30:06.508941Z","steps":["trace[112628901] 'agreement among raft nodes before linearized reading' (duration: 110.663823ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:07.850939Z","caller":"traceutil/trace.go:171","msg":"trace[973464730] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"168.746949ms","start":"2026-05-19T18:30:07.682159Z","end":"2026-05-19T18:30:07.850906Z","steps":["trace[973464730] 'process raft request' (duration: 167.376136ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:20.709226Z","caller":"traceutil/trace.go:171","msg":"trace[995615781] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"111.780052ms","start":"2026-05-19T18:30:20.597421Z","end":"2026-05-19T18:30:20.709201Z","steps":["trace[995615781] 'process raft request' (duration: 111.334265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:21.258772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640610168310229417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T18:30:21.722817Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.008967147s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-19T18:30:22.040195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.953645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:30:22.040522Z","caller":"traceutil/trace.go:171","msg":"trace[1192179578] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"1.326672592s","start":"2026-05-19T18:30:20.713615Z","end":"2026-05-19T18:30:22.040287Z","steps":["trace[1192179578] 'process raft request' (duration: 1.009553265s)","trace[1192179578] 'compare' (duration: 315.750226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:30:22.040635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:20.713589Z","time spent":"1.327007768s","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:22.083345Z","caller":"traceutil/trace.go:171","msg":"trace[473201052] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"1.369417113s","start":"2026-05-19T18:30:20.713883Z","end":"2026-05-19T18:30:22.0833Z","steps":["trace[473201052] 'process raft request' (duration: 1.369158988s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:22.083476Z","caller":"traceutil/trace.go:171","msg":"trace[1078060514] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"524.298387ms","start":"2026-05-19T18:30:21.55917Z","end":"2026-05-19T18:30:22.083469Z","steps":["trace[1078060514] 'process raft request' (duration: 524.114404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:22.083375Z","caller":"traceutil/trace.go:171","msg":"trace[1771156379] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"1.138724395s","start":"2026-05-19T18:30:20.944631Z","end":"2026-05-19T18:30:22.083356Z","steps":["trace[1771156379] 'process raft request' (duration: 1.138545842s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.083652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:21.559152Z","time spent":"524.353907ms","remote":"127.0.0.1:51392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T18:30:22.083749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326047861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:30:22.083389Z","caller":"traceutil/trace.go:171","msg":"trace[2118895458] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5058; }","duration":"1.325688265s","start":"2026-05-19T18:30:20.757683Z","end":"2026-05-19T18:30:22.083371Z","steps":["trace[2118895458] 'read index received' (duration: 965.569654ms)","trace[2118895458] 'applied index is now lower than readState.Index' (duration: 360.117751ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:30:22.083811Z","caller":"traceutil/trace.go:171","msg":"trace[1790146988] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4884; }","duration":"1.326151342s","start":"2026-05-19T18:30:20.757642Z","end":"2026-05-19T18:30:22.083793Z","steps":["trace[1790146988] 'agreement among raft nodes before linearized reading' (duration: 1.325784816s)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:22.083423Z","caller":"traceutil/trace.go:171","msg":"trace[161399185] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"1.090989561s","start":"2026-05-19T18:30:20.992426Z","end":"2026-05-19T18:30:22.083415Z","steps":["trace[161399185] 'process raft request' (duration: 1.090826098s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.08412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:20.757626Z","time spent":"1.326366726s","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:30:22.084498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:20.992401Z","time spent":"1.091666871s","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:22.083446Z","caller":"traceutil/trace.go:171","msg":"trace[762184389] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"1.1014369s","start":"2026-05-19T18:30:20.982002Z","end":"2026-05-19T18:30:22.083439Z","steps":["trace[762184389] 'process raft request' (duration: 1.101213917s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.08464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:20.944605Z","time spent":"1.139019179s","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:30:22.084796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:20.981987Z","time spent":"1.102609008s","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:30:22.08501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:20.713865Z","time spent":"1.369615536s","remote":"127.0.0.1:51592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T18:30:22.103681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010754192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:30:22.103755Z","caller":"traceutil/trace.go:171","msg":"trace[1702286339] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4885; }","duration":"1.010886413s","start":"2026-05-19T18:30:21.092852Z","end":"2026-05-19T18:30:22.103739Z","steps":["trace[1702286339] 'agreement among raft nodes before linearized reading' (duration: 1.010752111s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.103807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.010796ms","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-19T18:30:22.10387Z","caller":"traceutil/trace.go:171","msg":"trace[1727981864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4885; }","duration":"149.115827ms","start":"2026-05-19T18:30:21.954736Z","end":"2026-05-19T18:30:22.103852Z","steps":["trace[1727981864] 'agreement among raft nodes before linearized reading' (duration: 148.892874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.103855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:21.092829Z","time spent":"1.011012316s","remote":"127.0.0.1:52420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:30:22.104023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.124289ms","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-19T18:30:22.104087Z","caller":"traceutil/trace.go:171","msg":"trace[1230694632] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4885; }","duration":"449.177969ms","start":"2026-05-19T18:30:21.654888Z","end":"2026-05-19T18:30:22.104066Z","steps":["trace[1230694632] 'agreement among raft nodes before linearized reading' (duration: 449.025487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.104128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:21.654829Z","time spent":"449.291431ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-19T18:30:22.104235Z","caller":"traceutil/trace.go:171","msg":"trace[327982789] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"140.128873ms","start":"2026-05-19T18:30:21.964088Z","end":"2026-05-19T18:30:22.104216Z","steps":["trace[327982789] 'process raft request' (duration: 139.392431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.103872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.202868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-659bcbffbd-v8rtg\" ","response":"range_response_count:1 size:11845"} {"level":"info","ts":"2026-05-19T18:30:22.104335Z","caller":"traceutil/trace.go:171","msg":"trace[1388359432] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-659bcbffbd-v8rtg; range_end:; response_count:1; response_revision:4885; }","duration":"481.671216ms","start":"2026-05-19T18:30:21.622614Z","end":"2026-05-19T18:30:22.104285Z","steps":["trace[1388359432] 'agreement among raft nodes before linearized reading' (duration: 481.076667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.104647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.869807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:30:22.104694Z","caller":"traceutil/trace.go:171","msg":"trace[925466458] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:4885; }","duration":"933.959279ms","start":"2026-05-19T18:30:21.170723Z","end":"2026-05-19T18:30:22.104683Z","steps":["trace[925466458] 'agreement among raft nodes before linearized reading' (duration: 933.855968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.104724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:21.1707Z","time spent":"934.01528ms","remote":"127.0.0.1:52326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-19T18:30:22.104441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:21.622598Z","time spent":"481.820508ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11868,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-659bcbffbd-v8rtg\" "} {"level":"warn","ts":"2026-05-19T18:30:22.105514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.172652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T18:30:22.105607Z","caller":"traceutil/trace.go:171","msg":"trace[1621576302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4885; }","duration":"976.336455ms","start":"2026-05-19T18:30:21.129256Z","end":"2026-05-19T18:30:22.105592Z","steps":["trace[1621576302] 'agreement among raft nodes before linearized reading' (duration: 974.79815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.106142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:21.129236Z","time spent":"976.873184ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T18:30:22.304835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.471034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:30:22.305311Z","caller":"traceutil/trace.go:171","msg":"trace[2032692312] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"196.586192ms","start":"2026-05-19T18:30:22.108696Z","end":"2026-05-19T18:30:22.305282Z","steps":["trace[2032692312] 'process raft request' (duration: 80.587989ms)","trace[2032692312] 'compare' (duration: 115.321792ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:30:22.404112Z","caller":"traceutil/trace.go:171","msg":"trace[399987160] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"293.309243ms","start":"2026-05-19T18:30:22.110779Z","end":"2026-05-19T18:30:22.404088Z","steps":["trace[399987160] 'process raft request' (duration: 293.227321ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:22.404118Z","caller":"traceutil/trace.go:171","msg":"trace[3687472] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"293.415065ms","start":"2026-05-19T18:30:22.110669Z","end":"2026-05-19T18:30:22.404084Z","steps":["trace[3687472] 'process raft request' (duration: 293.184511ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:22.404256Z","caller":"traceutil/trace.go:171","msg":"trace[355204493] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"286.161667ms","start":"2026-05-19T18:30:22.118074Z","end":"2026-05-19T18:30:22.404236Z","steps":["trace[355204493] 'process raft request' (duration: 285.972424ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:22.546083Z","caller":"traceutil/trace.go:171","msg":"trace[755864022] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"132.986366ms","start":"2026-05-19T18:30:22.413069Z","end":"2026-05-19T18:30:22.546055Z","steps":["trace[755864022] 'process raft request' (duration: 41.782435ms)","trace[755864022] 'compare' (duration: 91.065539ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:30:22.950729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.370043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:30:22.951087Z","caller":"traceutil/trace.go:171","msg":"trace[1109252412] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"236.505665ms","start":"2026-05-19T18:30:22.714561Z","end":"2026-05-19T18:30:22.951067Z","steps":["trace[1109252412] 'process raft request' (duration: 236.449314ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:30:22.951383Z","caller":"traceutil/trace.go:171","msg":"trace[140499270] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"538.232815ms","start":"2026-05-19T18:30:22.413107Z","end":"2026-05-19T18:30:22.95134Z","steps":["trace[140499270] 'process raft request' (duration: 279.923477ms)","trace[140499270] 'compare' (duration: 257.011947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:30:22.951667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:22.413087Z","time spent":"538.406758ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:22.952272Z","caller":"traceutil/trace.go:171","msg":"trace[112698408] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"535.134624ms","start":"2026-05-19T18:30:22.417087Z","end":"2026-05-19T18:30:22.952222Z","steps":["trace[112698408] 'process raft request' (duration: 533.766702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.952634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:22.417077Z","time spent":"535.398078ms","remote":"127.0.0.1:51782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T18:30:22.953173Z","caller":"traceutil/trace.go:171","msg":"trace[91328245] linearizableReadLoop","detail":"{readStateIndex:5072; appliedIndex:5070; }","duration":"385.269026ms","start":"2026-05-19T18:30:22.567886Z","end":"2026-05-19T18:30:22.953155Z","steps":["trace[91328245] 'read index received' (duration: 125.079368ms)","trace[91328245] 'applied index is now lower than readState.Index' (duration: 260.187378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:30:22.953805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.699013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T18:30:22.953844Z","caller":"traceutil/trace.go:171","msg":"trace[27342504] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4893; }","duration":"336.768164ms","start":"2026-05-19T18:30:22.617064Z","end":"2026-05-19T18:30:22.953832Z","steps":["trace[27342504] 'agreement among raft nodes before linearized reading' (duration: 336.633112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.953878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:22.617047Z","time spent":"336.824824ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-19T18:30:22.953879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.641923ms","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-19T18:30:22.953975Z","caller":"traceutil/trace.go:171","msg":"trace[233658652] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4893; }","duration":"209.797006ms","start":"2026-05-19T18:30:22.744156Z","end":"2026-05-19T18:30:22.953953Z","steps":["trace[233658652] 'agreement among raft nodes before linearized reading' (duration: 209.588912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.953862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.952757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16548"} {"level":"warn","ts":"2026-05-19T18:30:22.954683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.824574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T18:30:22.954969Z","caller":"traceutil/trace.go:171","msg":"trace[1105136311] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4893; }","duration":"332.16556ms","start":"2026-05-19T18:30:22.622783Z","end":"2026-05-19T18:30:22.954949Z","steps":["trace[1105136311] 'agreement among raft nodes before linearized reading' (duration: 331.025701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.955047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:22.622771Z","time spent":"332.263312ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-19T18:30:22.95472Z","caller":"traceutil/trace.go:171","msg":"trace[1163969778] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4893; }","duration":"386.422234ms","start":"2026-05-19T18:30:22.567834Z","end":"2026-05-19T18:30:22.954256Z","steps":["trace[1163969778] 'agreement among raft nodes before linearized reading' (duration: 385.782674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:30:22.955897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T18:30:22.567813Z","time spent":"387.887538ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16571,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-19T18:30:23.313024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.399814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T18:30:23.313367Z","caller":"traceutil/trace.go:171","msg":"trace[1687198034] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4896; }","duration":"111.76257ms","start":"2026-05-19T18:30:23.201578Z","end":"2026-05-19T18:30:23.31334Z","steps":["trace[1687198034] 'count revisions from in-memory index tree' (duration: 111.280332ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:00.428118Z","caller":"traceutil/trace.go:171","msg":"trace[1704082248] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"204.466519ms","start":"2026-05-19T18:31:00.223624Z","end":"2026-05-19T18:31:00.42809Z","steps":["trace[1704082248] 'process raft request' (duration: 114.515262ms)","trace[1704082248] 'compare' (duration: 89.769953ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:31:00.428267Z","caller":"traceutil/trace.go:171","msg":"trace[1696385992] linearizableReadLoop","detail":"{readStateIndex:5406; appliedIndex:5403; }","duration":"193.991153ms","start":"2026-05-19T18:31:00.234267Z","end":"2026-05-19T18:31:00.428258Z","steps":["trace[1696385992] 'read index received' (duration: 15.121097ms)","trace[1696385992] 'applied index is now lower than readState.Index' (duration: 178.869416ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:31:00.428529Z","caller":"traceutil/trace.go:171","msg":"trace[544779839] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"204.787924ms","start":"2026-05-19T18:31:00.223733Z","end":"2026-05-19T18:31:00.428521Z","steps":["trace[544779839] 'process raft request' (duration: 204.317637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:31:00.428671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.398489ms","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-19T18:31:00.428697Z","caller":"traceutil/trace.go:171","msg":"trace[2035348970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5216; }","duration":"194.463841ms","start":"2026-05-19T18:31:00.234225Z","end":"2026-05-19T18:31:00.428689Z","steps":["trace[2035348970] 'agreement among raft nodes before linearized reading' (duration: 194.362389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:31:00.428785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.184055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T18:31:00.428822Z","caller":"traceutil/trace.go:171","msg":"trace[1665041602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5216; }","duration":"190.243127ms","start":"2026-05-19T18:31:00.238574Z","end":"2026-05-19T18:31:00.428817Z","steps":["trace[1665041602] 'agreement among raft nodes before linearized reading' (duration: 190.173146ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:00.642569Z","caller":"traceutil/trace.go:171","msg":"trace[2119403344] transaction","detail":"{read_only:false; response_revision:5217; number_of_response:1; }","duration":"210.412828ms","start":"2026-05-19T18:31:00.432127Z","end":"2026-05-19T18:31:00.64254Z","steps":["trace[2119403344] 'process raft request' (duration: 128.739026ms)","trace[2119403344] 'compare' (duration: 81.443119ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:31:00.642743Z","caller":"traceutil/trace.go:171","msg":"trace[152430835] linearizableReadLoop","detail":"{readStateIndex:5408; appliedIndex:5406; }","duration":"140.828846ms","start":"2026-05-19T18:31:00.501888Z","end":"2026-05-19T18:31:00.642717Z","steps":["trace[152430835] 'read index received' (duration: 59.091924ms)","trace[152430835] 'applied index is now lower than readState.Index' (duration: 81.735032ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:31:00.642833Z","caller":"traceutil/trace.go:171","msg":"trace[721210737] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"209.789679ms","start":"2026-05-19T18:31:00.433028Z","end":"2026-05-19T18:31:00.642818Z","steps":["trace[721210737] 'process raft request' (duration: 209.428784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:31:00.64328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.272833ms","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-19T18:31:00.644181Z","caller":"traceutil/trace.go:171","msg":"trace[943688676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5218; }","duration":"142.32824ms","start":"2026-05-19T18:31:00.501831Z","end":"2026-05-19T18:31:00.644159Z","steps":["trace[943688676] 'agreement among raft nodes before linearized reading' (duration: 141.131931ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:08.629665Z","caller":"traceutil/trace.go:171","msg":"trace[733963135] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"175.426128ms","start":"2026-05-19T18:31:08.454205Z","end":"2026-05-19T18:31:08.629632Z","steps":["trace[733963135] 'process raft request' (duration: 175.209424ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:08.643429Z","caller":"traceutil/trace.go:171","msg":"trace[1584375170] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5451; }","duration":"119.92322ms","start":"2026-05-19T18:31:08.523485Z","end":"2026-05-19T18:31:08.643408Z","steps":["trace[1584375170] 'read index received' (duration: 105.969364ms)","trace[1584375170] 'applied index is now lower than readState.Index' (duration: 13.952876ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:31:08.643607Z","caller":"traceutil/trace.go:171","msg":"trace[400110595] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"188.798365ms","start":"2026-05-19T18:31:08.454764Z","end":"2026-05-19T18:31:08.643562Z","steps":["trace[400110595] 'process raft request' (duration: 188.48111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:31:08.644362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.850843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2196733"} {"level":"info","ts":"2026-05-19T18:31:08.644453Z","caller":"traceutil/trace.go:171","msg":"trace[1986256057] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5262; }","duration":"120.975326ms","start":"2026-05-19T18:31:08.523456Z","end":"2026-05-19T18:31:08.644431Z","steps":["trace[1986256057] 'agreement among raft nodes before linearized reading' (duration: 120.063921ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:18.822822Z","caller":"traceutil/trace.go:171","msg":"trace[2142311157] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"139.631297ms","start":"2026-05-19T18:31:18.683159Z","end":"2026-05-19T18:31:18.822791Z","steps":["trace[2142311157] 'process raft request' (duration: 139.455915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:31:21.056432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.23511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T18:31:21.056509Z","caller":"traceutil/trace.go:171","msg":"trace[332353809] linearizableReadLoop","detail":"{readStateIndex:5547; appliedIndex:5546; }","duration":"132.658298ms","start":"2026-05-19T18:31:20.923836Z","end":"2026-05-19T18:31:21.056495Z","steps":["trace[332353809] 'read index received' (duration: 32.415748ms)","trace[332353809] 'applied index is now lower than readState.Index' (duration: 100.24164ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:31:21.056603Z","caller":"traceutil/trace.go:171","msg":"trace[595128583] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"161.539476ms","start":"2026-05-19T18:31:20.895027Z","end":"2026-05-19T18:31:21.056566Z","steps":["trace[595128583] 'process raft request' (duration: 61.107064ms)","trace[595128583] 'compare' (duration: 100.152148ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T18:31:21.056703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.865341ms","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-19T18:31:21.057037Z","caller":"traceutil/trace.go:171","msg":"trace[1325052782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5353; }","duration":"133.226607ms","start":"2026-05-19T18:31:20.923794Z","end":"2026-05-19T18:31:21.05702Z","steps":["trace[1325052782] 'agreement among raft nodes before linearized reading' (duration: 132.834281ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:21.090674Z","caller":"traceutil/trace.go:171","msg":"trace[1658183288] transaction","detail":"{read_only:false; response_revision:5354; number_of_response:1; }","duration":"143.363102ms","start":"2026-05-19T18:31:20.947278Z","end":"2026-05-19T18:31:21.090641Z","steps":["trace[1658183288] 'process raft request' (duration: 143.095079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:31:21.090991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.840232ms","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-19T18:31:21.091092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.677891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T18:31:21.091145Z","caller":"traceutil/trace.go:171","msg":"trace[89984744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5354; }","duration":"164.768533ms","start":"2026-05-19T18:31:20.926362Z","end":"2026-05-19T18:31:21.09113Z","steps":["trace[89984744] 'agreement among raft nodes before linearized reading' (duration: 164.483639ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:21.091076Z","caller":"traceutil/trace.go:171","msg":"trace[685085953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5354; }","duration":"167.183458ms","start":"2026-05-19T18:31:20.923873Z","end":"2026-05-19T18:31:21.091057Z","steps":["trace[685085953] 'agreement among raft nodes before linearized reading' (duration: 166.68102ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:31:51.572752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2882} {"level":"info","ts":"2026-05-19T18:31:51.666909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2882,"took":"91.724129ms","hash":22382714,"current-db-size-bytes":53612544,"current-db-size":"54 MB","current-db-size-in-use-bytes":28954624,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T18:31:51.667008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":22382714,"revision":2882,"compact-revision":-1} {"level":"info","ts":"2026-05-19T18:31:59.323971Z","caller":"traceutil/trace.go:171","msg":"trace[781849366] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"112.828138ms","start":"2026-05-19T18:31:59.211111Z","end":"2026-05-19T18:31:59.323939Z","steps":["trace[781849366] 'process raft request' (duration: 112.585514ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:05.592655Z","caller":"traceutil/trace.go:171","msg":"trace[1859473686] transaction","detail":"{read_only:false; response_revision:6498; number_of_response:1; }","duration":"125.043868ms","start":"2026-05-19T18:34:05.467593Z","end":"2026-05-19T18:34:05.592637Z","steps":["trace[1859473686] 'process raft request' (duration: 124.926686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T18:34:05.814122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.387004ms","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-19T18:34:05.814236Z","caller":"traceutil/trace.go:171","msg":"trace[1702137560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6498; }","duration":"109.549165ms","start":"2026-05-19T18:34:05.704662Z","end":"2026-05-19T18:34:05.814211Z","steps":["trace[1702137560] 'range keys from in-memory index tree' (duration: 109.08372ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:45.491156Z","caller":"traceutil/trace.go:171","msg":"trace[819758472] transaction","detail":"{read_only:false; response_revision:6764; number_of_response:1; }","duration":"178.831025ms","start":"2026-05-19T18:34:45.312303Z","end":"2026-05-19T18:34:45.491134Z","steps":["trace[819758472] 'process raft request' (duration: 178.717354ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T18:34:45.630129Z","caller":"traceutil/trace.go:171","msg":"trace[296142258] transaction","detail":"{read_only:false; response_revision:6765; number_of_response:1; }","duration":"128.082568ms","start":"2026-05-19T18:34:45.502024Z","end":"2026-05-19T18:34:45.630106Z","steps":["trace[296142258] 'process raft request' (duration: 71.900545ms)","trace[296142258] 'compare' (duration: 55.730188ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T18:36:51.579759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5570} {"level":"info","ts":"2026-05-19T18:36:51.689032Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5570,"took":"107.736123ms","hash":3235857498,"current-db-size-bytes":53612544,"current-db-size":"54 MB","current-db-size-in-use-bytes":18214912,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-19T18:36:51.689085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3235857498,"revision":5570,"compact-revision":2882}