{"level":"warn","ts":"2026-08-12T10:55:46.402909Z","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-12T10:55:46.403011Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.128: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.128:2380","--initial-cluster=instance=https://199.19.213.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.128: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-12T10:55:46.403111Z","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-12T10:55:46.403125Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-08-12T10:55:46.403204Z","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-12T10:55:46.404639Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"]} {"level":"info","ts":"2026-08-12T10:55:46.404997Z","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.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.128: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-12T10:55:46.418743Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.193086ms"} {"level":"info","ts":"2026-08-12T10:55:46.439996Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a463a6aa9b462de2","cluster-id":"cd3d14b8ed85e2f2"} {"level":"info","ts":"2026-08-12T10:55:46.440097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T10:55:46.440122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 0"} {"level":"info","ts":"2026-08-12T10:55:46.440135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a463a6aa9b462de2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T10:55:46.440142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 1"} {"level":"info","ts":"2026-08-12T10:55:46.440174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"warn","ts":"2026-08-12T10:55:46.447027Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T10:55:46.448304Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T10:55:46.449278Z","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-12T10:55:46.451522Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a463a6aa9b462de2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T10:55:46.451628Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a463a6aa9b462de2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T10:55:46.451683Z","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-12T10:55:46.45185Z","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-12T10:55:46.451871Z","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-12T10:55:46.452729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"info","ts":"2026-08-12T10:55:46.452944Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","added-peer-id":"a463a6aa9b462de2","added-peer-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-08-12T10:55:46.455669Z","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-12T10:55:46.455891Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-08-12T10:55:46.455924Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-08-12T10:55:46.45615Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a463a6aa9b462de2","initial-advertise-peer-urls":["https://199.19.213.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T10:55:46.456183Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T10:55:46.940431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T10:55:46.940495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T10:55:46.940543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgPreVoteResp from a463a6aa9b462de2 at term 1"} {"level":"info","ts":"2026-08-12T10:55:46.94057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became candidate at term 2"} {"level":"info","ts":"2026-08-12T10:55:46.940591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgVoteResp from a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-08-12T10:55:46.940652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became leader at term 2"} {"level":"info","ts":"2026-08-12T10:55:46.940675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a463a6aa9b462de2 elected leader a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-08-12T10:55:46.957963Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T10:55:46.962447Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T10:55:46.962439Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a463a6aa9b462de2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.128:2379]}","request-path":"/0/members/a463a6aa9b462de2/attributes","cluster-id":"cd3d14b8ed85e2f2","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T10:55:46.962535Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T10:55:46.962699Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T10:55:46.962735Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T10:55:46.962793Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T10:55:46.962892Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T10:55:46.962914Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T10:55:46.964259Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.128:2379"} {"level":"info","ts":"2026-08-12T10:55:46.96565Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T10:56:38.106165Z","caller":"traceutil/trace.go:171","msg":"trace[182680100] transaction","detail":"{read_only:false; number_of_response:0; response_revision:537; }","duration":"192.425991ms","start":"2026-08-12T10:56:37.913721Z","end":"2026-08-12T10:56:38.106147Z","steps":["trace[182680100] 'process raft request' (duration: 192.395131ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:38.106237Z","caller":"traceutil/trace.go:171","msg":"trace[195417846] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"194.923238ms","start":"2026-08-12T10:56:37.911287Z","end":"2026-08-12T10:56:38.10621Z","steps":["trace[195417846] 'process raft request' (duration: 194.720605ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:38.113566Z","caller":"traceutil/trace.go:171","msg":"trace[214691553] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"195.397494ms","start":"2026-08-12T10:56:37.918152Z","end":"2026-08-12T10:56:38.11355Z","steps":["trace[214691553] 'process raft request' (duration: 195.265372ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:38.113585Z","caller":"traceutil/trace.go:171","msg":"trace[1983590050] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"198.162495ms","start":"2026-08-12T10:56:37.915398Z","end":"2026-08-12T10:56:38.113561Z","steps":["trace[1983590050] 'process raft request' (duration: 197.919382ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:38.113861Z","caller":"traceutil/trace.go:171","msg":"trace[1558193389] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"193.982332ms","start":"2026-08-12T10:56:37.919859Z","end":"2026-08-12T10:56:38.113842Z","steps":["trace[1558193389] 'process raft request' (duration: 193.906011ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:38.113979Z","caller":"traceutil/trace.go:171","msg":"trace[41138274] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"190.245868ms","start":"2026-08-12T10:56:37.923717Z","end":"2026-08-12T10:56:38.113963Z","steps":["trace[41138274] 'process raft request' (duration: 190.084225ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:38.114049Z","caller":"traceutil/trace.go:171","msg":"trace[1782518123] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"195.545037ms","start":"2026-08-12T10:56:37.918334Z","end":"2026-08-12T10:56:38.113879Z","steps":["trace[1782518123] 'process raft request' (duration: 195.171201ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:44.113753Z","caller":"traceutil/trace.go:171","msg":"trace[65003930] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"138.951089ms","start":"2026-08-12T10:56:43.974774Z","end":"2026-08-12T10:56:44.113725Z","steps":["trace[65003930] 'process raft request' (duration: 138.421062ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:44.314346Z","caller":"traceutil/trace.go:171","msg":"trace[1555948689] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"148.844825ms","start":"2026-08-12T10:56:44.165468Z","end":"2026-08-12T10:56:44.314313Z","steps":["trace[1555948689] 'process raft request' (duration: 102.380494ms)","trace[1555948689] 'compare' (duration: 46.283038ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:56:44.334967Z","caller":"traceutil/trace.go:171","msg":"trace[2002674813] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"164.919789ms","start":"2026-08-12T10:56:44.169981Z","end":"2026-08-12T10:56:44.3349Z","steps":["trace[2002674813] 'process raft request' (duration: 164.750917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:56:44.811528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.433967ms","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-08-12T10:56:44.811573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.212207ms","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-08-12T10:56:44.811643Z","caller":"traceutil/trace.go:171","msg":"trace[1639048130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"208.337228ms","start":"2026-08-12T10:56:44.603287Z","end":"2026-08-12T10:56:44.811624Z","steps":["trace[1639048130] 'range keys from in-memory index tree' (duration: 208.055865ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:56:44.811631Z","caller":"traceutil/trace.go:171","msg":"trace[405568735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"200.581479ms","start":"2026-08-12T10:56:44.611025Z","end":"2026-08-12T10:56:44.811606Z","steps":["trace[405568735] 'range keys from in-memory index tree' (duration: 200.277945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:57:51.378971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.768952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:57:51.379071Z","caller":"traceutil/trace.go:171","msg":"trace[1994229830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1449; }","duration":"123.911223ms","start":"2026-08-12T10:57:51.25514Z","end":"2026-08-12T10:57:51.379052Z","steps":["trace[1994229830] 'range keys from in-memory index tree' (duration: 123.704861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:58:14.92159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.879905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T10:58:14.921739Z","caller":"traceutil/trace.go:171","msg":"trace[856291908] linearizableReadLoop","detail":"{readStateIndex:1923; appliedIndex:1922; }","duration":"142.541438ms","start":"2026-08-12T10:58:14.779178Z","end":"2026-08-12T10:58:14.92172Z","steps":["trace[856291908] 'read index received' (duration: 86.581µs)","trace[856291908] 'applied index is now lower than readState.Index' (duration: 142.453457ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:58:14.921791Z","caller":"traceutil/trace.go:171","msg":"trace[1666814411] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"325.640286ms","start":"2026-08-12T10:58:14.596088Z","end":"2026-08-12T10:58:14.921728Z","steps":["trace[1666814411] 'process raft request' (duration: 59.929873ms)","trace[1666814411] 'compare' (duration: 264.708834ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:58:14.922017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.019226ms","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-08-12T10:58:14.921996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:58:14.59606Z","time spent":"325.815288ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T10:58:14.922061Z","caller":"traceutil/trace.go:171","msg":"trace[1434939754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"137.129747ms","start":"2026-08-12T10:58:14.78492Z","end":"2026-08-12T10:58:14.92205Z","steps":["trace[1434939754] 'agreement among raft nodes before linearized reading' (duration: 136.916645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:58:14.922074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.070274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-12T10:58:14.922073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.863741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-12T10:58:14.922181Z","caller":"traceutil/trace.go:171","msg":"trace[705409241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1851; }","duration":"143.058342ms","start":"2026-08-12T10:58:14.779103Z","end":"2026-08-12T10:58:14.922161Z","steps":["trace[705409241] 'agreement among raft nodes before linearized reading' (duration: 142.730909ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:58:14.922182Z","caller":"traceutil/trace.go:171","msg":"trace[768943381] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"125.213486ms","start":"2026-08-12T10:58:14.796941Z","end":"2026-08-12T10:58:14.922155Z","steps":["trace[768943381] 'agreement among raft nodes before linearized reading' (duration: 124.960624ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:58:15.423427Z","caller":"traceutil/trace.go:171","msg":"trace[1030195085] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"128.609444ms","start":"2026-08-12T10:58:15.294776Z","end":"2026-08-12T10:58:15.423385Z","steps":["trace[1030195085] 'read index received' (duration: 110.750337ms)","trace[1030195085] 'applied index is now lower than readState.Index' (duration: 17.858187ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:58:15.423501Z","caller":"traceutil/trace.go:171","msg":"trace[2134425240] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"144.1788ms","start":"2026-08-12T10:58:15.279299Z","end":"2026-08-12T10:58:15.423478Z","steps":["trace[2134425240] 'process raft request' (duration: 126.291812ms)","trace[2134425240] 'compare' (duration: 17.495284ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:58:15.423975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.175189ms","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-08-12T10:58:15.424043Z","caller":"traceutil/trace.go:171","msg":"trace[1173778642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1855; }","duration":"129.29104ms","start":"2026-08-12T10:58:15.294736Z","end":"2026-08-12T10:58:15.424027Z","steps":["trace[1173778642] 'agreement among raft nodes before linearized reading' (duration: 129.123878ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:58:52.370666Z","caller":"traceutil/trace.go:171","msg":"trace[1420827812] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"216.88283ms","start":"2026-08-12T10:58:52.15376Z","end":"2026-08-12T10:58:52.370642Z","steps":["trace[1420827812] 'process raft request' (duration: 216.692678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:58:52.371072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.997998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-12T10:58:52.371193Z","caller":"traceutil/trace.go:171","msg":"trace[331429294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"181.09799ms","start":"2026-08-12T10:58:52.190017Z","end":"2026-08-12T10:58:52.371115Z","steps":["trace[331429294] 'agreement among raft nodes before linearized reading' (duration: 180.703247ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:58:52.37062Z","caller":"traceutil/trace.go:171","msg":"trace[356736987] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"180.544954ms","start":"2026-08-12T10:58:52.19005Z","end":"2026-08-12T10:58:52.370595Z","steps":["trace[356736987] 'read index received' (duration: 180.439283ms)","trace[356736987] 'applied index is now lower than readState.Index' (duration: 104.701µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:58:52.479424Z","caller":"traceutil/trace.go:171","msg":"trace[1289344804] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"261.49701ms","start":"2026-08-12T10:58:52.217874Z","end":"2026-08-12T10:58:52.479371Z","steps":["trace[1289344804] 'process raft request' (duration: 224.182715ms)","trace[1289344804] 'compare' (duration: 37.009673ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:58:52.479568Z","caller":"traceutil/trace.go:171","msg":"trace[693765419] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"107.889001ms","start":"2026-08-12T10:58:52.371328Z","end":"2026-08-12T10:58:52.479217Z","steps":["trace[693765419] 'read index received' (duration: 70.696546ms)","trace[693765419] 'applied index is now lower than readState.Index' (duration: 37.180825ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:58:52.479557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.516317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:58:52.479713Z","caller":"traceutil/trace.go:171","msg":"trace[58854710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2114; }","duration":"224.715909ms","start":"2026-08-12T10:58:52.254977Z","end":"2026-08-12T10:58:52.479693Z","steps":["trace[58854710] 'agreement among raft nodes before linearized reading' (duration: 224.472327ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:58:52.787247Z","caller":"traceutil/trace.go:171","msg":"trace[1494108424] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"113.330888ms","start":"2026-08-12T10:58:52.673896Z","end":"2026-08-12T10:58:52.787227Z","steps":["trace[1494108424] 'process raft request' (duration: 113.196867ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:58:53.252003Z","caller":"traceutil/trace.go:171","msg":"trace[400849124] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"122.081262ms","start":"2026-08-12T10:58:53.129897Z","end":"2026-08-12T10:58:53.251978Z","steps":["trace[400849124] 'read index received' (duration: 121.961161ms)","trace[400849124] 'applied index is now lower than readState.Index' (duration: 118.831µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:58:53.252083Z","caller":"traceutil/trace.go:171","msg":"trace[945500459] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"131.774187ms","start":"2026-08-12T10:58:53.120271Z","end":"2026-08-12T10:58:53.252046Z","steps":["trace[945500459] 'process raft request' (duration: 131.545865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:58:53.252295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.391335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-12T10:58:53.252344Z","caller":"traceutil/trace.go:171","msg":"trace[2080756644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2116; }","duration":"122.506936ms","start":"2026-08-12T10:58:53.129823Z","end":"2026-08-12T10:58:53.25233Z","steps":["trace[2080756644] 'agreement among raft nodes before linearized reading' (duration: 122.281884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:58:53.252531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.820073ms","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-12T10:58:53.252642Z","caller":"traceutil/trace.go:171","msg":"trace[740727926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2116; }","duration":"104.976974ms","start":"2026-08-12T10:58:53.147634Z","end":"2026-08-12T10:58:53.252611Z","steps":["trace[740727926] 'agreement among raft nodes before linearized reading' (duration: 104.575981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:43.149409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.392398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:5 size:212821"} {"level":"info","ts":"2026-08-12T10:59:43.149494Z","caller":"traceutil/trace.go:171","msg":"trace[1027927438] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:5; response_revision:2403; }","duration":"129.516662ms","start":"2026-08-12T10:59:43.01996Z","end":"2026-08-12T10:59:43.149476Z","steps":["trace[1027927438] 'range keys from in-memory index tree' (duration: 129.078949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:49.75471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696148658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T10:59:50.255287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696148658,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T10:59:50.365751Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.720034183s","expected-duration":"1s"} {"level":"info","ts":"2026-08-12T10:59:50.366343Z","caller":"traceutil/trace.go:171","msg":"trace[1057475010] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"1.720697562s","start":"2026-08-12T10:59:48.645618Z","end":"2026-08-12T10:59:50.366315Z","steps":["trace[1057475010] 'process raft request' (duration: 1.720498446s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.366546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:48.645596Z","time spent":"1.720860407s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T10:59:50.482798Z","caller":"traceutil/trace.go:171","msg":"trace[728586801] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"1.499917925s","start":"2026-08-12T10:59:48.982853Z","end":"2026-08-12T10:59:50.482771Z","steps":["trace[728586801] 'process raft request' (duration: 1.423882407s)","trace[728586801] 'compare' (duration: 75.878143ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:59:50.482951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:48.9828Z","time spent":"1.50006919s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T10:59:50.483032Z","caller":"traceutil/trace.go:171","msg":"trace[272666523] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"996.056193ms","start":"2026-08-12T10:59:49.486924Z","end":"2026-08-12T10:59:50.48298Z","steps":["trace[272666523] 'process raft request' (duration: 995.809215ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.483094Z","caller":"traceutil/trace.go:171","msg":"trace[231965613] linearizableReadLoop","detail":"{readStateIndex:2566; appliedIndex:2562; }","duration":"1.22888761s","start":"2026-08-12T10:59:49.254181Z","end":"2026-08-12T10:59:50.483069Z","steps":["trace[231965613] 'read index received' (duration: 1.111925878s)","trace[231965613] 'applied index is now lower than readState.Index' (duration: 116.960192ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:59:50.48319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.486898Z","time spent":"996.203018ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T10:59:50.48334Z","caller":"traceutil/trace.go:171","msg":"trace[327614572] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"721.933636ms","start":"2026-08-12T10:59:49.761396Z","end":"2026-08-12T10:59:50.483329Z","steps":["trace[327614572] 'process raft request' (duration: 721.494832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.483406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.76137Z","time spent":"722.003298ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T10:59:50.483298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229121666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T10:59:50.48348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032506956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T10:59:50.483493Z","caller":"traceutil/trace.go:171","msg":"trace[981598376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2472; }","duration":"1.229393464s","start":"2026-08-12T10:59:49.25409Z","end":"2026-08-12T10:59:50.483484Z","steps":["trace[981598376] 'agreement among raft nodes before linearized reading' (duration: 1.229177488s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.483524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.254071Z","time spent":"1.229447066s","remote":"127.0.0.1:53036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-12T10:59:50.483522Z","caller":"traceutil/trace.go:171","msg":"trace[1226778908] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2472; }","duration":"1.032574107s","start":"2026-08-12T10:59:49.450936Z","end":"2026-08-12T10:59:50.48351Z","steps":["trace[1226778908] 'agreement among raft nodes before linearized reading' (duration: 1.032505185s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.483621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.450917Z","time spent":"1.032681761s","remote":"127.0.0.1:53176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-12T10:59:50.483811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.62773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-12T10:59:50.483847Z","caller":"traceutil/trace.go:171","msg":"trace[512866500] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:1; response_revision:2472; }","duration":"483.704882ms","start":"2026-08-12T10:59:50.000132Z","end":"2026-08-12T10:59:50.483837Z","steps":["trace[512866500] 'agreement among raft nodes before linearized reading' (duration: 483.587798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.483935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.787996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-08-12T10:59:50.484003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.392898ms","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-08-12T10:59:50.484021Z","caller":"traceutil/trace.go:171","msg":"trace[877293860] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2472; }","duration":"780.90155ms","start":"2026-08-12T10:59:49.7031Z","end":"2026-08-12T10:59:50.484001Z","steps":["trace[877293860] 'agreement among raft nodes before linearized reading' (duration: 780.739395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.484034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.46877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-12T10:59:50.484045Z","caller":"traceutil/trace.go:171","msg":"trace[1477622205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2472; }","duration":"976.46266ms","start":"2026-08-12T10:59:49.507566Z","end":"2026-08-12T10:59:50.484029Z","steps":["trace[1477622205] 'agreement among raft nodes before linearized reading' (duration: 976.345886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.484057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.703079Z","time spent":"780.968631ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-12T10:59:50.484073Z","caller":"traceutil/trace.go:171","msg":"trace[1143125887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2472; }","duration":"926.544743ms","start":"2026-08-12T10:59:49.557516Z","end":"2026-08-12T10:59:50.484061Z","steps":["trace[1143125887] 'agreement among raft nodes before linearized reading' (duration: 926.434219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.484074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.507547Z","time spent":"976.519781ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T10:59:50.484107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.557496Z","time spent":"926.601415ms","remote":"127.0.0.1:53302","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-12T10:59:50.484256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.109798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-12T10:59:50.484294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.572762ms","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-08-12T10:59:50.484324Z","caller":"traceutil/trace.go:171","msg":"trace[719852343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2472; }","duration":"526.634974ms","start":"2026-08-12T10:59:49.957678Z","end":"2026-08-12T10:59:50.484313Z","steps":["trace[719852343] 'agreement among raft nodes before linearized reading' (duration: 526.557222ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.484293Z","caller":"traceutil/trace.go:171","msg":"trace[1133309080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2472; }","duration":"515.16792ms","start":"2026-08-12T10:59:49.969116Z","end":"2026-08-12T10:59:50.484284Z","steps":["trace[1133309080] 'agreement among raft nodes before linearized reading' (duration: 515.008365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:50.484523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.969106Z","time spent":"515.404418ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T10:59:50.484363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:49.957657Z","time spent":"526.697416ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T10:59:50.484765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T10:59:50.00011Z","time spent":"484.642821ms","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.128\" "} {"level":"info","ts":"2026-08-12T10:59:50.735709Z","caller":"traceutil/trace.go:171","msg":"trace[1950507240] transaction","detail":"{read_only:false; response_revision:2474; number_of_response:1; }","duration":"246.085376ms","start":"2026-08-12T10:59:50.489593Z","end":"2026-08-12T10:59:50.735679Z","steps":["trace[1950507240] 'process raft request' (duration: 245.974863ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.736045Z","caller":"traceutil/trace.go:171","msg":"trace[1535470704] transaction","detail":"{read_only:false; response_revision:2476; number_of_response:1; }","duration":"246.307712ms","start":"2026-08-12T10:59:50.489712Z","end":"2026-08-12T10:59:50.73602Z","steps":["trace[1535470704] 'process raft request' (duration: 246.166738ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.736092Z","caller":"traceutil/trace.go:171","msg":"trace[198471402] linearizableReadLoop","detail":"{readStateIndex:2572; appliedIndex:2567; }","duration":"243.436915ms","start":"2026-08-12T10:59:50.492637Z","end":"2026-08-12T10:59:50.736074Z","steps":["trace[198471402] 'read index received' (duration: 48.714542ms)","trace[198471402] 'applied index is now lower than readState.Index' (duration: 194.721413ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:59:50.735752Z","caller":"traceutil/trace.go:171","msg":"trace[332026987] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"246.398216ms","start":"2026-08-12T10:59:50.489323Z","end":"2026-08-12T10:59:50.735721Z","steps":["trace[332026987] 'process raft request' (duration: 207.565406ms)","trace[332026987] 'compare' (duration: 38.415537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:59:50.736231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.579619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T10:59:50.736356Z","caller":"traceutil/trace.go:171","msg":"trace[1769064066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2477; }","duration":"243.732494ms","start":"2026-08-12T10:59:50.492604Z","end":"2026-08-12T10:59:50.736337Z","steps":["trace[1769064066] 'agreement among raft nodes before linearized reading' (duration: 243.543928ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.736309Z","caller":"traceutil/trace.go:171","msg":"trace[1966103002] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"246.678264ms","start":"2026-08-12T10:59:50.489619Z","end":"2026-08-12T10:59:50.736298Z","steps":["trace[1966103002] 'process raft request' (duration: 246.010774ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.736467Z","caller":"traceutil/trace.go:171","msg":"trace[962337027] transaction","detail":"{read_only:false; response_revision:2477; number_of_response:1; }","duration":"246.689035ms","start":"2026-08-12T10:59:50.489762Z","end":"2026-08-12T10:59:50.736451Z","steps":["trace[962337027] 'process raft request' (duration: 246.21837ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:50.766858Z","caller":"traceutil/trace.go:171","msg":"trace[1596201974] transaction","detail":"{read_only:false; response_revision:2478; number_of_response:1; }","duration":"224.375121ms","start":"2026-08-12T10:59:50.542461Z","end":"2026-08-12T10:59:50.766836Z","steps":["trace[1596201974] 'process raft request' (duration: 224.236197ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:51.096678Z","caller":"traceutil/trace.go:171","msg":"trace[575404664] linearizableReadLoop","detail":"{readStateIndex:2574; appliedIndex:2573; }","duration":"171.415455ms","start":"2026-08-12T10:59:50.92524Z","end":"2026-08-12T10:59:51.096655Z","steps":["trace[575404664] 'read index received' (duration: 171.206579ms)","trace[575404664] 'applied index is now lower than readState.Index' (duration: 207.996µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T10:59:51.096869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.613122ms","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-08-12T10:59:51.096905Z","caller":"traceutil/trace.go:171","msg":"trace[1763253230] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2479; }","duration":"171.704115ms","start":"2026-08-12T10:59:50.925192Z","end":"2026-08-12T10:59:51.096896Z","steps":["trace[1763253230] 'agreement among raft nodes before linearized reading' (duration: 171.55386ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:51.096789Z","caller":"traceutil/trace.go:171","msg":"trace[580220110] transaction","detail":"{read_only:false; response_revision:2479; number_of_response:1; }","duration":"173.291062ms","start":"2026-08-12T10:59:50.923465Z","end":"2026-08-12T10:59:51.096756Z","steps":["trace[580220110] 'process raft request' (duration: 173.046705ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:56.707882Z","caller":"traceutil/trace.go:171","msg":"trace[324088376] linearizableReadLoop","detail":"{readStateIndex:2654; appliedIndex:2651; }","duration":"155.285633ms","start":"2026-08-12T10:59:56.55258Z","end":"2026-08-12T10:59:56.707866Z","steps":["trace[324088376] 'read index received' (duration: 154.564212ms)","trace[324088376] 'applied index is now lower than readState.Index' (duration: 720.941µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T10:59:56.707914Z","caller":"traceutil/trace.go:171","msg":"trace[563554160] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"200.783004ms","start":"2026-08-12T10:59:56.507108Z","end":"2026-08-12T10:59:56.707891Z","steps":["trace[563554160] 'process raft request' (duration: 200.699812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T10:59:56.708014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.430938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-12T10:59:56.708033Z","caller":"traceutil/trace.go:171","msg":"trace[1197851820] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2557; }","duration":"155.461709ms","start":"2026-08-12T10:59:56.552564Z","end":"2026-08-12T10:59:56.708026Z","steps":["trace[1197851820] 'agreement among raft nodes before linearized reading' (duration: 155.361895ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:56.708084Z","caller":"traceutil/trace.go:171","msg":"trace[1940153198] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"203.527737ms","start":"2026-08-12T10:59:56.504546Z","end":"2026-08-12T10:59:56.708074Z","steps":["trace[1940153198] 'process raft request' (duration: 202.599669ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T10:59:56.708159Z","caller":"traceutil/trace.go:171","msg":"trace[1790840494] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"198.415835ms","start":"2026-08-12T10:59:56.509733Z","end":"2026-08-12T10:59:56.708149Z","steps":["trace[1790840494] 'process raft request' (duration: 198.102945ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:00:22.957697Z","caller":"traceutil/trace.go:171","msg":"trace[1214240415] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"146.086037ms","start":"2026-08-12T11:00:22.811564Z","end":"2026-08-12T11:00:22.95765Z","steps":["trace[1214240415] 'process raft request' (duration: 146.018505ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:00:22.958002Z","caller":"traceutil/trace.go:171","msg":"trace[1247989254] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"147.509984ms","start":"2026-08-12T11:00:22.810224Z","end":"2026-08-12T11:00:22.957734Z","steps":["trace[1247989254] 'process raft request' (duration: 75.738294ms)","trace[1247989254] 'compare' (duration: 71.551685ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:00:23.589612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:24.090288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:24.591028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:25.09205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:25.592965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:26.094038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:26.254601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000230999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-12T11:00:26.254722Z","caller":"traceutil/trace.go:171","msg":"trace[1958168605] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000423704s","start":"2026-08-12T11:00:24.254276Z","end":"2026-08-12T11:00:26.2547Z","steps":["trace[1958168605] 'agreement among raft nodes before linearized reading' (duration: 2.00027671s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:26.254774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.254251Z","time spent":"2.000513846s","remote":"127.0.0.1:53036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T11:00:26.594715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:27.095683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:27.595848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:27.962103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:22.964115Z","time spent":"4.997980384s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-12T11:00:27.962122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:22.965354Z","time spent":"4.996763153s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/12 11:00:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:27.9621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:22.964947Z","time spent":"4.997145262s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/12 11:00:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:28.096448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:28.255564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000398681s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-12T11:00:28.255653Z","caller":"traceutil/trace.go:171","msg":"trace[1391826919] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000584065s","start":"2026-08-12T11:00:26.255045Z","end":"2026-08-12T11:00:28.255629Z","steps":["trace[1391826919] 'agreement among raft nodes before linearized reading' (duration: 2.000469083s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:28.255701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:26.255016Z","time spent":"2.000675208s","remote":"127.0.0.1:53030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/12 11:00:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:28.597522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:29.098192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:29.599301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149251,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:29.843113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.84522Z","time spent":"4.997886314s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/12 11:00:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:29.962254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.963714Z","time spent":"4.998530701s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/12 11:00:29 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:29.964376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:22.964262Z","time spent":"7.000106442s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-12T11:00:29.964427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:22.964353Z","time spent":"7.000069431s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-12T11:00:30.089747Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-08-12T11:00:30.090008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000987111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-08-12T11:00:30.090063Z","caller":"traceutil/trace.go:171","msg":"trace[33090886] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; }","duration":"7.001236878s","start":"2026-08-12T11:00:23.088811Z","end":"2026-08-12T11:00:30.090048Z","steps":["trace[33090886] 'agreement among raft nodes before linearized reading' (duration: 7.001033603s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:30.090109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:23.08875Z","time spent":"7.00134744s","remote":"127.0.0.1:53532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":0,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:30.263791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000231819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-12T11:00:30.263954Z","caller":"traceutil/trace.go:171","msg":"trace[189025480] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000449095s","start":"2026-08-12T11:00:28.263484Z","end":"2026-08-12T11:00:30.263933Z","steps":["trace[189025480] 'agreement among raft nodes before linearized reading' (duration: 2.00026511s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:30.264136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:28.263463Z","time spent":"2.00065931s","remote":"127.0.0.1:53036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/12 11:00:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:30.590144Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:31.090951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:31.523225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.52221Z","time spent":"7.001006587s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-12T11:00:31.591087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:31.591086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.590194Z","time spent":"7.000883519s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-12T11:00:31.964481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.963875Z","time spent":"7.000598217s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-12T11:00:32.091502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:32.264811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000189311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-12T11:00:32.264911Z","caller":"traceutil/trace.go:171","msg":"trace[988165005] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000365626s","start":"2026-08-12T11:00:30.264518Z","end":"2026-08-12T11:00:32.264883Z","steps":["trace[988165005] 'agreement among raft nodes before linearized reading' (duration: 2.000218362s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:32.264966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:30.264506Z","time spent":"2.000448948s","remote":"127.0.0.1:53030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/12 11:00:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:32.301983Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"2de29ff59d2d1e33","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-12T11:00:32.592018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:32.902833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.936717257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:32.902932Z","caller":"traceutil/trace.go:171","msg":"trace[650950507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.93684804s","start":"2026-08-12T11:00:29.96605Z","end":"2026-08-12T11:00:32.902898Z","steps":["trace[650950507] 'agreement among raft nodes before linearized reading' (duration: 2.936742787s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:32.903163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:29.966028Z","time spent":"2.937097166s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/12 11:00:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:32.917318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.212906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:32.917388Z","caller":"traceutil/trace.go:171","msg":"trace[1940155974] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; }","duration":"947.36011ms","start":"2026-08-12T11:00:31.970012Z","end":"2026-08-12T11:00:32.917372Z","steps":["trace[1940155974] 'agreement among raft nodes before linearized reading' (duration: 947.269837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:32.917504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:31.969986Z","time spent":"947.434972ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} 2026/08/12 11:00:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:32.925741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.092444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:32.925944Z","caller":"traceutil/trace.go:171","msg":"trace[687176819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"955.165386ms","start":"2026-08-12T11:00:31.970611Z","end":"2026-08-12T11:00:32.925777Z","steps":["trace[687176819] 'agreement among raft nodes before linearized reading' (duration: 955.112615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:32.926188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:31.970603Z","time spent":"955.383451ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/08/12 11:00:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:32.931883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.965680542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:32.931932Z","caller":"traceutil/trace.go:171","msg":"trace[1347803780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.965798244s","start":"2026-08-12T11:00:29.966123Z","end":"2026-08-12T11:00:32.931921Z","steps":["trace[1347803780] 'agreement among raft nodes before linearized reading' (duration: 2.965711692s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:32.931959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:29.966104Z","time spent":"2.965848956s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-12T11:00:32.931936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.967292693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} 2026/08/12 11:00:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-08-12T11:00:32.931992Z","caller":"traceutil/trace.go:171","msg":"trace[1681872736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.967372225s","start":"2026-08-12T11:00:29.964607Z","end":"2026-08-12T11:00:32.931979Z","steps":["trace[1681872736] 'agreement among raft nodes before linearized reading' (duration: 2.967304463s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:32.932039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:29.964604Z","time spent":"2.967425606s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/12 11:00:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:33.093165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:33.59449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:33.991989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:26.990832Z","time spent":"7.001149408s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-12T11:00:34.094859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:34.27262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99980868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:34.272692Z","caller":"traceutil/trace.go:171","msg":"trace[1439668899] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999938063s","start":"2026-08-12T11:00:32.272735Z","end":"2026-08-12T11:00:34.272674Z","steps":["trace[1439668899] 'agreement among raft nodes before linearized reading' (duration: 1.999843441s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:34.27273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:32.272711Z","time spent":"2.000011695s","remote":"127.0.0.1:53036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/12 11:00:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:34.354516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:27.353459Z","time spent":"7.001049967s","remote":"127.0.0.1:53196","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-12T11:00:34.580942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.9995933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:34.581041Z","caller":"traceutil/trace.go:171","msg":"trace[1787896890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.999874127s","start":"2026-08-12T11:00:24.581147Z","end":"2026-08-12T11:00:34.581021Z","steps":["trace[1787896890] 'agreement among raft nodes before linearized reading' (duration: 9.999624001s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:34.581213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.581131Z","time spent":"10.0000356s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/08/12 11:00:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:34.595393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:34.843182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998976694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-12T11:00:34.843271Z","caller":"traceutil/trace.go:171","msg":"trace[619034363] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; }","duration":"4.999111797s","start":"2026-08-12T11:00:29.844138Z","end":"2026-08-12T11:00:34.84325Z","steps":["trace[619034363] 'agreement among raft nodes before linearized reading' (duration: 4.998997665s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:34.843322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:29.844125Z","time spent":"4.999187139s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} 2026/08/12 11:00:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:34.86728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.204244137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:34.867414Z","caller":"traceutil/trace.go:171","msg":"trace[617905285] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; }","duration":"8.204450962s","start":"2026-08-12T11:00:26.662931Z","end":"2026-08-12T11:00:34.867382Z","steps":["trace[617905285] 'agreement among raft nodes before linearized reading' (duration: 8.204309459s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:34.867467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:26.662905Z","time spent":"8.204550005s","remote":"127.0.0.1:53208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":0,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} 2026/08/12 11:00:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:34.963801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99915303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:34.96392Z","caller":"traceutil/trace.go:171","msg":"trace[626395626] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.999324384s","start":"2026-08-12T11:00:29.964568Z","end":"2026-08-12T11:00:34.963893Z","steps":["trace[626395626] 'agreement among raft nodes before linearized reading' (duration: 4.999168971s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:34.963985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:29.964552Z","time spent":"4.999421596s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/08/12 11:00:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:34.969285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.000100259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:34.969377Z","caller":"traceutil/trace.go:171","msg":"trace[1965560103] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; }","duration":"3.000250202s","start":"2026-08-12T11:00:31.969106Z","end":"2026-08-12T11:00:34.969356Z","steps":["trace[1965560103] 'agreement among raft nodes before linearized reading' (duration: 3.00013605s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:34.969503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:31.969084Z","time spent":"3.000407097s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} 2026/08/12 11:00:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:35.096521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:35.596629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306380953696149265,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-12T11:00:36.016506Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"13.051329349s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-12T11:00:36.272932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00001822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-12T11:00:36.273137Z","caller":"traceutil/trace.go:171","msg":"trace[150709256] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000249307s","start":"2026-08-12T11:00:34.27286Z","end":"2026-08-12T11:00:36.273109Z","steps":["trace[150709256] 'agreement among raft nodes before linearized reading' (duration: 2.000012881s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.273605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:34.272799Z","time spent":"2.0007923s","remote":"127.0.0.1:53030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/12 11:00:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-12T11:00:36.397232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.399476429s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:00:36.397352Z","caller":"traceutil/trace.go:171","msg":"trace[2081113472] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2702; }","duration":"9.399608102s","start":"2026-08-12T11:00:26.997727Z","end":"2026-08-12T11:00:36.397335Z","steps":["trace[2081113472] 'range keys from in-memory index tree' (duration: 9.399458768s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.402651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.616463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-08-12T11:00:36.564253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.131415ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-08-12T11:00:36.564819Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"91.552µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"info","ts":"2026-08-12T11:00:36.564893Z","caller":"traceutil/trace.go:171","msg":"trace[498409644] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2714; }","duration":"5.03622649s","start":"2026-08-12T11:00:31.528638Z","end":"2026-08-12T11:00:36.564864Z","steps":["trace[498409644] 'process raft request' (duration: 5.036048525s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.565035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:31.52861Z","time spent":"5.036348753s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":722,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:00:36.565156Z","caller":"traceutil/trace.go:171","msg":"trace[1521909367] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2714; }","duration":"3.63307043s","start":"2026-08-12T11:00:32.932078Z","end":"2026-08-12T11:00:36.565149Z","steps":["trace[1521909367] 'process raft request' (duration: 3.632796954s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.565195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:32.932058Z","time spent":"3.633117921s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":48,"response count":0,"response size":474,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:00:36.565249Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"2de29ff59d2d1e33","error":"lease not found"} {"level":"info","ts":"2026-08-12T11:00:36.565288Z","caller":"traceutil/trace.go:171","msg":"trace[896332977] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2714; }","duration":"3.63271202s","start":"2026-08-12T11:00:32.932568Z","end":"2026-08-12T11:00:36.56528Z","steps":["trace[896332977] 'process raft request' (duration: 3.632477175s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.565325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:32.932561Z","time spent":"3.632741421s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":62,"response count":0,"response size":526,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:00:36.5654Z","caller":"traceutil/trace.go:171","msg":"trace[386860894] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2714; }","duration":"3.633261205s","start":"2026-08-12T11:00:32.932129Z","end":"2026-08-12T11:00:36.56539Z","steps":["trace[386860894] 'process raft request' (duration: 3.632883336s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.565444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:32.932113Z","time spent":"3.633311316s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":47,"response count":0,"response size":515,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:00:36.565572Z","caller":"traceutil/trace.go:171","msg":"trace[460052328] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2714; }","duration":"2.568694946s","start":"2026-08-12T11:00:33.996869Z","end":"2026-08-12T11:00:36.565564Z","steps":["trace[460052328] 'process raft request' (duration: 2.568226675s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.565612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:33.996848Z","time spent":"2.568745627s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":571,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:00:36.565734Z","caller":"traceutil/trace.go:171","msg":"trace[687039151] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"1.982113842s","start":"2026-08-12T11:00:34.58359Z","end":"2026-08-12T11:00:36.565704Z","steps":["trace[687039151] 'process raft request' (duration: 1.981567159s)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:00:36.565801Z","caller":"traceutil/trace.go:171","msg":"trace[449587056] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"1.599034019s","start":"2026-08-12T11:00:34.966758Z","end":"2026-08-12T11:00:36.565792Z","steps":["trace[449587056] 'process raft request' (duration: 1.598495836s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.56584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:34.966723Z","time spent":"1.599098699s","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-12T11:00:36.566122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:34.583562Z","time spent":"1.982238755s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:00:36.57735Z","caller":"traceutil/trace.go:171","msg":"trace[1703559308] linearizableReadLoop","detail":"{readStateIndex:2826; appliedIndex:2804; }","duration":"6.48749227s","start":"2026-08-12T11:00:30.089821Z","end":"2026-08-12T11:00:36.577313Z","steps":["trace[1703559308] 'read index received' (duration: 5.927125989s)","trace[1703559308] 'applied index is now lower than readState.Index' (duration: 560.363831ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:00:36.578017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.417270672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:00:36.57807Z","caller":"traceutil/trace.go:171","msg":"trace[583025171] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2717; }","duration":"13.417390126s","start":"2026-08-12T11:00:23.160667Z","end":"2026-08-12T11:00:36.578058Z","steps":["trace[583025171] 'agreement among raft nodes before linearized reading' (duration: 13.417235562s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.578106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:23.160642Z","time spent":"13.417452726s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.57815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.162322843s","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-08-12T11:00:36.578154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.289072072s","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-12T11:00:36.578232Z","caller":"traceutil/trace.go:171","msg":"trace[585834930] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2717; }","duration":"5.162494636s","start":"2026-08-12T11:00:31.415717Z","end":"2026-08-12T11:00:36.578212Z","steps":["trace[585834930] 'agreement among raft nodes before linearized reading' (duration: 5.162346433s)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:00:36.578264Z","caller":"traceutil/trace.go:171","msg":"trace[877788785] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2717; }","duration":"12.289229106s","start":"2026-08-12T11:00:24.289018Z","end":"2026-08-12T11:00:36.578247Z","steps":["trace[877788785] 'agreement among raft nodes before linearized reading' (duration: 12.289000781s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.578283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:31.415692Z","time spent":"5.162580109s","remote":"127.0.0.1:45756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.578307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.289002Z","time spent":"12.289297307s","remote":"127.0.0.1:53496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":12,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.578528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535088794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:00:36.578564Z","caller":"traceutil/trace.go:171","msg":"trace[759029678] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2717; }","duration":"1.535162816s","start":"2026-08-12T11:00:35.043394Z","end":"2026-08-12T11:00:36.578557Z","steps":["trace[759029678] 'agreement among raft nodes before linearized reading' (duration: 1.535102363s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.578589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:35.043371Z","time spent":"1.535210887s","remote":"127.0.0.1:53196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.578599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.822423814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:00:36.578648Z","caller":"traceutil/trace.go:171","msg":"trace[1497829998] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2717; }","duration":"3.822502066s","start":"2026-08-12T11:00:32.756125Z","end":"2026-08-12T11:00:36.578627Z","steps":["trace[1497829998] 'agreement among raft nodes before linearized reading' (duration: 3.822432305s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.578569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.170704ms","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":"warn","ts":"2026-08-12T11:00:36.578654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.559688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:00:36.578724Z","caller":"traceutil/trace.go:171","msg":"trace[1814444445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2717; }","duration":"295.668701ms","start":"2026-08-12T11:00:36.283046Z","end":"2026-08-12T11:00:36.578715Z","steps":["trace[1814444445] 'agreement among raft nodes before linearized reading' (duration: 295.583839ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:00:36.578779Z","caller":"traceutil/trace.go:171","msg":"trace[648933703] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2717; }","duration":"775.41302ms","start":"2026-08-12T11:00:35.803313Z","end":"2026-08-12T11:00:36.578726Z","steps":["trace[648933703] 'agreement among raft nodes before linearized reading' (duration: 774.799474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.578824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.815961343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:00:36.57892Z","caller":"traceutil/trace.go:171","msg":"trace[1011853566] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2717; }","duration":"4.816101646s","start":"2026-08-12T11:00:31.762809Z","end":"2026-08-12T11:00:36.578911Z","steps":["trace[1011853566] 'agreement among raft nodes before linearized reading' (duration: 4.815933422s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.578944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.593467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-08-12T11:00:36.578955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:31.762782Z","time spent":"4.816164708s","remote":"127.0.0.1:53236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":59,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:00:36.57902Z","caller":"traceutil/trace.go:171","msg":"trace[172734550] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2717; }","duration":"301.663178ms","start":"2026-08-12T11:00:36.277302Z","end":"2026-08-12T11:00:36.578965Z","steps":["trace[172734550] 'agreement among raft nodes before linearized reading' (duration: 301.589827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.579044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:36.27728Z","time spent":"301.758901ms","remote":"127.0.0.1:53208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7135,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-12T11:00:36.579034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578186408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:00:36.579071Z","caller":"traceutil/trace.go:171","msg":"trace[1189714659] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2717; }","duration":"1.57827105s","start":"2026-08-12T11:00:35.000791Z","end":"2026-08-12T11:00:36.579062Z","steps":["trace[1189714659] 'agreement among raft nodes before linearized reading' (duration: 1.577920781s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.579111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:35.000769Z","time spent":"1.578329971s","remote":"127.0.0.1:53548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":278,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.579159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.98367044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-12T11:00:36.579195Z","caller":"traceutil/trace.go:171","msg":"trace[1714263264] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:2717; }","duration":"4.983779942s","start":"2026-08-12T11:00:31.595407Z","end":"2026-08-12T11:00:36.579187Z","steps":["trace[1714263264] 'agreement among raft nodes before linearized reading' (duration: 4.983656999s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.579217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:31.595382Z","time spent":"4.983829364s","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":514,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-08-12T11:00:36.578827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:35.803295Z","time spent":"775.524033ms","remote":"127.0.0.1:53176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-12T11:00:36.579378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.470658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T11:00:36.579421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.221304233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9281"} {"level":"info","ts":"2026-08-12T11:00:36.579463Z","caller":"traceutil/trace.go:171","msg":"trace[1932494814] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2717; }","duration":"2.221361685s","start":"2026-08-12T11:00:34.35809Z","end":"2026-08-12T11:00:36.579451Z","steps":["trace[1932494814] 'agreement among raft nodes before linearized reading' (duration: 2.221198191s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.579496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:34.358075Z","time spent":"2.221414596s","remote":"127.0.0.1:53196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9305,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-12T11:00:36.579466Z","caller":"traceutil/trace.go:171","msg":"trace[402744821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2717; }","duration":"350.542489ms","start":"2026-08-12T11:00:36.228852Z","end":"2026-08-12T11:00:36.579394Z","steps":["trace[402744821] 'agreement among raft nodes before linearized reading' (duration: 350.496318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.579542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:36.228836Z","time spent":"350.698074ms","remote":"127.0.0.1:45684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.579671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.564822272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:00:36.579731Z","caller":"traceutil/trace.go:171","msg":"trace[988844153] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2717; }","duration":"8.564886674s","start":"2026-08-12T11:00:28.014806Z","end":"2026-08-12T11:00:36.579693Z","steps":["trace[988844153] 'agreement among raft nodes before linearized reading' (duration: 8.564837542s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.579757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:28.014777Z","time spent":"8.564973756s","remote":"127.0.0.1:45968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.579771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.142626008s","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-08-12T11:00:36.579813Z","caller":"traceutil/trace.go:171","msg":"trace[1653241193] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2717; }","duration":"11.14271087s","start":"2026-08-12T11:00:25.437091Z","end":"2026-08-12T11:00:36.579802Z","steps":["trace[1653241193] 'agreement among raft nodes before linearized reading' (duration: 11.142641839s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.579846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:25.43706Z","time spent":"11.142776352s","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.579889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.284287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-12T11:00:36.579976Z","caller":"traceutil/trace.go:171","msg":"trace[249674162] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2717; }","duration":"449.422101ms","start":"2026-08-12T11:00:36.130547Z","end":"2026-08-12T11:00:36.579969Z","steps":["trace[249674162] 'agreement among raft nodes before linearized reading' (duration: 449.220575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.58002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:36.130526Z","time spent":"449.487643ms","remote":"127.0.0.1:53302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-12T11:00:36.579973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.577671105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:00:36.580117Z","caller":"traceutil/trace.go:171","msg":"trace[1690202545] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:0; response_revision:2717; }","duration":"6.57783649s","start":"2026-08-12T11:00:30.002273Z","end":"2026-08-12T11:00:36.58011Z","steps":["trace[1690202545] 'agreement among raft nodes before linearized reading' (duration: 6.577676976s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.580252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:30.002262Z","time spent":"6.577982533s","remote":"127.0.0.1:53056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/199.19.213.128\" "} {"level":"warn","ts":"2026-08-12T11:00:36.580358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.81666997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:00:36.580373Z","caller":"traceutil/trace.go:171","msg":"trace[667301059] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2717; }","duration":"6.816722592s","start":"2026-08-12T11:00:29.763645Z","end":"2026-08-12T11:00:36.580368Z","steps":["trace[667301059] 'agreement among raft nodes before linearized reading' (duration: 6.816689711s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.578687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:32.756101Z","time spent":"3.822576358s","remote":"127.0.0.1:57790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.580766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.722965012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18cb09c2e53e72b6\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-12T11:00:36.581529Z","caller":"traceutil/trace.go:171","msg":"trace[1991477553] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18cb09c2e53e72b6; range_end:; response_count:1; response_revision:2717; }","duration":"11.723771052s","start":"2026-08-12T11:00:24.85775Z","end":"2026-08-12T11:00:36.581521Z","steps":["trace[1991477553] 'agreement among raft nodes before linearized reading' (duration: 11.722950062s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.581583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.857729Z","time spent":"11.723843344s","remote":"127.0.0.1:53098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18cb09c2e53e72b6\" "} {"level":"warn","ts":"2026-08-12T11:00:36.581715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:29.763611Z","time spent":"6.818096565s","remote":"127.0.0.1:53426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.580983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.911344671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:00:36.581858Z","caller":"traceutil/trace.go:171","msg":"trace[1378611901] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2717; }","duration":"11.912263254s","start":"2026-08-12T11:00:24.669587Z","end":"2026-08-12T11:00:36.58185Z","steps":["trace[1378611901] 'agreement among raft nodes before linearized reading' (duration: 11.908289416s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.581878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.669516Z","time spent":"11.912353947s","remote":"127.0.0.1:57116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:00:36.582281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.028757078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-12T11:00:36.582313Z","caller":"traceutil/trace.go:171","msg":"trace[1476643544] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2717; }","duration":"12.02883678s","start":"2026-08-12T11:00:24.553469Z","end":"2026-08-12T11:00:36.582305Z","steps":["trace[1476643544] 'agreement among raft nodes before linearized reading' (duration: 12.024505194s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.582329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:24.553457Z","time spent":"12.02886726s","remote":"127.0.0.1:53222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-12T11:00:36.577856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.507005872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:00:36.584395Z","caller":"traceutil/trace.go:171","msg":"trace[787535459] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2717; }","duration":"6.513608735s","start":"2026-08-12T11:00:30.070767Z","end":"2026-08-12T11:00:36.584376Z","steps":["trace[787535459] 'agreement among raft nodes before linearized reading' (duration: 6.507004252s)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:00:36.584747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:00:30.070752Z","time spent":"6.513974313s","remote":"127.0.0.1:45592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:04:08.653239Z","caller":"traceutil/trace.go:171","msg":"trace[2074035892] transaction","detail":"{read_only:false; response_revision:4510; number_of_response:1; }","duration":"116.234411ms","start":"2026-08-12T11:04:08.536976Z","end":"2026-08-12T11:04:08.653211Z","steps":["trace[2074035892] 'process raft request' (duration: 67.515661ms)","trace[2074035892] 'compare' (duration: 48.541517ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:17.88587Z","caller":"traceutil/trace.go:171","msg":"trace[947028382] linearizableReadLoop","detail":"{readStateIndex:4844; appliedIndex:4843; }","duration":"104.335732ms","start":"2026-08-12T11:04:17.781513Z","end":"2026-08-12T11:04:17.885849Z","steps":["trace[947028382] 'read index received' (duration: 104.18753ms)","trace[947028382] 'applied index is now lower than readState.Index' (duration: 147.462µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:17.886002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.470754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:17.886035Z","caller":"traceutil/trace.go:171","msg":"trace[807477907] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:4660; }","duration":"104.542226ms","start":"2026-08-12T11:04:17.781482Z","end":"2026-08-12T11:04:17.886024Z","steps":["trace[807477907] 'agreement among raft nodes before linearized reading' (duration: 104.477255ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:17.886232Z","caller":"traceutil/trace.go:171","msg":"trace[908711201] transaction","detail":"{read_only:false; response_revision:4660; number_of_response:1; }","duration":"108.551179ms","start":"2026-08-12T11:04:17.777663Z","end":"2026-08-12T11:04:17.886214Z","steps":["trace[908711201] 'process raft request' (duration: 108.057302ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:18.045531Z","caller":"traceutil/trace.go:171","msg":"trace[840797819] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"109.302898ms","start":"2026-08-12T11:04:17.936154Z","end":"2026-08-12T11:04:18.045457Z","steps":["trace[840797819] 'process raft request' (duration: 109.098156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:18.481188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.754706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:04:18.481314Z","caller":"traceutil/trace.go:171","msg":"trace[838923531] linearizableReadLoop","detail":"{readStateIndex:4851; appliedIndex:4850; }","duration":"220.189514ms","start":"2026-08-12T11:04:18.261102Z","end":"2026-08-12T11:04:18.481292Z","steps":["trace[838923531] 'read index received' (duration: 39.174194ms)","trace[838923531] 'applied index is now lower than readState.Index' (duration: 181.01303ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:18.481388Z","caller":"traceutil/trace.go:171","msg":"trace[938435815] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"220.65233ms","start":"2026-08-12T11:04:18.260658Z","end":"2026-08-12T11:04:18.481311Z","steps":["trace[938435815] 'process raft request' (duration: 39.672501ms)","trace[938435815] 'compare' (duration: 180.466682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:18.481846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.72928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-172dd7cf-e907-40f2-bd7c-1acc558e90c0\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-08-12T11:04:18.481948Z","caller":"traceutil/trace.go:171","msg":"trace[345489533] range","detail":"{range_begin:/registry/persistentvolumes/pvc-172dd7cf-e907-40f2-bd7c-1acc558e90c0; range_end:; response_count:1; response_revision:4666; }","duration":"220.814442ms","start":"2026-08-12T11:04:18.261079Z","end":"2026-08-12T11:04:18.481894Z","steps":["trace[345489533] 'agreement among raft nodes before linearized reading' (duration: 220.310506ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:18.501878Z","caller":"traceutil/trace.go:171","msg":"trace[2016606479] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"231.145577ms","start":"2026-08-12T11:04:18.270709Z","end":"2026-08-12T11:04:18.501854Z","steps":["trace[2016606479] 'process raft request' (duration: 230.961035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:18.502192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.64613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:18.502222Z","caller":"traceutil/trace.go:171","msg":"trace[367678047] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:0; response_revision:4667; }","duration":"239.710651ms","start":"2026-08-12T11:04:18.262503Z","end":"2026-08-12T11:04:18.502214Z","steps":["trace[367678047] 'agreement among raft nodes before linearized reading' (duration: 239.64917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:04:18.502743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.906107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:04:18.502905Z","caller":"traceutil/trace.go:171","msg":"trace[311869220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4667; }","duration":"111.161721ms","start":"2026-08-12T11:04:18.391704Z","end":"2026-08-12T11:04:18.502865Z","steps":["trace[311869220] 'agreement among raft nodes before linearized reading' (duration: 110.422442ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:29.797192Z","caller":"traceutil/trace.go:171","msg":"trace[2033291132] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"114.31802ms","start":"2026-08-12T11:04:29.682841Z","end":"2026-08-12T11:04:29.797159Z","steps":["trace[2033291132] 'process raft request' (duration: 114.051277ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:29.982012Z","caller":"traceutil/trace.go:171","msg":"trace[717325602] linearizableReadLoop","detail":"{readStateIndex:5068; appliedIndex:5067; }","duration":"105.535117ms","start":"2026-08-12T11:04:29.876446Z","end":"2026-08-12T11:04:29.981981Z","steps":["trace[717325602] 'read index received' (duration: 86.126265ms)","trace[717325602] 'applied index is now lower than readState.Index' (duration: 19.407612ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:04:29.982268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.81041ms","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-08-12T11:04:29.982303Z","caller":"traceutil/trace.go:171","msg":"trace[755430696] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4878; }","duration":"105.89126ms","start":"2026-08-12T11:04:29.876402Z","end":"2026-08-12T11:04:29.982293Z","steps":["trace[755430696] 'agreement among raft nodes before linearized reading' (duration: 105.697308ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:29.98243Z","caller":"traceutil/trace.go:171","msg":"trace[34411471] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"178.926266ms","start":"2026-08-12T11:04:29.803473Z","end":"2026-08-12T11:04:29.982399Z","steps":["trace[34411471] 'process raft request' (duration: 159.081939ms)","trace[34411471] 'compare' (duration: 19.28292ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:54.570542Z","caller":"traceutil/trace.go:171","msg":"trace[2012583856] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"145.553495ms","start":"2026-08-12T11:04:54.424961Z","end":"2026-08-12T11:04:54.570514Z","steps":["trace[2012583856] 'process raft request' (duration: 145.380813ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:04:54.665506Z","caller":"traceutil/trace.go:171","msg":"trace[1284378851] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"239.20763ms","start":"2026-08-12T11:04:54.426272Z","end":"2026-08-12T11:04:54.66548Z","steps":["trace[1284378851] 'process raft request' (duration: 208.446934ms)","trace[1284378851] 'compare' (duration: 30.586654ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:04:54.665743Z","caller":"traceutil/trace.go:171","msg":"trace[1985841311] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"239.344452ms","start":"2026-08-12T11:04:54.426356Z","end":"2026-08-12T11:04:54.6657Z","steps":["trace[1985841311] 'process raft request' (duration: 239.082499ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:05:47.09038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2790} {"level":"info","ts":"2026-08-12T11:05:47.159199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2790,"took":"67.320752ms","hash":465781055,"current-db-size-bytes":53313536,"current-db-size":"53 MB","current-db-size-in-use-bytes":29614080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-12T11:05:47.159375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":465781055,"revision":2790,"compact-revision":-1} {"level":"info","ts":"2026-08-12T11:08:14.762852Z","caller":"traceutil/trace.go:171","msg":"trace[890221029] transaction","detail":"{read_only:false; response_revision:6518; number_of_response:1; }","duration":"116.28781ms","start":"2026-08-12T11:08:14.646537Z","end":"2026-08-12T11:08:14.762825Z","steps":["trace[890221029] 'process raft request' (duration: 116.125515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:08:48.045224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.510846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:08:48.045366Z","caller":"traceutil/trace.go:171","msg":"trace[1155981979] transaction","detail":"{read_only:false; response_revision:6744; number_of_response:1; }","duration":"176.065231ms","start":"2026-08-12T11:08:47.869259Z","end":"2026-08-12T11:08:48.045325Z","steps":["trace[1155981979] 'process raft request' (duration: 45.293518ms)","trace[1155981979] 'compare' (duration: 130.369591ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:10:47.096289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5510} {"level":"info","ts":"2026-08-12T11:10:47.178707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5510,"took":"81.256752ms","hash":629688069,"current-db-size-bytes":53313536,"current-db-size":"53 MB","current-db-size-in-use-bytes":18616320,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-12T11:10:47.178852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":629688069,"revision":5510,"compact-revision":2790}