{"level":"warn","ts":"2026-02-27T14:48:23.337515Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T14:48:23.337669Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.175: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.175:2380","--initial-cluster=instance=https://199.19.213.175:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.175:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.175: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-02-27T14:48:23.337769Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T14:48:23.337787Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-02-27T14:48:23.337819Z","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-02-27T14:48:23.338682Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"]} {"level":"info","ts":"2026-02-27T14:48:23.338791Z","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":16,"max-cpu-available":16,"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.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.175: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-02-27T14:48:23.343398Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.309841ms"} {"level":"info","ts":"2026-02-27T14:48:23.349513Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"223c7693b78843e1","cluster-id":"b697af90308fdc01"} {"level":"info","ts":"2026-02-27T14:48:23.349582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T14:48:23.349605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 0"} {"level":"info","ts":"2026-02-27T14:48:23.34962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 223c7693b78843e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T14:48:23.349632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became follower at term 1"} {"level":"info","ts":"2026-02-27T14:48:23.349675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"warn","ts":"2026-02-27T14:48:23.354684Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T14:48:23.357536Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T14:48:23.359171Z","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-02-27T14:48:23.3615Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"223c7693b78843e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T14:48:23.361673Z","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-02-27T14:48:23.361746Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"223c7693b78843e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T14:48:23.361866Z","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-02-27T14:48:23.361928Z","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-02-27T14:48:23.363051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 switched to configuration voters=(2466977072703620065)"} {"level":"info","ts":"2026-02-27T14:48:23.363267Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","added-peer-id":"223c7693b78843e1","added-peer-peer-urls":["https://199.19.213.175:2380"]} {"level":"info","ts":"2026-02-27T14:48:23.365013Z","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-02-27T14:48:23.365208Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-02-27T14:48:23.365259Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.175:2380"} {"level":"info","ts":"2026-02-27T14:48:23.365261Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"223c7693b78843e1","initial-advertise-peer-urls":["https://199.19.213.175:2380"],"listen-peer-urls":["https://199.19.213.175:2380"],"advertise-client-urls":["https://199.19.213.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T14:48:23.365341Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T14:48:24.150735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T14:48:24.150815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T14:48:24.150874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgPreVoteResp from 223c7693b78843e1 at term 1"} {"level":"info","ts":"2026-02-27T14:48:24.150897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became candidate at term 2"} {"level":"info","ts":"2026-02-27T14:48:24.15091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 received MsgVoteResp from 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-02-27T14:48:24.150929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"223c7693b78843e1 became leader at term 2"} {"level":"info","ts":"2026-02-27T14:48:24.150952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 223c7693b78843e1 elected leader 223c7693b78843e1 at term 2"} {"level":"info","ts":"2026-02-27T14:48:24.15224Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T14:48:24.153392Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T14:48:24.153357Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"223c7693b78843e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.175:2379]}","request-path":"/0/members/223c7693b78843e1/attributes","cluster-id":"b697af90308fdc01","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T14:48:24.15352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T14:48:24.153762Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b697af90308fdc01","local-member-id":"223c7693b78843e1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T14:48:24.1538Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T14:48:24.153868Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T14:48:24.153939Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T14:48:24.154005Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T14:48:24.156988Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.175:2379"} {"level":"info","ts":"2026-02-27T14:48:24.157964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-27T14:48:39.121937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.139809ms","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-02-27T14:48:39.12206Z","caller":"traceutil/trace.go:171","msg":"trace[780283724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:369; }","duration":"144.375544ms","start":"2026-02-27T14:48:38.977663Z","end":"2026-02-27T14:48:39.122038Z","steps":["trace[780283724] 'range keys from in-memory index tree' (duration: 143.952077ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:39.229829Z","caller":"traceutil/trace.go:171","msg":"trace[970140466] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"101.165278ms","start":"2026-02-27T14:48:39.128637Z","end":"2026-02-27T14:48:39.229803Z","steps":["trace[970140466] 'read index received' (duration: 100.839082ms)","trace[970140466] 'applied index is now lower than readState.Index' (duration: 325.246µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:48:39.229973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.31832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4795"} {"level":"info","ts":"2026-02-27T14:48:39.229958Z","caller":"traceutil/trace.go:171","msg":"trace[1369993094] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"103.360239ms","start":"2026-02-27T14:48:39.126549Z","end":"2026-02-27T14:48:39.229909Z","steps":["trace[1369993094] 'process raft request' (duration: 103.092484ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:39.230018Z","caller":"traceutil/trace.go:171","msg":"trace[770573182] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:370; }","duration":"101.421993ms","start":"2026-02-27T14:48:39.128579Z","end":"2026-02-27T14:48:39.230001Z","steps":["trace[770573182] 'agreement among raft nodes before linearized reading' (duration: 101.326381ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:39.404163Z","caller":"traceutil/trace.go:171","msg":"trace[1176474884] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"167.007536ms","start":"2026-02-27T14:48:39.237111Z","end":"2026-02-27T14:48:39.404118Z","steps":["trace[1176474884] 'process raft request' (duration: 166.490456ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:53.633723Z","caller":"traceutil/trace.go:171","msg":"trace[574472397] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"160.994235ms","start":"2026-02-27T14:48:53.472687Z","end":"2026-02-27T14:48:53.633681Z","steps":["trace[574472397] 'process raft request' (duration: 160.431524ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:48:55.438917Z","caller":"traceutil/trace.go:171","msg":"trace[1082918713] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"108.504995ms","start":"2026-02-27T14:48:55.33038Z","end":"2026-02-27T14:48:55.438885Z","steps":["trace[1082918713] 'process raft request' (duration: 108.320702ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:01.995939Z","caller":"traceutil/trace.go:171","msg":"trace[2016966256] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"211.319841ms","start":"2026-02-27T14:49:01.784586Z","end":"2026-02-27T14:49:01.995906Z","steps":["trace[2016966256] 'process raft request' (duration: 211.126347ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:02.37027Z","caller":"traceutil/trace.go:171","msg":"trace[248825698] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"122.611418ms","start":"2026-02-27T14:49:02.247626Z","end":"2026-02-27T14:49:02.370237Z","steps":["trace[248825698] 'process raft request' (duration: 121.956136ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:14.27659Z","caller":"traceutil/trace.go:171","msg":"trace[923962283] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"117.144847ms","start":"2026-02-27T14:49:14.159419Z","end":"2026-02-27T14:49:14.276564Z","steps":["trace[923962283] 'process raft request' (duration: 84.003742ms)","trace[923962283] 'compare' (duration: 33.026432ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:14.326115Z","caller":"traceutil/trace.go:171","msg":"trace[1879115293] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"161.092644ms","start":"2026-02-27T14:49:14.164512Z","end":"2026-02-27T14:49:14.325605Z","steps":["trace[1879115293] 'process raft request' (duration: 160.976712ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:49:14.501366Z","caller":"traceutil/trace.go:171","msg":"trace[2030823262] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"101.781677ms","start":"2026-02-27T14:49:14.39955Z","end":"2026-02-27T14:49:14.501331Z","steps":["trace[2030823262] 'process raft request' (duration: 89.126219ms)","trace[2030823262] 'compare' (duration: 12.409953ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:49:14.687427Z","caller":"traceutil/trace.go:171","msg":"trace[1456307294] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"107.841321ms","start":"2026-02-27T14:49:14.579523Z","end":"2026-02-27T14:49:14.687364Z","steps":["trace[1456307294] 'process raft request' (duration: 107.621147ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:15.921682Z","caller":"traceutil/trace.go:171","msg":"trace[105755062] linearizableReadLoop","detail":"{readStateIndex:1590; appliedIndex:1589; }","duration":"353.307823ms","start":"2026-02-27T14:50:15.568344Z","end":"2026-02-27T14:50:15.921651Z","steps":["trace[105755062] 'read index received' (duration: 353.107199ms)","trace[105755062] 'applied index is now lower than readState.Index' (duration: 199.054µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:50:15.921748Z","caller":"traceutil/trace.go:171","msg":"trace[85957027] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"442.408953ms","start":"2026-02-27T14:50:15.479301Z","end":"2026-02-27T14:50:15.92171Z","steps":["trace[85957027] 'process raft request' (duration: 442.178389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:50:15.92203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.591536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9033"} {"level":"warn","ts":"2026-02-27T14:50:15.92203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.664001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-02-27T14:50:15.922083Z","caller":"traceutil/trace.go:171","msg":"trace[63189791] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1543; }","duration":"220.699927ms","start":"2026-02-27T14:50:15.701369Z","end":"2026-02-27T14:50:15.922069Z","steps":["trace[63189791] 'agreement among raft nodes before linearized reading' (duration: 220.486633ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:15.922102Z","caller":"traceutil/trace.go:171","msg":"trace[438839984] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1543; }","duration":"353.800243ms","start":"2026-02-27T14:50:15.568281Z","end":"2026-02-27T14:50:15.922081Z","steps":["trace[438839984] 'agreement among raft nodes before linearized reading' (duration: 353.531418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:50:15.92214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:50:15.56825Z","time spent":"353.879914ms","remote":"127.0.0.1:58668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-27T14:50:15.922234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.882237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca467963-5184-497e-97c3-eb083770cca5\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-02-27T14:50:15.922233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.735419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T14:50:15.922347Z","caller":"traceutil/trace.go:171","msg":"trace[79234952] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca467963-5184-497e-97c3-eb083770cca5; range_end:; response_count:1; response_revision:1543; }","duration":"136.02046ms","start":"2026-02-27T14:50:15.786298Z","end":"2026-02-27T14:50:15.922318Z","steps":["trace[79234952] 'agreement among raft nodes before linearized reading' (duration: 135.637202ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:15.922368Z","caller":"traceutil/trace.go:171","msg":"trace[765653609] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1543; }","duration":"171.944003ms","start":"2026-02-27T14:50:15.750395Z","end":"2026-02-27T14:50:15.922339Z","steps":["trace[765653609] 'agreement among raft nodes before linearized reading' (duration: 171.638847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:50:15.922476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:50:15.47928Z","time spent":"442.556047ms","remote":"127.0.0.1:59852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T14:50:16.04872Z","caller":"traceutil/trace.go:171","msg":"trace[1025789590] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"114.516147ms","start":"2026-02-27T14:50:15.934156Z","end":"2026-02-27T14:50:16.048672Z","steps":["trace[1025789590] 'process raft request' (duration: 86.013382ms)","trace[1025789590] 'compare' (duration: 28.294061ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:50:16.212614Z","caller":"traceutil/trace.go:171","msg":"trace[50109051] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"158.105615ms","start":"2026-02-27T14:50:16.054472Z","end":"2026-02-27T14:50:16.212577Z","steps":["trace[50109051] 'process raft request' (duration: 134.273748ms)","trace[50109051] 'compare' (duration: 23.584942ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:50:16.212673Z","caller":"traceutil/trace.go:171","msg":"trace[1531696456] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"157.89324ms","start":"2026-02-27T14:50:16.054756Z","end":"2026-02-27T14:50:16.212649Z","steps":["trace[1531696456] 'process raft request' (duration: 157.737907ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:16.21298Z","caller":"traceutil/trace.go:171","msg":"trace[501322366] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"157.751378ms","start":"2026-02-27T14:50:16.055197Z","end":"2026-02-27T14:50:16.212948Z","steps":["trace[501322366] 'process raft request' (duration: 157.380331ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:16.213391Z","caller":"traceutil/trace.go:171","msg":"trace[903735002] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"157.88804ms","start":"2026-02-27T14:50:16.055461Z","end":"2026-02-27T14:50:16.213349Z","steps":["trace[903735002] 'process raft request' (duration: 157.37385ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:24.61421Z","caller":"traceutil/trace.go:171","msg":"trace[130239845] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"106.333214ms","start":"2026-02-27T14:50:24.507849Z","end":"2026-02-27T14:50:24.614183Z","steps":["trace[130239845] 'process raft request' (duration: 106.15658ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:53.422466Z","caller":"traceutil/trace.go:171","msg":"trace[1948911200] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"143.759025ms","start":"2026-02-27T14:50:53.278676Z","end":"2026-02-27T14:50:53.422435Z","steps":["trace[1948911200] 'read index received' (duration: 143.48074ms)","trace[1948911200] 'applied index is now lower than readState.Index' (duration: 276.885µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:50:53.42256Z","caller":"traceutil/trace.go:171","msg":"trace[84121368] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"147.995315ms","start":"2026-02-27T14:50:53.274528Z","end":"2026-02-27T14:50:53.422523Z","steps":["trace[84121368] 'process raft request' (duration: 147.70419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:50:53.422829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.096812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"warn","ts":"2026-02-27T14:50:53.422841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.625498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T14:50:53.422904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.696041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T14:50:53.422974Z","caller":"traceutil/trace.go:171","msg":"trace[1517983280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1831; }","duration":"122.818653ms","start":"2026-02-27T14:50:53.300136Z","end":"2026-02-27T14:50:53.422954Z","steps":["trace[1517983280] 'agreement among raft nodes before linearized reading' (duration: 122.62957ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:53.422961Z","caller":"traceutil/trace.go:171","msg":"trace[460085119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1831; }","duration":"131.854152ms","start":"2026-02-27T14:50:53.291084Z","end":"2026-02-27T14:50:53.422938Z","steps":["trace[460085119] 'agreement among raft nodes before linearized reading' (duration: 131.613027ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:53.422905Z","caller":"traceutil/trace.go:171","msg":"trace[299418351] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1831; }","duration":"144.242355ms","start":"2026-02-27T14:50:53.278644Z","end":"2026-02-27T14:50:53.422886Z","steps":["trace[299418351] 'agreement among raft nodes before linearized reading' (duration: 143.99722ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:59.068921Z","caller":"traceutil/trace.go:171","msg":"trace[2087223194] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"134.234467ms","start":"2026-02-27T14:50:58.934653Z","end":"2026-02-27T14:50:59.068887Z","steps":["trace[2087223194] 'process raft request' (duration: 134.042373ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:59.247637Z","caller":"traceutil/trace.go:171","msg":"trace[2065180971] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"173.38706ms","start":"2026-02-27T14:50:59.074222Z","end":"2026-02-27T14:50:59.247609Z","steps":["trace[2065180971] 'process raft request' (duration: 173.217887ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:59.462654Z","caller":"traceutil/trace.go:171","msg":"trace[238463820] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1932; }","duration":"300.705137ms","start":"2026-02-27T14:50:59.161898Z","end":"2026-02-27T14:50:59.462603Z","steps":["trace[238463820] 'read index received' (duration: 85.599764ms)","trace[238463820] 'applied index is now lower than readState.Index' (duration: 215.103423ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:50:59.462706Z","caller":"traceutil/trace.go:171","msg":"trace[71704384] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"369.745232ms","start":"2026-02-27T14:50:59.09293Z","end":"2026-02-27T14:50:59.462675Z","steps":["trace[71704384] 'process raft request' (duration: 314.933404ms)","trace[71704384] 'compare' (duration: 54.502392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:50:59.46294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.015594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T14:50:59.463034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.479354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-02-27T14:50:59.46309Z","caller":"traceutil/trace.go:171","msg":"trace[376827384] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"196.572135ms","start":"2026-02-27T14:50:59.266507Z","end":"2026-02-27T14:50:59.463079Z","steps":["trace[376827384] 'agreement among raft nodes before linearized reading' (duration: 196.388942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:50:59.463168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.430973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:50:59.463199Z","caller":"traceutil/trace.go:171","msg":"trace[878735788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1868; }","duration":"172.496414ms","start":"2026-02-27T14:50:59.290694Z","end":"2026-02-27T14:50:59.463191Z","steps":["trace[878735788] 'agreement among raft nodes before linearized reading' (duration: 172.446933ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:50:59.463037Z","caller":"traceutil/trace.go:171","msg":"trace[1171590584] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1868; }","duration":"301.181027ms","start":"2026-02-27T14:50:59.161837Z","end":"2026-02-27T14:50:59.463018Z","steps":["trace[1171590584] 'agreement among raft nodes before linearized reading' (duration: 300.944163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:50:59.463452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:50:59.161822Z","time spent":"301.612645ms","remote":"127.0.0.1:59928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":21,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-27T14:50:59.462933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:50:59.092904Z","time spent":"369.934556ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T14:50:59.688697Z","caller":"traceutil/trace.go:171","msg":"trace[198485475] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"136.56405ms","start":"2026-02-27T14:50:59.552077Z","end":"2026-02-27T14:50:59.688641Z","steps":["trace[198485475] 'process raft request' (duration: 133.690256ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:51:35.787726Z","caller":"traceutil/trace.go:171","msg":"trace[1734029054] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2230; }","duration":"498.083574ms","start":"2026-02-27T14:51:35.289604Z","end":"2026-02-27T14:51:35.787687Z","steps":["trace[1734029054] 'read index received' (duration: 497.84795ms)","trace[1734029054] 'applied index is now lower than readState.Index' (duration: 230.694µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:51:35.787877Z","caller":"traceutil/trace.go:171","msg":"trace[251548510] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"517.401636ms","start":"2026-02-27T14:51:35.270376Z","end":"2026-02-27T14:51:35.787777Z","steps":["trace[251548510] 'process raft request' (duration: 517.07878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:51:35.788031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.38337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T14:51:35.788099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:51:35.270343Z","time spent":"517.63209ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:51:35.78812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.302659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-02-27T14:51:35.788148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.137902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T14:51:35.788174Z","caller":"traceutil/trace.go:171","msg":"trace[392759928] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2149; }","duration":"359.411411ms","start":"2026-02-27T14:51:35.428743Z","end":"2026-02-27T14:51:35.788155Z","steps":["trace[392759928] 'agreement among raft nodes before linearized reading' (duration: 359.185577ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:51:35.788172Z","caller":"traceutil/trace.go:171","msg":"trace[1931235140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2149; }","duration":"498.579983ms","start":"2026-02-27T14:51:35.289561Z","end":"2026-02-27T14:51:35.788141Z","steps":["trace[1931235140] 'agreement among raft nodes before linearized reading' (duration: 498.332039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:51:35.788186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.432186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-02-27T14:51:35.788214Z","caller":"traceutil/trace.go:171","msg":"trace[609479070] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2149; }","duration":"300.249114ms","start":"2026-02-27T14:51:35.487948Z","end":"2026-02-27T14:51:35.788197Z","steps":["trace[609479070] 'agreement among raft nodes before linearized reading' (duration: 300.00774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:51:35.788234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:51:35.42873Z","time spent":"359.489952ms","remote":"127.0.0.1:59982","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-02-27T14:51:35.78825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:51:35.28955Z","time spent":"498.689695ms","remote":"127.0.0.1:59718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T14:51:35.788263Z","caller":"traceutil/trace.go:171","msg":"trace[400177534] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2149; }","duration":"101.550748ms","start":"2026-02-27T14:51:35.68669Z","end":"2026-02-27T14:51:35.788241Z","steps":["trace[400177534] 'agreement among raft nodes before linearized reading' (duration: 101.329574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:51:35.788233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.371915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-27T14:51:35.788264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:51:35.487933Z","time spent":"300.321455ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-27T14:51:35.788388Z","caller":"traceutil/trace.go:171","msg":"trace[2104752286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2149; }","duration":"101.616929ms","start":"2026-02-27T14:51:35.686742Z","end":"2026-02-27T14:51:35.788359Z","steps":["trace[2104752286] 'agreement among raft nodes before linearized reading' (duration: 101.212242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:51:35.78852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.767929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-02-27T14:51:35.788665Z","caller":"traceutil/trace.go:171","msg":"trace[225016771] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2149; }","duration":"336.951342ms","start":"2026-02-27T14:51:35.451675Z","end":"2026-02-27T14:51:35.788626Z","steps":["trace[225016771] 'agreement among raft nodes before linearized reading' (duration: 336.430663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:51:35.788729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:51:35.451667Z","time spent":"337.051454ms","remote":"127.0.0.1:59928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11520,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-02-27T14:52:06.57058Z","caller":"traceutil/trace.go:171","msg":"trace[2001540628] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"142.487933ms","start":"2026-02-27T14:52:06.42806Z","end":"2026-02-27T14:52:06.570548Z","steps":["trace[2001540628] 'process raft request' (duration: 142.3098ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:52:06.753184Z","caller":"traceutil/trace.go:171","msg":"trace[1600937373] transaction","detail":"{read_only:false; response_revision:2350; number_of_response:1; }","duration":"173.282744ms","start":"2026-02-27T14:52:06.579847Z","end":"2026-02-27T14:52:06.753129Z","steps":["trace[1600937373] 'process raft request' (duration: 162.109081ms)","trace[1600937373] 'compare' (duration: 10.949609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:52:07.164566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.540685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T14:52:07.164668Z","caller":"traceutil/trace.go:171","msg":"trace[1074649614] linearizableReadLoop","detail":"{readStateIndex:2446; appliedIndex:2445; }","duration":"297.2886ms","start":"2026-02-27T14:52:06.867362Z","end":"2026-02-27T14:52:07.164651Z","steps":["trace[1074649614] 'read index received' (duration: 138.337417ms)","trace[1074649614] 'applied index is now lower than readState.Index' (duration: 158.949553ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:52:07.164924Z","caller":"traceutil/trace.go:171","msg":"trace[908472206] transaction","detail":"{read_only:false; response_revision:2351; number_of_response:1; }","duration":"358.690138ms","start":"2026-02-27T14:52:06.806218Z","end":"2026-02-27T14:52:07.164908Z","steps":["trace[908472206] 'process raft request' (duration: 199.576302ms)","trace[908472206] 'compare' (duration: 158.420633ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:52:07.16496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.600336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"warn","ts":"2026-02-27T14:52:07.165002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:52:06.806181Z","time spent":"358.782299ms","remote":"127.0.0.1:60208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:52:07.16502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.613551ms","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-02-27T14:52:07.165142Z","caller":"traceutil/trace.go:171","msg":"trace[224174159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2351; }","duration":"283.786445ms","start":"2026-02-27T14:52:06.881329Z","end":"2026-02-27T14:52:07.165115Z","steps":["trace[224174159] 'agreement among raft nodes before linearized reading' (duration: 283.460419ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:52:07.16506Z","caller":"traceutil/trace.go:171","msg":"trace[2016132549] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2351; }","duration":"297.704658ms","start":"2026-02-27T14:52:06.867329Z","end":"2026-02-27T14:52:07.165034Z","steps":["trace[2016132549] 'agreement among raft nodes before linearized reading' (duration: 297.458664ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:52:07.512869Z","caller":"traceutil/trace.go:171","msg":"trace[1759467486] linearizableReadLoop","detail":"{readStateIndex:2447; appliedIndex:2446; }","duration":"340.67751ms","start":"2026-02-27T14:52:07.17215Z","end":"2026-02-27T14:52:07.512828Z","steps":["trace[1759467486] 'read index received' (duration: 275.578065ms)","trace[1759467486] 'applied index is now lower than readState.Index' (duration: 65.097765ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:52:07.512981Z","caller":"traceutil/trace.go:171","msg":"trace[1190866434] transaction","detail":"{read_only:false; response_revision:2352; number_of_response:1; }","duration":"341.356592ms","start":"2026-02-27T14:52:07.171591Z","end":"2026-02-27T14:52:07.512947Z","steps":["trace[1190866434] 'process raft request' (duration: 276.202516ms)","trace[1190866434] 'compare' (duration: 64.867551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:52:07.513141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:52:07.171564Z","time spent":"341.489185ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T14:52:07.513177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.001326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1249"} {"level":"info","ts":"2026-02-27T14:52:07.513407Z","caller":"traceutil/trace.go:171","msg":"trace[178046014] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2352; }","duration":"341.25636ms","start":"2026-02-27T14:52:07.172133Z","end":"2026-02-27T14:52:07.513389Z","steps":["trace[178046014] 'agreement among raft nodes before linearized reading' (duration: 340.908394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:52:07.513455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:52:07.172126Z","time spent":"341.311181ms","remote":"127.0.0.1:59930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1272,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-02-27T14:52:07.51345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.537342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.175\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-27T14:52:07.513468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.641581ms","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-02-27T14:52:07.513618Z","caller":"traceutil/trace.go:171","msg":"trace[1019398841] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:2352; }","duration":"339.838655ms","start":"2026-02-27T14:52:07.173748Z","end":"2026-02-27T14:52:07.513587Z","steps":["trace[1019398841] 'agreement among raft nodes before linearized reading' (duration: 339.5979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:52:07.513682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:52:07.173728Z","time spent":"339.941986ms","remote":"127.0.0.1:59830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"warn","ts":"2026-02-27T14:52:07.513525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.079166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-02-27T14:52:07.514039Z","caller":"traceutil/trace.go:171","msg":"trace[2060950413] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2352; }","duration":"203.603545ms","start":"2026-02-27T14:52:07.310396Z","end":"2026-02-27T14:52:07.513999Z","steps":["trace[2060950413] 'agreement among raft nodes before linearized reading' (duration: 202.991584ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:52:07.513532Z","caller":"traceutil/trace.go:171","msg":"trace[1525938633] range","detail":"{range_begin:/registry/masterleases/199.19.213.175; range_end:; response_count:1; response_revision:2352; }","duration":"221.679294ms","start":"2026-02-27T14:52:07.291839Z","end":"2026-02-27T14:52:07.513518Z","steps":["trace[1525938633] 'agreement among raft nodes before linearized reading' (duration: 221.502201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:52:07.514746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.660663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-27T14:52:07.514866Z","caller":"traceutil/trace.go:171","msg":"trace[450339531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2352; }","duration":"316.809805ms","start":"2026-02-27T14:52:07.198031Z","end":"2026-02-27T14:52:07.514841Z","steps":["trace[450339531] 'agreement among raft nodes before linearized reading' (duration: 315.333939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:52:07.51554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:52:07.197963Z","time spent":"317.548909ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T14:54:18.781609Z","caller":"traceutil/trace.go:171","msg":"trace[1247156379] transaction","detail":"{read_only:false; response_revision:3746; number_of_response:1; }","duration":"115.415858ms","start":"2026-02-27T14:54:18.666159Z","end":"2026-02-27T14:54:18.781575Z","steps":["trace[1247156379] 'process raft request' (duration: 115.118662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:18.782053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.433091ms","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-02-27T14:54:18.782172Z","caller":"traceutil/trace.go:171","msg":"trace[1339695787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3747; }","duration":"135.589004ms","start":"2026-02-27T14:54:18.646555Z","end":"2026-02-27T14:54:18.782144Z","steps":["trace[1339695787] 'agreement among raft nodes before linearized reading' (duration: 135.202046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:24.435791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.768082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-02-27T14:54:24.435902Z","caller":"traceutil/trace.go:171","msg":"trace[1470833798] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:3872; }","duration":"134.902154ms","start":"2026-02-27T14:54:24.300966Z","end":"2026-02-27T14:54:24.435869Z","steps":["trace[1470833798] 'range keys from in-memory index tree' (duration: 134.623139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:24.436151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.19558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-02-27T14:54:24.436215Z","caller":"traceutil/trace.go:171","msg":"trace[1802938749] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:3872; }","duration":"127.310963ms","start":"2026-02-27T14:54:24.308888Z","end":"2026-02-27T14:54:24.436199Z","steps":["trace[1802938749] 'range keys from in-memory index tree' (duration: 127.1384ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:25.302851Z","caller":"traceutil/trace.go:171","msg":"trace[956299071] transaction","detail":"{read_only:false; response_revision:3909; number_of_response:1; }","duration":"221.518678ms","start":"2026-02-27T14:54:25.081302Z","end":"2026-02-27T14:54:25.302821Z","steps":["trace[956299071] 'process raft request' (duration: 221.339495ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:25.335373Z","caller":"traceutil/trace.go:171","msg":"trace[143357016] linearizableReadLoop","detail":"{readStateIndex:4083; appliedIndex:4081; }","duration":"219.275946ms","start":"2026-02-27T14:54:25.116062Z","end":"2026-02-27T14:54:25.335338Z","steps":["trace[143357016] 'read index received' (duration: 186.596447ms)","trace[143357016] 'applied index is now lower than readState.Index' (duration: 32.677989ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:54:25.335465Z","caller":"traceutil/trace.go:171","msg":"trace[1319960999] transaction","detail":"{read_only:false; response_revision:3910; number_of_response:1; }","duration":"241.554122ms","start":"2026-02-27T14:54:25.093882Z","end":"2026-02-27T14:54:25.335436Z","steps":["trace[1319960999] 'process raft request' (duration: 241.215675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:25.335712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.624963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-02-27T14:54:25.335768Z","caller":"traceutil/trace.go:171","msg":"trace[1197109887] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3910; }","duration":"219.733985ms","start":"2026-02-27T14:54:25.116015Z","end":"2026-02-27T14:54:25.335749Z","steps":["trace[1197109887] 'agreement among raft nodes before linearized reading' (duration: 219.51088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:25.336283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.878322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f860414a-0558-42bb-b4af-598a8e2566b7\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-02-27T14:54:25.336439Z","caller":"traceutil/trace.go:171","msg":"trace[563087471] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f860414a-0558-42bb-b4af-598a8e2566b7; range_end:; response_count:1; response_revision:3910; }","duration":"129.104156ms","start":"2026-02-27T14:54:25.2073Z","end":"2026-02-27T14:54:25.336404Z","steps":["trace[563087471] 'agreement among raft nodes before linearized reading' (duration: 128.345792ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:29.803966Z","caller":"traceutil/trace.go:171","msg":"trace[1517638842] transaction","detail":"{read_only:false; response_revision:4075; number_of_response:1; }","duration":"126.375026ms","start":"2026-02-27T14:54:29.677529Z","end":"2026-02-27T14:54:29.803904Z","steps":["trace[1517638842] 'process raft request' (duration: 97.906975ms)","trace[1517638842] 'compare' (duration: 28.324608ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:54:29.804078Z","caller":"traceutil/trace.go:171","msg":"trace[1845529806] linearizableReadLoop","detail":"{readStateIndex:4257; appliedIndex:4256; }","duration":"121.425533ms","start":"2026-02-27T14:54:29.682608Z","end":"2026-02-27T14:54:29.804034Z","steps":["trace[1845529806] 'read index received' (duration: 92.84566ms)","trace[1845529806] 'applied index is now lower than readState.Index' (duration: 28.576663ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:54:29.803989Z","caller":"traceutil/trace.go:171","msg":"trace[1834670266] transaction","detail":"{read_only:false; response_revision:4076; number_of_response:1; }","duration":"116.505951ms","start":"2026-02-27T14:54:29.687462Z","end":"2026-02-27T14:54:29.803968Z","steps":["trace[1834670266] 'process raft request' (duration: 116.45561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:29.804431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.79004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14497"} {"level":"info","ts":"2026-02-27T14:54:29.804503Z","caller":"traceutil/trace.go:171","msg":"trace[1386122581] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4076; }","duration":"121.914402ms","start":"2026-02-27T14:54:29.682571Z","end":"2026-02-27T14:54:29.804485Z","steps":["trace[1386122581] 'agreement among raft nodes before linearized reading' (duration: 121.611196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:29.804851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.149136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T14:54:29.804892Z","caller":"traceutil/trace.go:171","msg":"trace[1123945717] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:0; response_revision:4076; }","duration":"122.223448ms","start":"2026-02-27T14:54:29.68266Z","end":"2026-02-27T14:54:29.804884Z","steps":["trace[1123945717] 'agreement among raft nodes before linearized reading' (duration: 122.130356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:29.805232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.591553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-02-27T14:54:29.805368Z","caller":"traceutil/trace.go:171","msg":"trace[1556677822] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4076; }","duration":"109.744535ms","start":"2026-02-27T14:54:29.695588Z","end":"2026-02-27T14:54:29.805333Z","steps":["trace[1556677822] 'agreement among raft nodes before linearized reading' (duration: 109.200805ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:30.843366Z","caller":"traceutil/trace.go:171","msg":"trace[423086695] transaction","detail":"{read_only:false; response_revision:4106; number_of_response:1; }","duration":"282.78954ms","start":"2026-02-27T14:54:30.560542Z","end":"2026-02-27T14:54:30.843331Z","steps":["trace[423086695] 'process raft request' (duration: 280.498707ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:31.262631Z","caller":"traceutil/trace.go:171","msg":"trace[1133726972] linearizableReadLoop","detail":"{readStateIndex:4290; appliedIndex:4289; }","duration":"415.243699ms","start":"2026-02-27T14:54:30.847352Z","end":"2026-02-27T14:54:31.262595Z","steps":["trace[1133726972] 'read index received' (duration: 407.79448ms)","trace[1133726972] 'applied index is now lower than readState.Index' (duration: 7.447999ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:54:31.26264Z","caller":"traceutil/trace.go:171","msg":"trace[750055652] transaction","detail":"{read_only:false; response_revision:4107; number_of_response:1; }","duration":"693.588026ms","start":"2026-02-27T14:54:30.569019Z","end":"2026-02-27T14:54:31.262607Z","steps":["trace[750055652] 'process raft request' (duration: 686.236779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:31.262978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:54:30.56899Z","time spent":"693.915523ms","remote":"127.0.0.1:35202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2895,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T14:54:31.263034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.56555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-27T14:54:31.262999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.615556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-27T14:54:31.263085Z","caller":"traceutil/trace.go:171","msg":"trace[2052761262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4107; }","duration":"411.704953ms","start":"2026-02-27T14:54:30.851368Z","end":"2026-02-27T14:54:31.263073Z","steps":["trace[2052761262] 'agreement among raft nodes before linearized reading' (duration: 411.442678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:31.263122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:54:30.851343Z","time spent":"411.770364ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T14:54:31.263118Z","caller":"traceutil/trace.go:171","msg":"trace[848366846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4107; }","duration":"415.80881ms","start":"2026-02-27T14:54:30.847282Z","end":"2026-02-27T14:54:31.263091Z","steps":["trace[848366846] 'agreement among raft nodes before linearized reading' (duration: 415.409463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:31.263178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:54:30.847263Z","time spent":"415.902751ms","remote":"127.0.0.1:59982","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-02-27T14:54:31.263452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.155034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-02-27T14:54:31.263502Z","caller":"traceutil/trace.go:171","msg":"trace[903208198] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4107; }","duration":"410.230055ms","start":"2026-02-27T14:54:30.853258Z","end":"2026-02-27T14:54:31.263488Z","steps":["trace[903208198] 'agreement among raft nodes before linearized reading' (duration: 410.055972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:31.263464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.751047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a28954e0-e42c-4f52-9525-17dd1018709d\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-02-27T14:54:31.263531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:54:30.853248Z","time spent":"410.275976ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-02-27T14:54:31.263508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.540094ms","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-02-27T14:54:31.26357Z","caller":"traceutil/trace.go:171","msg":"trace[398662037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a28954e0-e42c-4f52-9525-17dd1018709d; range_end:; response_count:1; response_revision:4107; }","duration":"174.9208ms","start":"2026-02-27T14:54:31.088625Z","end":"2026-02-27T14:54:31.263546Z","steps":["trace[398662037] 'agreement among raft nodes before linearized reading' (duration: 174.557964ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:31.26363Z","caller":"traceutil/trace.go:171","msg":"trace[1356746182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4107; }","duration":"408.725547ms","start":"2026-02-27T14:54:30.854875Z","end":"2026-02-27T14:54:31.263601Z","steps":["trace[1356746182] 'agreement among raft nodes before linearized reading' (duration: 408.392881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:31.263701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T14:54:30.854836Z","time spent":"408.8485ms","remote":"127.0.0.1:59982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T14:54:33.591711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.958717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T14:54:33.592148Z","caller":"traceutil/trace.go:171","msg":"trace[783131259] transaction","detail":"{read_only:false; response_revision:4150; number_of_response:1; }","duration":"288.939615ms","start":"2026-02-27T14:54:33.303189Z","end":"2026-02-27T14:54:33.592129Z","steps":["trace[783131259] 'process raft request' (duration: 184.488378ms)","trace[783131259] 'compare' (duration: 103.850225ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:54:33.592218Z","caller":"traceutil/trace.go:171","msg":"trace[1439604095] transaction","detail":"{read_only:false; response_revision:4151; number_of_response:1; }","duration":"288.566858ms","start":"2026-02-27T14:54:33.303597Z","end":"2026-02-27T14:54:33.592164Z","steps":["trace[1439604095] 'process raft request' (duration: 288.206051ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:33.983752Z","caller":"traceutil/trace.go:171","msg":"trace[73002733] transaction","detail":"{read_only:false; response_revision:4157; number_of_response:1; }","duration":"191.633251ms","start":"2026-02-27T14:54:33.792032Z","end":"2026-02-27T14:54:33.983665Z","steps":["trace[73002733] 'process raft request' (duration: 191.473308ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:33.984905Z","caller":"traceutil/trace.go:171","msg":"trace[1739405317] transaction","detail":"{read_only:false; response_revision:4158; number_of_response:1; }","duration":"168.184235ms","start":"2026-02-27T14:54:33.816685Z","end":"2026-02-27T14:54:33.98487Z","steps":["trace[1739405317] 'process raft request' (duration: 168.009481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:42.743597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.011085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:7 size:176299"} {"level":"warn","ts":"2026-02-27T14:54:42.743633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.000307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/memcached-memcached\" ","response":"range_response_count:1 size:543"} {"level":"info","ts":"2026-02-27T14:54:42.743701Z","caller":"traceutil/trace.go:171","msg":"trace[2106654820] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:7; response_revision:4309; }","duration":"164.171488ms","start":"2026-02-27T14:54:42.579509Z","end":"2026-02-27T14:54:42.74368Z","steps":["trace[2106654820] 'range keys from in-memory index tree' (duration: 163.72583ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:42.743758Z","caller":"traceutil/trace.go:171","msg":"trace[716653358] range","detail":"{range_begin:/registry/serviceaccounts/openstack/memcached-memcached; range_end:; response_count:1; response_revision:4309; }","duration":"104.16422ms","start":"2026-02-27T14:54:42.639565Z","end":"2026-02-27T14:54:42.743729Z","steps":["trace[716653358] 'range keys from in-memory index tree' (duration: 103.799843ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:43.113795Z","caller":"traceutil/trace.go:171","msg":"trace[2045387094] transaction","detail":"{read_only:false; response_revision:4310; number_of_response:1; }","duration":"135.721319ms","start":"2026-02-27T14:54:42.978044Z","end":"2026-02-27T14:54:43.113765Z","steps":["trace[2045387094] 'process raft request' (duration: 135.415133ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:43.326668Z","caller":"traceutil/trace.go:171","msg":"trace[707472600] linearizableReadLoop","detail":"{readStateIndex:4504; appliedIndex:4503; }","duration":"125.526939ms","start":"2026-02-27T14:54:43.201102Z","end":"2026-02-27T14:54:43.326629Z","steps":["trace[707472600] 'read index received' (duration: 38.046909ms)","trace[707472600] 'applied index is now lower than readState.Index' (duration: 87.47824ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:54:43.326825Z","caller":"traceutil/trace.go:171","msg":"trace[1683438121] transaction","detail":"{read_only:false; response_revision:4311; number_of_response:1; }","duration":"205.262486ms","start":"2026-02-27T14:54:43.121543Z","end":"2026-02-27T14:54:43.326806Z","steps":["trace[1683438121] 'process raft request' (duration: 117.559571ms)","trace[1683438121] 'compare' (duration: 87.238026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T14:54:43.326996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.874965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-02-27T14:54:43.32704Z","caller":"traceutil/trace.go:171","msg":"trace[1481519732] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4311; }","duration":"125.986258ms","start":"2026-02-27T14:54:43.201042Z","end":"2026-02-27T14:54:43.327028Z","steps":["trace[1481519732] 'agreement among raft nodes before linearized reading' (duration: 125.776744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:54:43.327301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.208677ms","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-02-27T14:54:43.327345Z","caller":"traceutil/trace.go:171","msg":"trace[1905060722] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4311; }","duration":"100.313109ms","start":"2026-02-27T14:54:43.227021Z","end":"2026-02-27T14:54:43.327334Z","steps":["trace[1905060722] 'agreement among raft nodes before linearized reading' (duration: 99.82051ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:54:49.102428Z","caller":"traceutil/trace.go:171","msg":"trace[1428288517] transaction","detail":"{read_only:false; response_revision:4403; number_of_response:1; }","duration":"101.9352ms","start":"2026-02-27T14:54:49.000455Z","end":"2026-02-27T14:54:49.10239Z","steps":["trace[1428288517] 'process raft request' (duration: 101.584533ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:55:20.081348Z","caller":"traceutil/trace.go:171","msg":"trace[2050935990] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4905; }","duration":"278.026501ms","start":"2026-02-27T14:55:19.80329Z","end":"2026-02-27T14:55:20.081316Z","steps":["trace[2050935990] 'read index received' (duration: 277.778647ms)","trace[2050935990] 'applied index is now lower than readState.Index' (duration: 246.064µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T14:55:20.08158Z","caller":"traceutil/trace.go:171","msg":"trace[671772402] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"288.076379ms","start":"2026-02-27T14:55:19.793489Z","end":"2026-02-27T14:55:20.081565Z","steps":["trace[671772402] 'process raft request' (duration: 287.61045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:55:20.08187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.557572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-02-27T14:55:20.081915Z","caller":"traceutil/trace.go:171","msg":"trace[461833106] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:4695; }","duration":"278.659083ms","start":"2026-02-27T14:55:19.803245Z","end":"2026-02-27T14:55:20.081904Z","steps":["trace[461833106] 'agreement among raft nodes before linearized reading' (duration: 278.540721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:55:20.081905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.288315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-27T14:55:20.082038Z","caller":"traceutil/trace.go:171","msg":"trace[1370928136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4695; }","duration":"235.431537ms","start":"2026-02-27T14:55:19.846585Z","end":"2026-02-27T14:55:20.082017Z","steps":["trace[1370928136] 'agreement among raft nodes before linearized reading' (duration: 235.234364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:55:20.082058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.549999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-27T14:55:20.082176Z","caller":"traceutil/trace.go:171","msg":"trace[2009532192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4695; }","duration":"228.707452ms","start":"2026-02-27T14:55:19.853446Z","end":"2026-02-27T14:55:20.082154Z","steps":["trace[2009532192] 'agreement among raft nodes before linearized reading' (duration: 228.334685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:55:20.082211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.434748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-02-27T14:55:20.082249Z","caller":"traceutil/trace.go:171","msg":"trace[2131919672] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:4695; }","duration":"242.52116ms","start":"2026-02-27T14:55:19.839719Z","end":"2026-02-27T14:55:20.08224Z","steps":["trace[2131919672] 'agreement among raft nodes before linearized reading' (duration: 242.450908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T14:55:20.082564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.894762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/\" range_end:\"/registry/serviceaccounts/openstack0\" limit:500 ","response":"range_response_count:6 size:3552"} {"level":"warn","ts":"2026-02-27T14:55:20.082637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.711605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/\" range_end:\"/registry/statefulsets/monitoring0\" limit:500 ","response":"range_response_count:3 size:32707"} {"level":"info","ts":"2026-02-27T14:55:20.082763Z","caller":"traceutil/trace.go:171","msg":"trace[524321031] range","detail":"{range_begin:/registry/serviceaccounts/openstack/; range_end:/registry/serviceaccounts/openstack0; response_count:6; response_revision:4695; }","duration":"210.136517ms","start":"2026-02-27T14:55:19.872593Z","end":"2026-02-27T14:55:20.08273Z","steps":["trace[524321031] 'agreement among raft nodes before linearized reading' (duration: 209.283221ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T14:55:20.082832Z","caller":"traceutil/trace.go:171","msg":"trace[1880737356] range","detail":"{range_begin:/registry/statefulsets/monitoring/; range_end:/registry/statefulsets/monitoring0; response_count:3; response_revision:4695; }","duration":"271.760765ms","start":"2026-02-27T14:55:19.811051Z","end":"2026-02-27T14:55:20.082811Z","steps":["trace[1880737356] 'agreement among raft nodes before linearized reading' (duration: 270.539362ms)"],"step_count":1}