{"level":"warn","ts":"2026-08-19T09:03:22.071791Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:03:22.07188Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.193: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.193:2380","--initial-cluster=instance=https://199.19.213.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.193: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-08-19T09:03:22.071955Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-19T09:03:22.071972Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-08-19T09:03:22.071999Z","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-08-19T09:03:22.07452Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"]} {"level":"info","ts":"2026-08-19T09:03:22.074829Z","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.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.193: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-08-19T09:03:22.079936Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.411781ms"} {"level":"info","ts":"2026-08-19T09:03:22.097006Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fc4b8086375a405d","cluster-id":"7155ef20751e16de"} {"level":"info","ts":"2026-08-19T09:03:22.09716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T09:03:22.0972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 0"} {"level":"info","ts":"2026-08-19T09:03:22.097218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fc4b8086375a405d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T09:03:22.097239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became follower at term 1"} {"level":"info","ts":"2026-08-19T09:03:22.097311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"warn","ts":"2026-08-19T09:03:22.110455Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T09:03:22.112048Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T09:03:22.112946Z","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-08-19T09:03:22.116057Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fc4b8086375a405d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T09:03:22.11643Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fc4b8086375a405d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T09:03:22.116529Z","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-08-19T09:03:22.1167Z","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-08-19T09:03:22.116747Z","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-08-19T09:03:22.118431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d switched to configuration voters=(18179765634753773661)"} {"level":"info","ts":"2026-08-19T09:03:22.121833Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","added-peer-id":"fc4b8086375a405d","added-peer-peer-urls":["https://199.19.213.193:2380"]} {"level":"info","ts":"2026-08-19T09:03:22.127041Z","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-08-19T09:03:22.127113Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-08-19T09:03:22.127197Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.193:2380"} {"level":"info","ts":"2026-08-19T09:03:22.127806Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fc4b8086375a405d","initial-advertise-peer-urls":["https://199.19.213.193:2380"],"listen-peer-urls":["https://199.19.213.193:2380"],"advertise-client-urls":["https://199.19.213.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T09:03:22.12787Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T09:03:22.198579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T09:03:22.198648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T09:03:22.198712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgPreVoteResp from fc4b8086375a405d at term 1"} {"level":"info","ts":"2026-08-19T09:03:22.198733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became candidate at term 2"} {"level":"info","ts":"2026-08-19T09:03:22.198765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d received MsgVoteResp from fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-08-19T09:03:22.198819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc4b8086375a405d became leader at term 2"} {"level":"info","ts":"2026-08-19T09:03:22.198861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fc4b8086375a405d elected leader fc4b8086375a405d at term 2"} {"level":"info","ts":"2026-08-19T09:03:22.199733Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:03:22.200374Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:03:22.200373Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fc4b8086375a405d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.193:2379]}","request-path":"/0/members/fc4b8086375a405d/attributes","cluster-id":"7155ef20751e16de","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T09:03:22.200434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T09:03:22.200799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T09:03:22.200852Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T09:03:22.201008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7155ef20751e16de","local-member-id":"fc4b8086375a405d","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:03:22.201177Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:03:22.201302Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T09:03:22.203856Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T09:03:22.20438Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.193:2379"} {"level":"info","ts":"2026-08-19T09:04:10.463155Z","caller":"traceutil/trace.go:171","msg":"trace[370185852] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"189.590753ms","start":"2026-08-19T09:04:10.273534Z","end":"2026-08-19T09:04:10.463125Z","steps":["trace[370185852] 'process raft request' (duration: 189.420937ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:10.463931Z","caller":"traceutil/trace.go:171","msg":"trace[1354078488] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:537; }","duration":"126.519202ms","start":"2026-08-19T09:04:10.337371Z","end":"2026-08-19T09:04:10.46389Z","steps":["trace[1354078488] 'read index received' (duration: 126.504102ms)","trace[1354078488] 'applied index is now lower than readState.Index' (duration: 11.57µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:10.464112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.72286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-19T09:04:10.464277Z","caller":"traceutil/trace.go:171","msg":"trace[1074721769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"126.921726ms","start":"2026-08-19T09:04:10.337337Z","end":"2026-08-19T09:04:10.464258Z","steps":["trace[1074721769] 'agreement among raft nodes before linearized reading' (duration: 126.665647ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:10.469306Z","caller":"traceutil/trace.go:171","msg":"trace[1467025617] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"118.060597ms","start":"2026-08-19T09:04:10.351228Z","end":"2026-08-19T09:04:10.469288Z","steps":["trace[1467025617] 'process raft request' (duration: 117.174057ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:10.46981Z","caller":"traceutil/trace.go:171","msg":"trace[877958886] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"113.733361ms","start":"2026-08-19T09:04:10.356065Z","end":"2026-08-19T09:04:10.469798Z","steps":["trace[877958886] 'process raft request' (duration: 112.423427ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:10.46993Z","caller":"traceutil/trace.go:171","msg":"trace[1961496401] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"103.873258ms","start":"2026-08-19T09:04:10.366048Z","end":"2026-08-19T09:04:10.469922Z","steps":["trace[1961496401] 'process raft request' (duration: 102.514453ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:10.470022Z","caller":"traceutil/trace.go:171","msg":"trace[1432764424] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"103.864348ms","start":"2026-08-19T09:04:10.366151Z","end":"2026-08-19T09:04:10.470015Z","steps":["trace[1432764424] 'process raft request' (duration: 102.454411ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:16.583917Z","caller":"traceutil/trace.go:171","msg":"trace[302427700] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"119.161433ms","start":"2026-08-19T09:04:16.464728Z","end":"2026-08-19T09:04:16.583889Z","steps":["trace[302427700] 'process raft request' (duration: 118.631813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:16.922387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.732921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:04:16.922642Z","caller":"traceutil/trace.go:171","msg":"trace[1465941018] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"333.770599ms","start":"2026-08-19T09:04:16.588853Z","end":"2026-08-19T09:04:16.922624Z","steps":["trace[1465941018] 'process raft request' (duration: 333.627294ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:16.922637Z","caller":"traceutil/trace.go:171","msg":"trace[447236506] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"334.024318ms","start":"2026-08-19T09:04:16.588588Z","end":"2026-08-19T09:04:16.922613Z","steps":["trace[447236506] 'process raft request' (duration: 60.543171ms)","trace[447236506] 'compare' (duration: 272.596556ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:16.922757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:16.58857Z","time spent":"334.123122ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:04:16.922811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:16.588817Z","time spent":"333.869654ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:04:16.9697Z","caller":"traceutil/trace.go:171","msg":"trace[2032019352] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"300.090979ms","start":"2026-08-19T09:04:16.669563Z","end":"2026-08-19T09:04:16.969654Z","steps":["trace[2032019352] 'process raft request' (duration: 299.406605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:16.969884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:16.669494Z","time spent":"300.257995ms","remote":"127.0.0.1:48448","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-08-19T09:04:17.599569Z","caller":"traceutil/trace.go:171","msg":"trace[1381454662] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"443.769627ms","start":"2026-08-19T09:04:17.155768Z","end":"2026-08-19T09:04:17.599538Z","steps":["trace[1381454662] 'read index received' (duration: 439.128762ms)","trace[1381454662] 'applied index is now lower than readState.Index' (duration: 4.639485ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:04:17.599715Z","caller":"traceutil/trace.go:171","msg":"trace[1387493500] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"568.274691ms","start":"2026-08-19T09:04:17.031412Z","end":"2026-08-19T09:04:17.599687Z","steps":["trace[1387493500] 'process raft request' (duration: 563.455679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:17.599955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.179562ms","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-08-19T09:04:17.600009Z","caller":"traceutil/trace.go:171","msg":"trace[1735724584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"444.277816ms","start":"2026-08-19T09:04:17.15572Z","end":"2026-08-19T09:04:17.599998Z","steps":["trace[1735724584] 'agreement among raft nodes before linearized reading' (duration: 444.069678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:17.600044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:17.155701Z","time spent":"444.334508ms","remote":"127.0.0.1:48636","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-08-19T09:04:17.60016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:17.031387Z","time spent":"568.568611ms","remote":"127.0.0.1:48596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:04:17.600389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.630046ms","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-08-19T09:04:17.600432Z","caller":"traceutil/trace.go:171","msg":"trace[1316419085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"124.708499ms","start":"2026-08-19T09:04:17.475714Z","end":"2026-08-19T09:04:17.600422Z","steps":["trace[1316419085] 'agreement among raft nodes before linearized reading' (duration: 124.609936ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:32.816307Z","caller":"traceutil/trace.go:171","msg":"trace[985760699] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"343.717131ms","start":"2026-08-19T09:04:32.472565Z","end":"2026-08-19T09:04:32.816282Z","steps":["trace[985760699] 'read index received' (duration: 343.543265ms)","trace[985760699] 'applied index is now lower than readState.Index' (duration: 172.996µs)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:04:32.816638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.034034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:04:32.816724Z","caller":"traceutil/trace.go:171","msg":"trace[190774183] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:786; }","duration":"344.154298ms","start":"2026-08-19T09:04:32.47255Z","end":"2026-08-19T09:04:32.816705Z","steps":["trace[190774183] 'agreement among raft nodes before linearized reading' (duration: 343.985711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:32.816773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:32.472545Z","time spent":"344.21752ms","remote":"127.0.0.1:33692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-08-19T09:04:32.816773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.025813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:04:32.816786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.704981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:04:32.816796Z","caller":"traceutil/trace.go:171","msg":"trace[762707876] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"348.735556ms","start":"2026-08-19T09:04:32.468043Z","end":"2026-08-19T09:04:32.816779Z","steps":["trace[762707876] 'process raft request' (duration: 348.122222ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:32.816854Z","caller":"traceutil/trace.go:171","msg":"trace[178140845] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:786; }","duration":"343.800424ms","start":"2026-08-19T09:04:32.473041Z","end":"2026-08-19T09:04:32.816841Z","steps":["trace[178140845] 'agreement among raft nodes before linearized reading' (duration: 343.665679ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:04:32.816851Z","caller":"traceutil/trace.go:171","msg":"trace[1237511278] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:786; }","duration":"344.151888ms","start":"2026-08-19T09:04:32.472681Z","end":"2026-08-19T09:04:32.816833Z","steps":["trace[1237511278] 'agreement among raft nodes before linearized reading' (duration: 344.009952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:04:32.816887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:32.473023Z","time spent":"343.857356ms","remote":"127.0.0.1:33692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-08-19T09:04:32.8169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:32.472662Z","time spent":"344.227401ms","remote":"127.0.0.1:33692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:04:32.816976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:04:32.468024Z","time spent":"348.847909ms","remote":"127.0.0.1:48552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:05:02.09723Z","caller":"traceutil/trace.go:171","msg":"trace[313117818] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"248.477666ms","start":"2026-08-19T09:05:01.848721Z","end":"2026-08-19T09:05:02.097199Z","steps":["trace[313117818] 'process raft request' (duration: 152.83293ms)","trace[313117818] 'compare' (duration: 95.389005ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:24.916681Z","caller":"traceutil/trace.go:171","msg":"trace[1307817882] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"166.676328ms","start":"2026-08-19T09:05:24.749911Z","end":"2026-08-19T09:05:24.916588Z","steps":["trace[1307817882] 'process raft request' (duration: 166.482089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:48.792656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.820371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:05:48.792932Z","caller":"traceutil/trace.go:171","msg":"trace[656514917] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"222.813004ms","start":"2026-08-19T09:05:48.570095Z","end":"2026-08-19T09:05:48.792908Z","steps":["trace[656514917] 'process raft request' (duration: 222.692098ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:05:48.792997Z","caller":"traceutil/trace.go:171","msg":"trace[1875446668] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"354.18623ms","start":"2026-08-19T09:05:48.43878Z","end":"2026-08-19T09:05:48.792967Z","steps":["trace[1875446668] 'read index received' (duration: 231.944608ms)","trace[1875446668] 'applied index is now lower than readState.Index' (duration: 122.239262ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:48.793122Z","caller":"traceutil/trace.go:171","msg":"trace[1820709388] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"454.9305ms","start":"2026-08-19T09:05:48.337888Z","end":"2026-08-19T09:05:48.792818Z","steps":["trace[1820709388] 'process raft request' (duration: 332.823614ms)","trace[1820709388] 'compare' (duration: 121.672234ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:05:48.79328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:48.337866Z","time spent":"455.343529ms","remote":"127.0.0.1:48636","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-08-19T09:05:48.793327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.501474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:05:48.793409Z","caller":"traceutil/trace.go:171","msg":"trace[648097274] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1838; }","duration":"354.647811ms","start":"2026-08-19T09:05:48.438749Z","end":"2026-08-19T09:05:48.793397Z","steps":["trace[648097274] 'agreement among raft nodes before linearized reading' (duration: 354.313025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:48.793485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:48.438736Z","time spent":"354.692563ms","remote":"127.0.0.1:48724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":67,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-19T09:05:48.944333Z","caller":"traceutil/trace.go:171","msg":"trace[272756713] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"136.696589ms","start":"2026-08-19T09:05:48.807599Z","end":"2026-08-19T09:05:48.944296Z","steps":["trace[272756713] 'process raft request' (duration: 117.668855ms)","trace[272756713] 'compare' (duration: 18.863937ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:49.113027Z","caller":"traceutil/trace.go:171","msg":"trace[1284054861] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1917; }","duration":"278.591913ms","start":"2026-08-19T09:05:48.834415Z","end":"2026-08-19T09:05:49.113007Z","steps":["trace[1284054861] 'read index received' (duration: 90.863861ms)","trace[1284054861] 'applied index is now lower than readState.Index' (duration: 187.727422ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:49.113128Z","caller":"traceutil/trace.go:171","msg":"trace[1517060805] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"304.141487ms","start":"2026-08-19T09:05:48.808956Z","end":"2026-08-19T09:05:49.113098Z","steps":["trace[1517060805] 'process raft request' (duration: 289.442217ms)","trace[1517060805] 'compare' (duration: 14.4914ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:05:49.113209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.773451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44ca8a7e-7828-4c87-8fec-4c2738d65928\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-08-19T09:05:49.113247Z","caller":"traceutil/trace.go:171","msg":"trace[625320866] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44ca8a7e-7828-4c87-8fec-4c2738d65928; range_end:; response_count:1; response_revision:1840; }","duration":"278.849054ms","start":"2026-08-19T09:05:48.834378Z","end":"2026-08-19T09:05:49.113228Z","steps":["trace[625320866] 'agreement among raft nodes before linearized reading' (duration: 278.733258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:49.113347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:05:48.808937Z","time spent":"304.269013ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:05:57.057043Z","caller":"traceutil/trace.go:171","msg":"trace[1995093931] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"147.978973ms","start":"2026-08-19T09:05:56.909031Z","end":"2026-08-19T09:05:57.05701Z","steps":["trace[1995093931] 'read index received' (duration: 122.361842ms)","trace[1995093931] 'applied index is now lower than readState.Index' (duration: 25.615571ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:05:57.057254Z","caller":"traceutil/trace.go:171","msg":"trace[1659206381] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"172.176525ms","start":"2026-08-19T09:05:56.885058Z","end":"2026-08-19T09:05:57.057234Z","steps":["trace[1659206381] 'process raft request' (duration: 146.395006ms)","trace[1659206381] 'compare' (duration: 25.344547ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:05:57.057572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.51505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:05:57.057685Z","caller":"traceutil/trace.go:171","msg":"trace[1948195358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1922; }","duration":"148.656746ms","start":"2026-08-19T09:05:56.909Z","end":"2026-08-19T09:05:57.057657Z","steps":["trace[1948195358] 'agreement among raft nodes before linearized reading' (duration: 148.459307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:05:57.057765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.617697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-19T09:05:57.057813Z","caller":"traceutil/trace.go:171","msg":"trace[1556911988] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1922; }","duration":"142.69656ms","start":"2026-08-19T09:05:56.915105Z","end":"2026-08-19T09:05:57.057802Z","steps":["trace[1556911988] 'agreement among raft nodes before linearized reading' (duration: 142.296871ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:02.291888Z","caller":"traceutil/trace.go:171","msg":"trace[183521773] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"120.014424ms","start":"2026-08-19T09:06:02.171828Z","end":"2026-08-19T09:06:02.291843Z","steps":["trace[183521773] 'process raft request' (duration: 119.729751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:17.264277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.357381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-19T09:06:17.2644Z","caller":"traceutil/trace.go:171","msg":"trace[1482985865] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2049; }","duration":"219.525729ms","start":"2026-08-19T09:06:17.044848Z","end":"2026-08-19T09:06:17.264374Z","steps":["trace[1482985865] 'range keys from in-memory index tree' (duration: 219.12636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:17.264701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.133193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:06:17.264931Z","caller":"traceutil/trace.go:171","msg":"trace[712109428] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"156.452633ms","start":"2026-08-19T09:06:17.108427Z","end":"2026-08-19T09:06:17.26488Z","steps":["trace[712109428] 'process raft request' (duration: 33.055297ms)","trace[712109428] 'compare' (duration: 122.527544ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:17.771855Z","caller":"traceutil/trace.go:171","msg":"trace[113684060] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"180.687039ms","start":"2026-08-19T09:06:17.591136Z","end":"2026-08-19T09:06:17.771823Z","steps":["trace[113684060] 'process raft request' (duration: 104.05656ms)","trace[113684060] 'compare' (duration: 76.501362ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:17.964356Z","caller":"traceutil/trace.go:171","msg":"trace[1974091440] linearizableReadLoop","detail":"{readStateIndex:2140; appliedIndex:2139; }","duration":"257.902967ms","start":"2026-08-19T09:06:17.706429Z","end":"2026-08-19T09:06:17.964332Z","steps":["trace[1974091440] 'read index received' (duration: 248.905446ms)","trace[1974091440] 'applied index is now lower than readState.Index' (duration: 8.996821ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:17.964392Z","caller":"traceutil/trace.go:171","msg":"trace[1914845130] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"372.849419ms","start":"2026-08-19T09:06:17.591527Z","end":"2026-08-19T09:06:17.964377Z","steps":["trace[1914845130] 'process raft request' (duration: 363.795745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:17.964537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.106306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-08-19T09:06:17.964575Z","caller":"traceutil/trace.go:171","msg":"trace[11288252] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2054; }","duration":"258.18639ms","start":"2026-08-19T09:06:17.706379Z","end":"2026-08-19T09:06:17.964565Z","steps":["trace[11288252] 'agreement among raft nodes before linearized reading' (duration: 258.040443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:17.96464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:17.591506Z","time spent":"372.920482ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:06:17.972289Z","caller":"traceutil/trace.go:171","msg":"trace[1361411130] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"120.103454ms","start":"2026-08-19T09:06:17.852157Z","end":"2026-08-19T09:06:17.97226Z","steps":["trace[1361411130] 'process raft request' (duration: 120.02063ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:17.972587Z","caller":"traceutil/trace.go:171","msg":"trace[696594361] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"170.493519ms","start":"2026-08-19T09:06:17.802056Z","end":"2026-08-19T09:06:17.972549Z","steps":["trace[696594361] 'process raft request' (duration: 169.973883ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:18.610085Z","caller":"traceutil/trace.go:171","msg":"trace[901681383] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"178.754191ms","start":"2026-08-19T09:06:18.431304Z","end":"2026-08-19T09:06:18.610058Z","steps":["trace[901681383] 'process raft request' (duration: 169.917438ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:06:19.334483Z","caller":"traceutil/trace.go:171","msg":"trace[265134167] linearizableReadLoop","detail":"{readStateIndex:2145; appliedIndex:2144; }","duration":"410.55981ms","start":"2026-08-19T09:06:18.923899Z","end":"2026-08-19T09:06:19.334459Z","steps":["trace[265134167] 'read index received' (duration: 410.445174ms)","trace[265134167] 'applied index is now lower than readState.Index' (duration: 113.466µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:19.334597Z","caller":"traceutil/trace.go:171","msg":"trace[414665886] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"423.002538ms","start":"2026-08-19T09:06:18.911567Z","end":"2026-08-19T09:06:19.334569Z","steps":["trace[414665886] 'process raft request' (duration: 422.720414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:19.334745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.33546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-19T09:06:19.334816Z","caller":"traceutil/trace.go:171","msg":"trace[1913567019] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2059; }","duration":"409.460285ms","start":"2026-08-19T09:06:18.925339Z","end":"2026-08-19T09:06:19.3348Z","steps":["trace[1913567019] 'agreement among raft nodes before linearized reading' (duration: 409.304508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:19.334869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:18.925335Z","time spent":"409.520908ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:06:19.335007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:18.911551Z","time spent":"423.220529ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:06:19.335501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.58829ms","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-08-19T09:06:19.335564Z","caller":"traceutil/trace.go:171","msg":"trace[168510499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2059; }","duration":"411.671284ms","start":"2026-08-19T09:06:18.923875Z","end":"2026-08-19T09:06:19.335546Z","steps":["trace[168510499] 'agreement among raft nodes before linearized reading' (duration: 410.749819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:19.335606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:06:18.923859Z","time spent":"411.735647ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-19T09:06:19.619279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.009781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:06:19.619521Z","caller":"traceutil/trace.go:171","msg":"trace[1645480922] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2145; }","duration":"183.528441ms","start":"2026-08-19T09:06:19.435971Z","end":"2026-08-19T09:06:19.6195Z","steps":["trace[1645480922] 'read index received' (duration: 6.195694ms)","trace[1645480922] 'applied index is now lower than readState.Index' (duration: 177.331557ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:19.61955Z","caller":"traceutil/trace.go:171","msg":"trace[734680160] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"279.576675ms","start":"2026-08-19T09:06:19.339945Z","end":"2026-08-19T09:06:19.619522Z","steps":["trace[734680160] 'process raft request' (duration: 102.232147ms)","trace[734680160] 'compare' (duration: 176.877015ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:06:19.619649Z","caller":"traceutil/trace.go:171","msg":"trace[1755334902] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"279.581975ms","start":"2026-08-19T09:06:19.340052Z","end":"2026-08-19T09:06:19.619634Z","steps":["trace[1755334902] 'process raft request' (duration: 279.367445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:06:19.619912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.929341ms","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-08-19T09:06:19.619996Z","caller":"traceutil/trace.go:171","msg":"trace[653613396] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2061; }","duration":"184.052327ms","start":"2026-08-19T09:06:19.435925Z","end":"2026-08-19T09:06:19.619977Z","steps":["trace[653613396] 'agreement among raft nodes before linearized reading' (duration: 183.839416ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:31.818505Z","caller":"traceutil/trace.go:171","msg":"trace[24279094] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"234.239478ms","start":"2026-08-19T09:07:31.584247Z","end":"2026-08-19T09:07:31.818487Z","steps":["trace[24279094] 'process raft request' (duration: 234.124527ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:07:31.826271Z","caller":"traceutil/trace.go:171","msg":"trace[56532886] transaction","detail":"{read_only:false; response_revision:2552; number_of_response:1; }","duration":"229.91541ms","start":"2026-08-19T09:07:31.596332Z","end":"2026-08-19T09:07:31.826247Z","steps":["trace[56532886] 'process raft request' (duration: 229.778819ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:04.331719Z","caller":"traceutil/trace.go:171","msg":"trace[93408608] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"204.213552ms","start":"2026-08-19T09:08:04.127476Z","end":"2026-08-19T09:08:04.33169Z","steps":["trace[93408608] 'process raft request' (duration: 203.963828ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:04.345311Z","caller":"traceutil/trace.go:171","msg":"trace[601180875] linearizableReadLoop","detail":"{readStateIndex:2841; appliedIndex:2839; }","duration":"214.403608ms","start":"2026-08-19T09:08:04.130877Z","end":"2026-08-19T09:08:04.34528Z","steps":["trace[601180875] 'read index received' (duration: 200.572233ms)","trace[601180875] 'applied index is now lower than readState.Index' (duration: 13.830435ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:04.345542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.606641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-08-19T09:08:04.345581Z","caller":"traceutil/trace.go:171","msg":"trace[1055812174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2731; }","duration":"214.716953ms","start":"2026-08-19T09:08:04.130853Z","end":"2026-08-19T09:08:04.34557Z","steps":["trace[1055812174] 'agreement among raft nodes before linearized reading' (duration: 214.54654ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:04.345693Z","caller":"traceutil/trace.go:171","msg":"trace[280854604] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"217.089545ms","start":"2026-08-19T09:08:04.128594Z","end":"2026-08-19T09:08:04.345684Z","steps":["trace[280854604] 'process raft request' (duration: 216.554447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:04.771049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.73738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:08:04.772332Z","caller":"traceutil/trace.go:171","msg":"trace[2076434954] linearizableReadLoop","detail":"{readStateIndex:2843; appliedIndex:2842; }","duration":"411.740237ms","start":"2026-08-19T09:08:04.360549Z","end":"2026-08-19T09:08:04.772289Z","steps":["trace[2076434954] 'read index received' (duration: 29.547656ms)","trace[2076434954] 'applied index is now lower than readState.Index' (duration: 382.189321ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:04.772368Z","caller":"traceutil/trace.go:171","msg":"trace[976567296] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"417.713118ms","start":"2026-08-19T09:08:04.354614Z","end":"2026-08-19T09:08:04.772327Z","steps":["trace[976567296] 'process raft request' (duration: 47.550638ms)","trace[976567296] 'compare' (duration: 368.280624ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:04.772679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.115383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-19T09:08:04.772744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:04.354592Z","time spent":"418.078672ms","remote":"127.0.0.1:48636","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-08-19T09:08:04.77276Z","caller":"traceutil/trace.go:171","msg":"trace[1994473054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2733; }","duration":"412.232704ms","start":"2026-08-19T09:08:04.360498Z","end":"2026-08-19T09:08:04.772731Z","steps":["trace[1994473054] 'agreement among raft nodes before linearized reading' (duration: 411.8998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:04.772917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:04.360476Z","time spent":"412.354495ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-19T09:08:04.77292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.516952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18cd29b0558e9267\" ","response":"range_response_count:1 size:781"} {"level":"warn","ts":"2026-08-19T09:08:04.77292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.786988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-19T09:08:04.772975Z","caller":"traceutil/trace.go:171","msg":"trace[2097450359] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18cd29b0558e9267; range_end:; response_count:1; response_revision:2733; }","duration":"308.648203ms","start":"2026-08-19T09:08:04.464314Z","end":"2026-08-19T09:08:04.772962Z","steps":["trace[2097450359] 'agreement among raft nodes before linearized reading' (duration: 308.4157ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:04.772996Z","caller":"traceutil/trace.go:171","msg":"trace[1153735798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2733; }","duration":"300.911379ms","start":"2026-08-19T09:08:04.472058Z","end":"2026-08-19T09:08:04.772969Z","steps":["trace[1153735798] 'agreement among raft nodes before linearized reading' (duration: 300.630635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:04.77302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:04.464279Z","time spent":"308.732345ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18cd29b0558e9267\" "} {"level":"warn","ts":"2026-08-19T09:08:04.773039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:04.472035Z","time spent":"300.996031ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-19T09:08:04.773174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.889209ms","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-08-19T09:08:04.774705Z","caller":"traceutil/trace.go:171","msg":"trace[744826095] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2733; }","duration":"401.588492ms","start":"2026-08-19T09:08:04.373092Z","end":"2026-08-19T09:08:04.77468Z","steps":["trace[744826095] 'agreement among raft nodes before linearized reading' (duration: 399.778038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:04.774835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:04.373066Z","time spent":"401.679203ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T09:08:05.137235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.943919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:08:05.137336Z","caller":"traceutil/trace.go:171","msg":"trace[1950734543] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"360.097136ms","start":"2026-08-19T09:08:04.777223Z","end":"2026-08-19T09:08:05.13732Z","steps":["trace[1950734543] 'process raft request' (duration: 214.991755ms)","trace[1950734543] 'compare' (duration: 144.751047ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:08:05.137383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:04.777198Z","time spent":"360.165028ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:08:05.137856Z","caller":"traceutil/trace.go:171","msg":"trace[2011868899] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"360.161597ms","start":"2026-08-19T09:08:04.777683Z","end":"2026-08-19T09:08:05.137844Z","steps":["trace[2011868899] 'process raft request' (duration: 359.715211ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:08:05.137933Z","caller":"traceutil/trace.go:171","msg":"trace[1270363909] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"358.646558ms","start":"2026-08-19T09:08:04.779253Z","end":"2026-08-19T09:08:05.1379Z","steps":["trace[1270363909] 'process raft request' (duration: 358.302773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:05.137964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:04.777668Z","time spent":"360.233828ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T09:08:05.138131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:08:04.779231Z","time spent":"358.7937ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T09:08:05.138129Z","caller":"traceutil/trace.go:171","msg":"trace[1296079901] linearizableReadLoop","detail":"{readStateIndex:2846; appliedIndex:2843; }","duration":"235.833659ms","start":"2026-08-19T09:08:04.90224Z","end":"2026-08-19T09:08:05.138074Z","steps":["trace[1296079901] 'read index received' (duration: 90.06741ms)","trace[1296079901] 'applied index is now lower than readState.Index' (duration: 145.763039ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:08:05.138399Z","caller":"traceutil/trace.go:171","msg":"trace[1179101631] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"149.547421ms","start":"2026-08-19T09:08:04.988765Z","end":"2026-08-19T09:08:05.138313Z","steps":["trace[1179101631] 'process raft request' (duration: 149.057934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:08:05.138469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.200965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-19T09:08:05.138527Z","caller":"traceutil/trace.go:171","msg":"trace[174526167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2737; }","duration":"236.317996ms","start":"2026-08-19T09:08:04.902197Z","end":"2026-08-19T09:08:05.138515Z","steps":["trace[174526167] 'agreement among raft nodes before linearized reading' (duration: 236.029252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:11:50.686903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.630019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:11:50.687102Z","caller":"traceutil/trace.go:171","msg":"trace[595828595] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"139.671377ms","start":"2026-08-19T09:11:50.547406Z","end":"2026-08-19T09:11:50.687078Z","steps":["trace[595828595] 'process raft request' (duration: 29.791939ms)","trace[595828595] 'compare' (duration: 109.534315ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:11:54.997684Z","caller":"traceutil/trace.go:171","msg":"trace[553435921] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"235.07949ms","start":"2026-08-19T09:11:54.762568Z","end":"2026-08-19T09:11:54.997647Z","steps":["trace[553435921] 'process raft request' (duration: 234.681724ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:55.040701Z","caller":"traceutil/trace.go:171","msg":"trace[1804166948] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"184.770805ms","start":"2026-08-19T09:11:54.855898Z","end":"2026-08-19T09:11:55.040669Z","steps":["trace[1804166948] 'process raft request' (duration: 184.453193ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:11:55.143157Z","caller":"traceutil/trace.go:171","msg":"trace[129770110] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"137.451618ms","start":"2026-08-19T09:11:55.005675Z","end":"2026-08-19T09:11:55.143127Z","steps":["trace[129770110] 'process raft request' (duration: 114.424048ms)","trace[129770110] 'compare' (duration: 22.881905ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:12:00.805614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.222572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:12:00.805738Z","caller":"traceutil/trace.go:171","msg":"trace[1372538024] linearizableReadLoop","detail":"{readStateIndex:4932; appliedIndex:4931; }","duration":"214.514866ms","start":"2026-08-19T09:12:00.591203Z","end":"2026-08-19T09:12:00.805718Z","steps":["trace[1372538024] 'read index received' (duration: 35.992µs)","trace[1372538024] 'applied index is now lower than readState.Index' (duration: 214.475564ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:12:00.805758Z","caller":"traceutil/trace.go:171","msg":"trace[47709225] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"432.0516ms","start":"2026-08-19T09:12:00.373683Z","end":"2026-08-19T09:12:00.805735Z","steps":["trace[47709225] 'process raft request' (duration: 80.580718ms)","trace[47709225] 'compare' (duration: 350.92169ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:12:00.80585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:00.373663Z","time spent":"432.144014ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":924,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T09:12:00.805986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.774396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88c9fca2-f614-4863-bf82-2c278f1b4ad1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-19T09:12:00.806034Z","caller":"traceutil/trace.go:171","msg":"trace[41357230] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88c9fca2-f614-4863-bf82-2c278f1b4ad1; range_end:; response_count:1; response_revision:4745; }","duration":"214.857019ms","start":"2026-08-19T09:12:00.591161Z","end":"2026-08-19T09:12:00.806018Z","steps":["trace[41357230] 'agreement among raft nodes before linearized reading' (duration: 214.63278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:00.854619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.338417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88c9fca2-f614-4863-bf82-2c278f1b4ad1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-19T09:12:00.854697Z","caller":"traceutil/trace.go:171","msg":"trace[1356717551] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88c9fca2-f614-4863-bf82-2c278f1b4ad1; range_end:; response_count:1; response_revision:4745; }","duration":"216.448451ms","start":"2026-08-19T09:12:00.638231Z","end":"2026-08-19T09:12:00.854679Z","steps":["trace[1356717551] 'agreement among raft nodes before linearized reading' (duration: 216.243103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:00.854791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.000989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-19T09:12:00.855032Z","caller":"traceutil/trace.go:171","msg":"trace[371900409] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4745; }","duration":"237.255539ms","start":"2026-08-19T09:12:00.617748Z","end":"2026-08-19T09:12:00.855004Z","steps":["trace[371900409] 'agreement among raft nodes before linearized reading' (duration: 236.803681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:01.325609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.561572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:12:01.325779Z","caller":"traceutil/trace.go:171","msg":"trace[1483760412] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"260.196572ms","start":"2026-08-19T09:12:01.065565Z","end":"2026-08-19T09:12:01.325762Z","steps":["trace[1483760412] 'process raft request' (duration: 260.133939ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:01.325807Z","caller":"traceutil/trace.go:171","msg":"trace[620997925] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"344.872073ms","start":"2026-08-19T09:12:00.980905Z","end":"2026-08-19T09:12:01.325777Z","steps":["trace[620997925] 'process raft request' (duration: 109.090063ms)","trace[620997925] 'compare' (duration: 235.409046ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:12:01.325903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:00.980888Z","time spent":"344.975078ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-19T09:12:11.840889Z","caller":"traceutil/trace.go:171","msg":"trace[384362767] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"213.326623ms","start":"2026-08-19T09:12:11.627537Z","end":"2026-08-19T09:12:11.840864Z","steps":["trace[384362767] 'process raft request' (duration: 213.194958ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:12.163169Z","caller":"traceutil/trace.go:171","msg":"trace[1761853476] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"131.680395ms","start":"2026-08-19T09:12:12.031452Z","end":"2026-08-19T09:12:12.163132Z","steps":["trace[1761853476] 'process raft request' (duration: 131.592681ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:12.163214Z","caller":"traceutil/trace.go:171","msg":"trace[1617492779] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"487.49758ms","start":"2026-08-19T09:12:11.675664Z","end":"2026-08-19T09:12:12.163161Z","steps":["trace[1617492779] 'process raft request' (duration: 433.4312ms)","trace[1617492779] 'compare' (duration: 53.8173ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:12:12.16336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.675639Z","time spent":"487.642845ms","remote":"127.0.0.1:48636","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-08-19T09:12:12.16356Z","caller":"traceutil/trace.go:171","msg":"trace[635405514] linearizableReadLoop","detail":"{readStateIndex:5086; appliedIndex:5084; }","duration":"482.847925ms","start":"2026-08-19T09:12:11.680646Z","end":"2026-08-19T09:12:12.163494Z","steps":["trace[635405514] 'read index received' (duration: 160.089399ms)","trace[635405514] 'applied index is now lower than readState.Index' (duration: 322.755686ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T09:12:12.163914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.393249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T09:12:12.163953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.336935ms","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":"warn","ts":"2026-08-19T09:12:12.163934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.273381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-19T09:12:12.163955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.208634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-19T09:12:12.163993Z","caller":"traceutil/trace.go:171","msg":"trace[279102471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4894; }","duration":"255.507834ms","start":"2026-08-19T09:12:11.908455Z","end":"2026-08-19T09:12:12.163963Z","steps":["trace[279102471] 'agreement among raft nodes before linearized reading' (duration: 255.39501ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:12.163999Z","caller":"traceutil/trace.go:171","msg":"trace[685434434] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4894; }","duration":"370.416079ms","start":"2026-08-19T09:12:11.793568Z","end":"2026-08-19T09:12:12.163984Z","steps":["trace[685434434] 'agreement among raft nodes before linearized reading' (duration: 370.269123ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:12.164004Z","caller":"traceutil/trace.go:171","msg":"trace[1242686440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4894; }","duration":"483.376995ms","start":"2026-08-19T09:12:11.680614Z","end":"2026-08-19T09:12:12.163991Z","steps":["trace[1242686440] 'agreement among raft nodes before linearized reading' (duration: 483.120915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:12.16409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.680599Z","time spent":"483.477259ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-19T09:12:12.164017Z","caller":"traceutil/trace.go:171","msg":"trace[358402193] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4894; }","duration":"465.284697ms","start":"2026-08-19T09:12:11.698716Z","end":"2026-08-19T09:12:12.164001Z","steps":["trace[358402193] 'agreement among raft nodes before linearized reading' (duration: 465.139871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:12.164103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.793548Z","time spent":"370.484201ms","remote":"127.0.0.1:48524","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":"warn","ts":"2026-08-19T09:12:12.164184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.698707Z","time spent":"465.458344ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-19T09:12:12.16429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.143351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-08-19T09:12:12.164403Z","caller":"traceutil/trace.go:171","msg":"trace[1493557626] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4894; }","duration":"468.258445ms","start":"2026-08-19T09:12:11.696086Z","end":"2026-08-19T09:12:12.164345Z","steps":["trace[1493557626] 'agreement among raft nodes before linearized reading' (duration: 467.752265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T09:12:12.165657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T09:12:11.69607Z","time spent":"469.555748ms","remote":"127.0.0.1:48552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-19T09:12:12.428476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.280262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T09:12:12.428741Z","caller":"traceutil/trace.go:171","msg":"trace[930846928] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"252.924126ms","start":"2026-08-19T09:12:12.175789Z","end":"2026-08-19T09:12:12.428713Z","steps":["trace[930846928] 'process raft request' (duration: 252.785231ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:12:12.428697Z","caller":"traceutil/trace.go:171","msg":"trace[415677034] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"258.785309ms","start":"2026-08-19T09:12:12.169878Z","end":"2026-08-19T09:12:12.428663Z","steps":["trace[415677034] 'process raft request' (duration: 142.231665ms)","trace[415677034] 'compare' (duration: 116.006022ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T09:13:22.566688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2829} {"level":"info","ts":"2026-08-19T09:13:22.657061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2829,"took":"88.92734ms","hash":1144461960,"current-db-size-bytes":53862400,"current-db-size":"54 MB","current-db-size-in-use-bytes":29106176,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-19T09:13:22.65713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1144461960,"revision":2829,"compact-revision":-1} {"level":"warn","ts":"2026-08-19T09:15:17.202462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.887965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T09:15:17.202538Z","caller":"traceutil/trace.go:171","msg":"trace[1213157487] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:6172; }","duration":"226.018891ms","start":"2026-08-19T09:15:16.9765Z","end":"2026-08-19T09:15:17.202519Z","steps":["trace[1213157487] 'count revisions from in-memory index tree' (duration: 225.74752ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:16:15.302026Z","caller":"traceutil/trace.go:171","msg":"trace[2138984388] transaction","detail":"{read_only:false; response_revision:6688; number_of_response:1; }","duration":"121.959126ms","start":"2026-08-19T09:16:15.180039Z","end":"2026-08-19T09:16:15.301998Z","steps":["trace[2138984388] 'process raft request' (duration: 121.834345ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T09:18:22.572279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5501} {"level":"info","ts":"2026-08-19T09:18:22.673599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5501,"took":"100.096443ms","hash":1999220833,"current-db-size-bytes":53862400,"current-db-size":"54 MB","current-db-size-in-use-bytes":18358272,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-19T09:18:22.673646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1999220833,"revision":5501,"compact-revision":2829}