{"level":"warn","ts":"2026-05-21T15:28:23.942522Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T15:28:23.942653Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.231: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.231:2380","--initial-cluster=instance=https://199.19.213.231:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.231:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.231:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T15:28:23.942795Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T15:28:23.94282Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.231:2380"]} {"level":"info","ts":"2026-05-21T15:28:23.942865Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T15:28:23.943787Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"]} {"level":"info","ts":"2026-05-21T15:28:23.943911Z","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.231:2380"],"listen-peer-urls":["https://199.19.213.231:2380"],"advertise-client-urls":["https://199.19.213.231:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.231:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T15:28:23.94771Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.39735ms"} {"level":"info","ts":"2026-05-21T15:28:23.953157Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8bb149ac537fafcb","cluster-id":"6a60f8f1d4e49112"} {"level":"info","ts":"2026-05-21T15:28:23.953272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T15:28:23.953303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became follower at term 0"} {"level":"info","ts":"2026-05-21T15:28:23.953384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8bb149ac537fafcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T15:28:23.953433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became follower at term 1"} {"level":"info","ts":"2026-05-21T15:28:23.953497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=(10065907646633848779)"} {"level":"warn","ts":"2026-05-21T15:28:23.959528Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T15:28:23.960861Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T15:28:23.961963Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T15:28:23.964686Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8bb149ac537fafcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T15:28:23.964939Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8bb149ac537fafcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T15:28:23.965097Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T15:28:23.965206Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T15:28:23.96522Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T15:28:23.965995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=(10065907646633848779)"} {"level":"info","ts":"2026-05-21T15:28:23.971855Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6a60f8f1d4e49112","local-member-id":"8bb149ac537fafcb","added-peer-id":"8bb149ac537fafcb","added-peer-peer-urls":["https://199.19.213.231:2380"]} {"level":"info","ts":"2026-05-21T15:28:23.973147Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T15:28:23.973325Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8bb149ac537fafcb","initial-advertise-peer-urls":["https://199.19.213.231:2380"],"listen-peer-urls":["https://199.19.213.231:2380"],"advertise-client-urls":["https://199.19.213.231:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T15:28:23.97338Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T15:28:23.97344Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.231:2380"} {"level":"info","ts":"2026-05-21T15:28:23.973551Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.231:2380"} {"level":"info","ts":"2026-05-21T15:28:24.753715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T15:28:24.753784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T15:28:24.75385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb received MsgPreVoteResp from 8bb149ac537fafcb at term 1"} {"level":"info","ts":"2026-05-21T15:28:24.753865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became candidate at term 2"} {"level":"info","ts":"2026-05-21T15:28:24.753873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb received MsgVoteResp from 8bb149ac537fafcb at term 2"} {"level":"info","ts":"2026-05-21T15:28:24.753883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became leader at term 2"} {"level":"info","ts":"2026-05-21T15:28:24.753892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8bb149ac537fafcb elected leader 8bb149ac537fafcb at term 2"} {"level":"info","ts":"2026-05-21T15:28:24.761483Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T15:28:24.762205Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T15:28:24.762272Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T15:28:24.762195Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8bb149ac537fafcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.231:2379]}","request-path":"/0/members/8bb149ac537fafcb/attributes","cluster-id":"6a60f8f1d4e49112","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T15:28:24.762562Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T15:28:24.762601Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T15:28:24.762598Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6a60f8f1d4e49112","local-member-id":"8bb149ac537fafcb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T15:28:24.762685Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T15:28:24.762721Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T15:28:24.766393Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.231:2379"} {"level":"info","ts":"2026-05-21T15:28:24.768086Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T15:28:27.376685Z","caller":"traceutil/trace.go:171","msg":"trace[375501840] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"214.660591ms","start":"2026-05-21T15:28:27.161996Z","end":"2026-05-21T15:28:27.376656Z","steps":["trace[375501840] 'read index received' (duration: 214.437178ms)","trace[375501840] 'applied index is now lower than readState.Index' (duration: 222.663µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:28:27.37682Z","caller":"traceutil/trace.go:171","msg":"trace[377402572] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"254.884554ms","start":"2026-05-21T15:28:27.121922Z","end":"2026-05-21T15:28:27.376807Z","steps":["trace[377402572] 'process raft request' (duration: 254.576011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:28:27.377308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.382658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:28:27.377391Z","caller":"traceutil/trace.go:171","msg":"trace[1835209800] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:75; }","duration":"214.494749ms","start":"2026-05-21T15:28:27.162879Z","end":"2026-05-21T15:28:27.377374Z","steps":["trace[1835209800] 'agreement among raft nodes before linearized reading' (duration: 214.396508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:28:27.377442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.984691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:28:27.377554Z","caller":"traceutil/trace.go:171","msg":"trace[1448089450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"201.219433ms","start":"2026-05-21T15:28:27.176305Z","end":"2026-05-21T15:28:27.377524Z","steps":["trace[1448089450] 'agreement among raft nodes before linearized reading' (duration: 200.97776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:28:27.376993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.970866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:28:27.377977Z","caller":"traceutil/trace.go:171","msg":"trace[1084522838] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:75; }","duration":"216.041213ms","start":"2026-05-21T15:28:27.161924Z","end":"2026-05-21T15:28:27.377966Z","steps":["trace[1084522838] 'agreement among raft nodes before linearized reading' (duration: 214.941177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:28:27.793965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.547105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-21T15:28:27.794116Z","caller":"traceutil/trace.go:171","msg":"trace[2027107504] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"407.946239ms","start":"2026-05-21T15:28:27.386147Z","end":"2026-05-21T15:28:27.794094Z","steps":["trace[2027107504] 'read index received' (duration: 41.432701ms)","trace[2027107504] 'applied index is now lower than readState.Index' (duration: 366.511968ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:28:27.794247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.086071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T15:28:27.794283Z","caller":"traceutil/trace.go:171","msg":"trace[904099877] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:76; }","duration":"408.143032ms","start":"2026-05-21T15:28:27.386129Z","end":"2026-05-21T15:28:27.794272Z","steps":["trace[904099877] 'agreement among raft nodes before linearized reading' (duration: 408.029571ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:28:27.794275Z","caller":"traceutil/trace.go:171","msg":"trace[643726809] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"408.921484ms","start":"2026-05-21T15:28:27.385289Z","end":"2026-05-21T15:28:27.79421Z","steps":["trace[643726809] 'process raft request' (duration: 42.206812ms)","trace[643726809] 'compare' (duration: 365.317731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:28:27.794323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:28:27.386121Z","time spent":"408.193023ms","remote":"127.0.0.1:43954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-05-21T15:28:27.794591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:28:27.385254Z","time spent":"409.098427ms","remote":"127.0.0.1:43970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T15:29:01.548358Z","caller":"traceutil/trace.go:171","msg":"trace[942612231] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"105.087175ms","start":"2026-05-21T15:29:01.443247Z","end":"2026-05-21T15:29:01.548334Z","steps":["trace[942612231] 'process raft request' (duration: 104.578508ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.548534Z","caller":"traceutil/trace.go:171","msg":"trace[479767579] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"100.548947ms","start":"2026-05-21T15:29:01.447976Z","end":"2026-05-21T15:29:01.548525Z","steps":["trace[479767579] 'process raft request' (duration: 99.939227ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.698374Z","caller":"traceutil/trace.go:171","msg":"trace[1586081328] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"131.30368ms","start":"2026-05-21T15:29:01.567053Z","end":"2026-05-21T15:29:01.698356Z","steps":["trace[1586081328] 'process raft request' (duration: 131.262749ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.698619Z","caller":"traceutil/trace.go:171","msg":"trace[39492559] transaction","detail":"{read_only:false; number_of_response:0; response_revision:545; }","duration":"132.732229ms","start":"2026-05-21T15:29:01.565856Z","end":"2026-05-21T15:29:01.698588Z","steps":["trace[39492559] 'process raft request' (duration: 132.437839ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.6988Z","caller":"traceutil/trace.go:171","msg":"trace[115813509] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"132.988758ms","start":"2026-05-21T15:29:01.565806Z","end":"2026-05-21T15:29:01.698794Z","steps":["trace[115813509] 'process raft request' (duration: 75.300617ms)","trace[115813509] 'compare' (duration: 57.09795ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:01.710475Z","caller":"traceutil/trace.go:171","msg":"trace[713735679] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"118.684454ms","start":"2026-05-21T15:29:01.59177Z","end":"2026-05-21T15:29:01.710454Z","steps":["trace[713735679] 'process raft request' (duration: 117.38197ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.710538Z","caller":"traceutil/trace.go:171","msg":"trace[989537092] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"140.681552ms","start":"2026-05-21T15:29:01.56981Z","end":"2026-05-21T15:29:01.710491Z","steps":["trace[989537092] 'process raft request' (duration: 135.076009ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.710258Z","caller":"traceutil/trace.go:171","msg":"trace[1547010399] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"140.360772ms","start":"2026-05-21T15:29:01.569871Z","end":"2026-05-21T15:29:01.710232Z","steps":["trace[1547010399] 'process raft request' (duration: 135.96343ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.710866Z","caller":"traceutil/trace.go:171","msg":"trace[805824664] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"135.92665ms","start":"2026-05-21T15:29:01.57493Z","end":"2026-05-21T15:29:01.710857Z","steps":["trace[805824664] 'process raft request' (duration: 131.758296ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.710933Z","caller":"traceutil/trace.go:171","msg":"trace[705943179] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"132.320255ms","start":"2026-05-21T15:29:01.578602Z","end":"2026-05-21T15:29:01.710922Z","steps":["trace[705943179] 'process raft request' (duration: 130.42367ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:01.711064Z","caller":"traceutil/trace.go:171","msg":"trace[1516038377] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"132.832141ms","start":"2026-05-21T15:29:01.578172Z","end":"2026-05-21T15:29:01.711004Z","steps":["trace[1516038377] 'process raft request' (duration: 130.151359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:06.867676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.586701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:29:06.867812Z","caller":"traceutil/trace.go:171","msg":"trace[1034807507] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"316.14268ms","start":"2026-05-21T15:29:06.551651Z","end":"2026-05-21T15:29:06.867794Z","steps":["trace[1034807507] 'read index received' (duration: 179.042261ms)","trace[1034807507] 'applied index is now lower than readState.Index' (duration: 137.099269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:06.867914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.260993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:06.86788Z","caller":"traceutil/trace.go:171","msg":"trace[102522802] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"346.90194ms","start":"2026-05-21T15:29:06.520935Z","end":"2026-05-21T15:29:06.867837Z","steps":["trace[102522802] 'process raft request' (duration: 209.850873ms)","trace[102522802] 'compare' (duration: 136.468858ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:06.867953Z","caller":"traceutil/trace.go:171","msg":"trace[211292557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"316.327635ms","start":"2026-05-21T15:29:06.551615Z","end":"2026-05-21T15:29:06.867943Z","steps":["trace[211292557] 'agreement among raft nodes before linearized reading' (duration: 316.265194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:06.867991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:06.551596Z","time spent":"316.387758ms","remote":"127.0.0.1:43592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T15:29:06.868065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:06.520917Z","time spent":"347.059866ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:29:07.629222Z","caller":"traceutil/trace.go:171","msg":"trace[752916629] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"106.514447ms","start":"2026-05-21T15:29:07.522682Z","end":"2026-05-21T15:29:07.629197Z","steps":["trace[752916629] 'process raft request' (duration: 95.541236ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:29:07.771872Z","caller":"traceutil/trace.go:171","msg":"trace[1953532920] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"219.764626ms","start":"2026-05-21T15:29:07.552081Z","end":"2026-05-21T15:29:07.771846Z","steps":["trace[1953532920] 'read index received' (duration: 66.132058ms)","trace[1953532920] 'applied index is now lower than readState.Index' (duration: 153.631428ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:29:07.772009Z","caller":"traceutil/trace.go:171","msg":"trace[1949208687] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"247.81318ms","start":"2026-05-21T15:29:07.524183Z","end":"2026-05-21T15:29:07.771996Z","steps":["trace[1949208687] 'process raft request' (duration: 231.460921ms)","trace[1949208687] 'compare' (duration: 16.074179ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:07.772277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.141199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:07.772324Z","caller":"traceutil/trace.go:171","msg":"trace[1333403105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"220.273764ms","start":"2026-05-21T15:29:07.552038Z","end":"2026-05-21T15:29:07.772312Z","steps":["trace[1333403105] 'agreement among raft nodes before linearized reading' (duration: 220.102168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:07.772404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.34444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:29:07.772495Z","caller":"traceutil/trace.go:171","msg":"trace[771166608] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:635; }","duration":"133.462185ms","start":"2026-05-21T15:29:07.639009Z","end":"2026-05-21T15:29:07.772471Z","steps":["trace[771166608] 'agreement among raft nodes before linearized reading' (duration: 133.328661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:08.534476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.988603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T15:29:08.534592Z","caller":"traceutil/trace.go:171","msg":"trace[1415280211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"551.101387ms","start":"2026-05-21T15:29:07.983462Z","end":"2026-05-21T15:29:08.534563Z","steps":["trace[1415280211] 'range keys from in-memory index tree' (duration: 550.841758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:29:08.53463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:07.983421Z","time spent":"551.197979ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T15:29:08.541149Z","caller":"traceutil/trace.go:171","msg":"trace[1018775581] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"368.791983ms","start":"2026-05-21T15:29:08.172332Z","end":"2026-05-21T15:29:08.541124Z","steps":["trace[1018775581] 'process raft request' (duration: 325.534679ms)","trace[1018775581] 'compare' (duration: 36.338977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:29:08.541334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:29:08.172304Z","time spent":"368.889585ms","remote":"127.0.0.1:43678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:29:24.061687Z","caller":"traceutil/trace.go:171","msg":"trace[832281230] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"151.900055ms","start":"2026-05-21T15:29:23.909763Z","end":"2026-05-21T15:29:24.061663Z","steps":["trace[832281230] 'process raft request' (duration: 151.736619ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:17.088121Z","caller":"traceutil/trace.go:171","msg":"trace[1696992584] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1518; }","duration":"117.89144ms","start":"2026-05-21T15:30:16.970209Z","end":"2026-05-21T15:30:17.088101Z","steps":["trace[1696992584] 'read index received' (duration: 117.782827ms)","trace[1696992584] 'applied index is now lower than readState.Index' (duration: 107.903µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:17.088279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.074463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T15:30:17.088301Z","caller":"traceutil/trace.go:171","msg":"trace[1446076974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1474; }","duration":"118.154855ms","start":"2026-05-21T15:30:16.97014Z","end":"2026-05-21T15:30:17.088295Z","steps":["trace[1446076974] 'agreement among raft nodes before linearized reading' (duration: 118.057873ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:17.088258Z","caller":"traceutil/trace.go:171","msg":"trace[2085527913] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"133.049569ms","start":"2026-05-21T15:30:16.955173Z","end":"2026-05-21T15:30:17.088223Z","steps":["trace[2085527913] 'process raft request' (duration: 132.820594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:17.088478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.064159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:30:17.088558Z","caller":"traceutil/trace.go:171","msg":"trace[1679561740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1474; }","duration":"112.180931ms","start":"2026-05-21T15:30:16.976359Z","end":"2026-05-21T15:30:17.08854Z","steps":["trace[1679561740] 'agreement among raft nodes before linearized reading' (duration: 111.944416ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:41.415646Z","caller":"traceutil/trace.go:171","msg":"trace[532714886] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"158.426421ms","start":"2026-05-21T15:30:41.257196Z","end":"2026-05-21T15:30:41.415623Z","steps":["trace[532714886] 'process raft request' (duration: 152.292764ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:41.465072Z","caller":"traceutil/trace.go:171","msg":"trace[663995148] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"205.638688ms","start":"2026-05-21T15:30:41.259404Z","end":"2026-05-21T15:30:41.465043Z","steps":["trace[663995148] 'process raft request' (duration: 205.458705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.052644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667392422522757100,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T15:30:42.164078Z","caller":"traceutil/trace.go:171","msg":"trace[931578800] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"694.333661ms","start":"2026-05-21T15:30:41.4696Z","end":"2026-05-21T15:30:42.163934Z","steps":["trace[931578800] 'process raft request' (duration: 664.605648ms)","trace[931578800] 'compare' (duration: 29.202012ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:42.16432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:41.46958Z","time spent":"694.607927ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:30:42.25792Z","caller":"traceutil/trace.go:171","msg":"trace[460414938] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"787.966146ms","start":"2026-05-21T15:30:41.46992Z","end":"2026-05-21T15:30:42.257886Z","steps":["trace[460414938] 'process raft request' (duration: 787.740391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.258113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:41.469893Z","time spent":"788.125118ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:30:42.258226Z","caller":"traceutil/trace.go:171","msg":"trace[293260242] linearizableReadLoop","detail":"{readStateIndex:1960; appliedIndex:1956; }","duration":"706.6391ms","start":"2026-05-21T15:30:41.551564Z","end":"2026-05-21T15:30:42.258203Z","steps":["trace[293260242] 'read index received' (duration: 582.597178ms)","trace[293260242] 'applied index is now lower than readState.Index' (duration: 124.040982ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:42.258253Z","caller":"traceutil/trace.go:171","msg":"trace[640526291] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"736.417027ms","start":"2026-05-21T15:30:41.521794Z","end":"2026-05-21T15:30:42.258211Z","steps":["trace[640526291] 'process raft request' (duration: 736.001389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.258672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.561611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9ead805-cf2c-4064-bb0f-2b9885252b8b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-21T15:30:42.258765Z","caller":"traceutil/trace.go:171","msg":"trace[1519204195] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9ead805-cf2c-4064-bb0f-2b9885252b8b; range_end:; response_count:1; response_revision:1888; }","duration":"345.664243ms","start":"2026-05-21T15:30:41.913084Z","end":"2026-05-21T15:30:42.258748Z","steps":["trace[1519204195] 'agreement among raft nodes before linearized reading' (duration: 345.383568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.258831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:41.913059Z","time spent":"345.759467ms","remote":"127.0.0.1:43766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9ead805-cf2c-4064-bb0f-2b9885252b8b\" "} {"level":"info","ts":"2026-05-21T15:30:42.258281Z","caller":"traceutil/trace.go:171","msg":"trace[968364265] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"705.727921ms","start":"2026-05-21T15:30:41.552498Z","end":"2026-05-21T15:30:42.258226Z","steps":["trace[968364265] 'process raft request' (duration: 705.536938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.258494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.906706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:42.259061Z","caller":"traceutil/trace.go:171","msg":"trace[631548424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1888; }","duration":"707.524709ms","start":"2026-05-21T15:30:41.551521Z","end":"2026-05-21T15:30:42.259046Z","steps":["trace[631548424] 'agreement among raft nodes before linearized reading' (duration: 706.807094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.259123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:41.551492Z","time spent":"707.620412ms","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T15:30:42.258545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.477671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-21T15:30:42.259219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:41.552482Z","time spent":"706.462667ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:30:42.259262Z","caller":"traceutil/trace.go:171","msg":"trace[704203900] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1888; }","duration":"487.216458ms","start":"2026-05-21T15:30:41.772002Z","end":"2026-05-21T15:30:42.259218Z","steps":["trace[704203900] 'agreement among raft nodes before linearized reading' (duration: 486.34952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.259433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:41.771988Z","time spent":"487.37489ms","remote":"127.0.0.1:40740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-21T15:30:42.258602Z","caller":"traceutil/trace.go:171","msg":"trace[220989476] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"132.424425ms","start":"2026-05-21T15:30:42.125872Z","end":"2026-05-21T15:30:42.258297Z","steps":["trace[220989476] 'process raft request' (duration: 132.273703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.258638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.86738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d9ead805-cf2c-4064-bb0f-2b9885252b8b\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-21T15:30:42.259661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:41.521768Z","time spent":"736.958699ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:30:42.259715Z","caller":"traceutil/trace.go:171","msg":"trace[1369202933] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d9ead805-cf2c-4064-bb0f-2b9885252b8b; range_end:; response_count:1; response_revision:1888; }","duration":"248.969363ms","start":"2026-05-21T15:30:42.010688Z","end":"2026-05-21T15:30:42.259657Z","steps":["trace[1369202933] 'agreement among raft nodes before linearized reading' (duration: 247.688716ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:42.801575Z","caller":"traceutil/trace.go:171","msg":"trace[89191939] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"317.083169ms","start":"2026-05-21T15:30:42.484448Z","end":"2026-05-21T15:30:42.801531Z","steps":["trace[89191939] 'read index received' (duration: 316.909926ms)","trace[89191939] 'applied index is now lower than readState.Index' (duration: 172.143µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:42.801707Z","caller":"traceutil/trace.go:171","msg":"trace[1451370915] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"533.752014ms","start":"2026-05-21T15:30:42.267944Z","end":"2026-05-21T15:30:42.801696Z","steps":["trace[1451370915] 'process raft request' (duration: 533.429678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.801804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.696868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:30:42.801855Z","caller":"traceutil/trace.go:171","msg":"trace[502500732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1889; }","duration":"249.764339ms","start":"2026-05-21T15:30:42.552079Z","end":"2026-05-21T15:30:42.801844Z","steps":["trace[502500732] 'agreement among raft nodes before linearized reading' (duration: 249.657807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.801847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:42.267921Z","time spent":"533.820056ms","remote":"127.0.0.1:43766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:30:42.801924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.462868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T15:30:42.802017Z","caller":"traceutil/trace.go:171","msg":"trace[1555593935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1889; }","duration":"317.605111ms","start":"2026-05-21T15:30:42.484395Z","end":"2026-05-21T15:30:42.802Z","steps":["trace[1555593935] 'agreement among raft nodes before linearized reading' (duration: 317.357525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:42.802057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:42.484378Z","time spent":"317.670871ms","remote":"127.0.0.1:43846","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":"info","ts":"2026-05-21T15:30:53.558711Z","caller":"traceutil/trace.go:171","msg":"trace[555505561] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"334.255125ms","start":"2026-05-21T15:30:53.224424Z","end":"2026-05-21T15:30:53.558679Z","steps":["trace[555505561] 'process raft request' (duration: 334.02813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:53.55889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:30:53.224402Z","time spent":"334.393658ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:30:53.567443Z","caller":"traceutil/trace.go:171","msg":"trace[889478142] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"223.281156ms","start":"2026-05-21T15:30:53.344146Z","end":"2026-05-21T15:30:53.567427Z","steps":["trace[889478142] 'process raft request' (duration: 223.152883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:53.567662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.884277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T15:30:53.567745Z","caller":"traceutil/trace.go:171","msg":"trace[967604628] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1984; }","duration":"199.010549ms","start":"2026-05-21T15:30:53.368712Z","end":"2026-05-21T15:30:53.567723Z","steps":["trace[967604628] 'agreement among raft nodes before linearized reading' (duration: 198.809285ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:53.567923Z","caller":"traceutil/trace.go:171","msg":"trace[821744053] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2058; }","duration":"198.64205ms","start":"2026-05-21T15:30:53.36876Z","end":"2026-05-21T15:30:53.567402Z","steps":["trace[821744053] 'read index received' (duration: 189.71055ms)","trace[821744053] 'applied index is now lower than readState.Index' (duration: 8.93071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:30:53.568117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.398448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:30:53.568154Z","caller":"traceutil/trace.go:171","msg":"trace[2107874307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1984; }","duration":"103.504949ms","start":"2026-05-21T15:30:53.46464Z","end":"2026-05-21T15:30:53.568145Z","steps":["trace[2107874307] 'agreement among raft nodes before linearized reading' (duration: 103.401377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:53.568337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.812513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:30:53.568384Z","caller":"traceutil/trace.go:171","msg":"trace[1273504005] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1984; }","duration":"175.910044ms","start":"2026-05-21T15:30:53.392463Z","end":"2026-05-21T15:30:53.568373Z","steps":["trace[1273504005] 'agreement among raft nodes before linearized reading' (duration: 175.556087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:53.769947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.48024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:30:53.770169Z","caller":"traceutil/trace.go:171","msg":"trace[200089607] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"198.315994ms","start":"2026-05-21T15:30:53.571828Z","end":"2026-05-21T15:30:53.770144Z","steps":["trace[200089607] 'process raft request' (duration: 87.517177ms)","trace[200089607] 'compare' (duration: 109.820287ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:53.770491Z","caller":"traceutil/trace.go:171","msg":"trace[1006203523] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"198.139321ms","start":"2026-05-21T15:30:53.572339Z","end":"2026-05-21T15:30:53.770478Z","steps":["trace[1006203523] 'process raft request' (duration: 197.714913ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:30:57.880519Z","caller":"traceutil/trace.go:171","msg":"trace[1414358861] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2090; }","duration":"278.350062ms","start":"2026-05-21T15:30:57.602144Z","end":"2026-05-21T15:30:57.880494Z","steps":["trace[1414358861] 'read index received' (duration: 278.194628ms)","trace[1414358861] 'applied index is now lower than readState.Index' (duration: 154.454µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:30:57.880611Z","caller":"traceutil/trace.go:171","msg":"trace[1219068367] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"299.307489ms","start":"2026-05-21T15:30:57.581247Z","end":"2026-05-21T15:30:57.880554Z","steps":["trace[1219068367] 'process raft request' (duration: 299.040424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:30:57.881214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.842641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T15:30:57.881332Z","caller":"traceutil/trace.go:171","msg":"trace[625034090] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2014; }","duration":"279.24652ms","start":"2026-05-21T15:30:57.602072Z","end":"2026-05-21T15:30:57.881319Z","steps":["trace[625034090] 'agreement among raft nodes before linearized reading' (duration: 278.565035ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:20.558953Z","caller":"traceutil/trace.go:171","msg":"trace[1539916754] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"106.361067ms","start":"2026-05-21T15:31:20.452568Z","end":"2026-05-21T15:31:20.55893Z","steps":["trace[1539916754] 'process raft request' (duration: 106.232204ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:20.606072Z","caller":"traceutil/trace.go:171","msg":"trace[549965796] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"141.826143ms","start":"2026-05-21T15:31:20.464221Z","end":"2026-05-21T15:31:20.606047Z","steps":["trace[549965796] 'process raft request' (duration: 103.651225ms)","trace[549965796] 'compare' (duration: 38.032725ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:20.606259Z","caller":"traceutil/trace.go:171","msg":"trace[280229629] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"117.03798ms","start":"2026-05-21T15:31:20.489199Z","end":"2026-05-21T15:31:20.606237Z","steps":["trace[280229629] 'process raft request' (duration: 116.798746ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:21.361784Z","caller":"traceutil/trace.go:171","msg":"trace[622251659] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"348.897749ms","start":"2026-05-21T15:31:21.012875Z","end":"2026-05-21T15:31:21.361773Z","steps":["trace[622251659] 'process raft request' (duration: 348.660965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:21.362116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.59107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-05-21T15:31:21.362172Z","caller":"traceutil/trace.go:171","msg":"trace[1267911656] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2149; }","duration":"341.691012ms","start":"2026-05-21T15:31:21.020469Z","end":"2026-05-21T15:31:21.36216Z","steps":["trace[1267911656] 'agreement among raft nodes before linearized reading' (duration: 341.518578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:21.362196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:21.020417Z","time spent":"341.772944ms","remote":"127.0.0.1:43772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-21T15:31:21.362572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:21.012845Z","time spent":"349.246955ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:31:21.361739Z","caller":"traceutil/trace.go:171","msg":"trace[48775601] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2230; }","duration":"341.196343ms","start":"2026-05-21T15:31:21.020521Z","end":"2026-05-21T15:31:21.361717Z","steps":["trace[48775601] 'read index received' (duration: 340.940298ms)","trace[48775601] 'applied index is now lower than readState.Index' (duration: 255.315µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:21.460312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.867746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T15:31:21.460361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.325946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-21T15:31:21.460408Z","caller":"traceutil/trace.go:171","msg":"trace[1323947363] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2150; }","duration":"197.007739ms","start":"2026-05-21T15:31:21.263367Z","end":"2026-05-21T15:31:21.460375Z","steps":["trace[1323947363] 'agreement among raft nodes before linearized reading' (duration: 196.858705ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:21.460414Z","caller":"traceutil/trace.go:171","msg":"trace[1198975693] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2150; }","duration":"423.409857ms","start":"2026-05-21T15:31:21.036988Z","end":"2026-05-21T15:31:21.460398Z","steps":["trace[1198975693] 'agreement among raft nodes before linearized reading' (duration: 423.228083ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:21.460411Z","caller":"traceutil/trace.go:171","msg":"trace[764405832] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"373.63134ms","start":"2026-05-21T15:31:21.086736Z","end":"2026-05-21T15:31:21.460368Z","steps":["trace[764405832] 'process raft request' (duration: 284.121486ms)","trace[764405832] 'compare' (duration: 89.211138ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:21.460454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:21.036975Z","time spent":"423.469128ms","remote":"127.0.0.1:40740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-21T15:31:21.46044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.078139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:31:21.460725Z","caller":"traceutil/trace.go:171","msg":"trace[263340924] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2150; }","duration":"404.410406ms","start":"2026-05-21T15:31:21.056295Z","end":"2026-05-21T15:31:21.460705Z","steps":["trace[263340924] 'agreement among raft nodes before linearized reading' (duration: 403.997178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:21.460781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:21.056282Z","time spent":"404.483647ms","remote":"127.0.0.1:43946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-21T15:31:21.460914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:21.086716Z","time spent":"373.764863ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:31:22.100091Z","caller":"traceutil/trace.go:171","msg":"trace[1733708067] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"116.684757ms","start":"2026-05-21T15:31:21.983376Z","end":"2026-05-21T15:31:22.10006Z","steps":["trace[1733708067] 'read index received' (duration: 116.498285ms)","trace[1733708067] 'applied index is now lower than readState.Index' (duration: 185.492µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:22.100193Z","caller":"traceutil/trace.go:171","msg":"trace[424505756] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"144.031457ms","start":"2026-05-21T15:31:21.956129Z","end":"2026-05-21T15:31:22.10016Z","steps":["trace[424505756] 'process raft request' (duration: 143.742923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:22.100422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.992453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:31:22.100522Z","caller":"traceutil/trace.go:171","msg":"trace[370277940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2151; }","duration":"117.160717ms","start":"2026-05-21T15:31:21.983324Z","end":"2026-05-21T15:31:22.100485Z","steps":["trace[370277940] 'agreement among raft nodes before linearized reading' (duration: 116.851982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:22.100875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.718941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T15:31:22.100908Z","caller":"traceutil/trace.go:171","msg":"trace[1576441275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2151; }","duration":"103.814133ms","start":"2026-05-21T15:31:21.997085Z","end":"2026-05-21T15:31:22.1009Z","steps":["trace[1576441275] 'agreement among raft nodes before linearized reading' (duration: 103.12593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:22.502913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.57118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:31:22.503415Z","caller":"traceutil/trace.go:171","msg":"trace[1361351306] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"397.566703ms","start":"2026-05-21T15:31:22.105826Z","end":"2026-05-21T15:31:22.503393Z","steps":["trace[1361351306] 'process raft request' (duration: 196.413591ms)","trace[1361351306] 'compare' (duration: 200.375486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:22.503504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:22.105805Z","time spent":"397.658834ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:31:22.503686Z","caller":"traceutil/trace.go:171","msg":"trace[1130289077] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"397.847257ms","start":"2026-05-21T15:31:22.105828Z","end":"2026-05-21T15:31:22.503675Z","steps":["trace[1130289077] 'process raft request' (duration: 397.181985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:22.503748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:22.105804Z","time spent":"397.91569ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:31:22.503725Z","caller":"traceutil/trace.go:171","msg":"trace[1099158722] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2233; }","duration":"306.120155ms","start":"2026-05-21T15:31:22.197561Z","end":"2026-05-21T15:31:22.503682Z","steps":["trace[1099158722] 'read index received' (duration: 104.667309ms)","trace[1099158722] 'applied index is now lower than readState.Index' (duration: 201.449506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:22.503916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.314159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:31:22.504021Z","caller":"traceutil/trace.go:171","msg":"trace[725023108] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2153; }","duration":"306.579644ms","start":"2026-05-21T15:31:22.197418Z","end":"2026-05-21T15:31:22.503997Z","steps":["trace[725023108] 'agreement among raft nodes before linearized reading' (duration: 306.34229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:22.504075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:22.197344Z","time spent":"306.723067ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-21T15:31:22.828622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.085091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:31:22.829629Z","caller":"traceutil/trace.go:171","msg":"trace[629232399] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"320.702623ms","start":"2026-05-21T15:31:22.508872Z","end":"2026-05-21T15:31:22.829574Z","steps":["trace[629232399] 'process raft request' (duration: 98.042823ms)","trace[629232399] 'compare' (duration: 220.879557ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:31:22.82988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:31:22.50885Z","time spent":"320.894667ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:31:22.829987Z","caller":"traceutil/trace.go:171","msg":"trace[2014501585] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"265.8217ms","start":"2026-05-21T15:31:22.564145Z","end":"2026-05-21T15:31:22.829967Z","steps":["trace[2014501585] 'process raft request' (duration: 265.674947ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:22.830322Z","caller":"traceutil/trace.go:171","msg":"trace[991714233] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"218.218326ms","start":"2026-05-21T15:31:22.612071Z","end":"2026-05-21T15:31:22.83029Z","steps":["trace[991714233] 'process raft request' (duration: 217.857509ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:31:22.830316Z","caller":"traceutil/trace.go:171","msg":"trace[1615847151] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2235; }","duration":"207.466261ms","start":"2026-05-21T15:31:22.622822Z","end":"2026-05-21T15:31:22.830288Z","steps":["trace[1615847151] 'read index received' (duration: 203.557627ms)","trace[1615847151] 'applied index is now lower than readState.Index' (duration: 3.907524ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:31:22.830439Z","caller":"traceutil/trace.go:171","msg":"trace[1696799916] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"218.080994ms","start":"2026-05-21T15:31:22.612286Z","end":"2026-05-21T15:31:22.830367Z","steps":["trace[1696799916] 'process raft request' (duration: 217.88771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:31:22.830703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.877461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:31:22.830789Z","caller":"traceutil/trace.go:171","msg":"trace[137496508] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2157; }","duration":"207.971842ms","start":"2026-05-21T15:31:22.622801Z","end":"2026-05-21T15:31:22.830773Z","steps":["trace[137496508] 'agreement among raft nodes before linearized reading' (duration: 207.688147ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:11.28737Z","caller":"traceutil/trace.go:171","msg":"trace[1594321304] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"280.848281ms","start":"2026-05-21T15:32:11.00649Z","end":"2026-05-21T15:32:11.287338Z","steps":["trace[1594321304] 'process raft request' (duration: 280.650577ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:11.307755Z","caller":"traceutil/trace.go:171","msg":"trace[569234550] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"300.999673ms","start":"2026-05-21T15:32:11.006722Z","end":"2026-05-21T15:32:11.307722Z","steps":["trace[569234550] 'process raft request' (duration: 300.793029ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:11.307778Z","caller":"traceutil/trace.go:171","msg":"trace[341027884] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"300.895811ms","start":"2026-05-21T15:32:11.006852Z","end":"2026-05-21T15:32:11.307748Z","steps":["trace[341027884] 'process raft request' (duration: 300.760699ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:11.307732Z","caller":"traceutil/trace.go:171","msg":"trace[1398229220] linearizableReadLoop","detail":"{readStateIndex:2617; appliedIndex:2614; }","duration":"289.825312ms","start":"2026-05-21T15:32:11.017886Z","end":"2026-05-21T15:32:11.307712Z","steps":["trace[1398229220] 'read index received' (duration: 269.285513ms)","trace[1398229220] 'applied index is now lower than readState.Index' (duration: 20.538399ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:32:11.307853Z","caller":"traceutil/trace.go:171","msg":"trace[1518913594] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"241.35685ms","start":"2026-05-21T15:32:11.066483Z","end":"2026-05-21T15:32:11.30784Z","steps":["trace[1518913594] 'process raft request' (duration: 241.177188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:32:11.307915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:32:11.006709Z","time spent":"301.128425ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:32:11.307928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:32:11.006819Z","time spent":"301.030363ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:32:11.308048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.134688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T15:32:11.308078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.097713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-05-21T15:32:11.308078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.955091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T15:32:11.308099Z","caller":"traceutil/trace.go:171","msg":"trace[1153606509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2524; }","duration":"290.231558ms","start":"2026-05-21T15:32:11.017852Z","end":"2026-05-21T15:32:11.308084Z","steps":["trace[1153606509] 'agreement among raft nodes before linearized reading' (duration: 290.055276ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:11.308114Z","caller":"traceutil/trace.go:171","msg":"trace[296793253] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2524; }","duration":"227.165306ms","start":"2026-05-21T15:32:11.080938Z","end":"2026-05-21T15:32:11.308103Z","steps":["trace[296793253] 'agreement among raft nodes before linearized reading' (duration: 227.038913ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:11.308116Z","caller":"traceutil/trace.go:171","msg":"trace[1913470757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2524; }","duration":"197.037433ms","start":"2026-05-21T15:32:11.111071Z","end":"2026-05-21T15:32:11.308108Z","steps":["trace[1913470757] 'agreement among raft nodes before linearized reading' (duration: 196.929201ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:43.943399Z","caller":"traceutil/trace.go:171","msg":"trace[1898835003] linearizableReadLoop","detail":"{readStateIndex:2805; appliedIndex:2804; }","duration":"164.826967ms","start":"2026-05-21T15:32:43.778543Z","end":"2026-05-21T15:32:43.94337Z","steps":["trace[1898835003] 'read index received' (duration: 147.648554ms)","trace[1898835003] 'applied index is now lower than readState.Index' (duration: 17.176923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:32:43.943794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.149892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T15:32:43.943965Z","caller":"traceutil/trace.go:171","msg":"trace[1098343818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2703; }","duration":"165.48332ms","start":"2026-05-21T15:32:43.778458Z","end":"2026-05-21T15:32:43.943941Z","steps":["trace[1098343818] 'agreement among raft nodes before linearized reading' (duration: 165.025601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:32:44.24639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.832397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:32:44.24654Z","caller":"traceutil/trace.go:171","msg":"trace[67330853] linearizableReadLoop","detail":"{readStateIndex:2807; appliedIndex:2806; }","duration":"194.078092ms","start":"2026-05-21T15:32:44.052447Z","end":"2026-05-21T15:32:44.246525Z","steps":["trace[67330853] 'read index received' (duration: 58.849309ms)","trace[67330853] 'applied index is now lower than readState.Index' (duration: 135.227663ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:32:44.246612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.173164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:32:44.246647Z","caller":"traceutil/trace.go:171","msg":"trace[1596882664] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2705; }","duration":"194.207424ms","start":"2026-05-21T15:32:44.052429Z","end":"2026-05-21T15:32:44.246636Z","steps":["trace[1596882664] 'agreement among raft nodes before linearized reading' (duration: 194.140213ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:32:44.246716Z","caller":"traceutil/trace.go:171","msg":"trace[1874496345] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"210.101784ms","start":"2026-05-21T15:32:44.036523Z","end":"2026-05-21T15:32:44.246625Z","steps":["trace[1874496345] 'process raft request' (duration: 74.932311ms)","trace[1874496345] 'compare' (duration: 134.679725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:33:52.115434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.005752ms","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-05-21T15:33:52.115557Z","caller":"traceutil/trace.go:171","msg":"trace[2081060887] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:3064; }","duration":"188.174847ms","start":"2026-05-21T15:33:51.927362Z","end":"2026-05-21T15:33:52.115537Z","steps":["trace[2081060887] 'count revisions from in-memory index tree' (duration: 187.843517ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:53.833283Z","caller":"traceutil/trace.go:171","msg":"trace[424729019] transaction","detail":"{read_only:false; response_revision:3070; number_of_response:1; }","duration":"120.334063ms","start":"2026-05-21T15:33:53.712917Z","end":"2026-05-21T15:33:53.833251Z","steps":["trace[424729019] 'process raft request' (duration: 120.152748ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:53.851037Z","caller":"traceutil/trace.go:171","msg":"trace[1797156080] transaction","detail":"{read_only:false; response_revision:3071; number_of_response:1; }","duration":"131.270293ms","start":"2026-05-21T15:33:53.719744Z","end":"2026-05-21T15:33:53.851014Z","steps":["trace[1797156080] 'process raft request' (duration: 131.099869ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:53.851282Z","caller":"traceutil/trace.go:171","msg":"trace[865792434] transaction","detail":"{read_only:false; response_revision:3072; number_of_response:1; }","duration":"130.639586ms","start":"2026-05-21T15:33:53.720593Z","end":"2026-05-21T15:33:53.851232Z","steps":["trace[865792434] 'process raft request' (duration: 130.384999ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:53.999927Z","caller":"traceutil/trace.go:171","msg":"trace[1049346875] linearizableReadLoop","detail":"{readStateIndex:3190; appliedIndex:3188; }","duration":"109.389943ms","start":"2026-05-21T15:33:53.890505Z","end":"2026-05-21T15:33:53.999895Z","steps":["trace[1049346875] 'read index received' (duration: 23.725923ms)","trace[1049346875] 'applied index is now lower than readState.Index' (duration: 85.66238ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:33:54Z","caller":"traceutil/trace.go:171","msg":"trace[2001815710] transaction","detail":"{read_only:false; response_revision:3073; number_of_response:1; }","duration":"145.422395ms","start":"2026-05-21T15:33:53.854529Z","end":"2026-05-21T15:33:53.999952Z","steps":["trace[2001815710] 'process raft request' (duration: 145.159358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:33:54.098037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.506458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T15:33:54.098129Z","caller":"traceutil/trace.go:171","msg":"trace[1488334704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3073; }","duration":"207.657193ms","start":"2026-05-21T15:33:53.890447Z","end":"2026-05-21T15:33:54.098104Z","steps":["trace[1488334704] 'agreement among raft nodes before linearized reading' (duration: 109.582409ms)","trace[1488334704] 'range keys from in-memory index tree' (duration: 97.786166ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:33:57.526501Z","caller":"traceutil/trace.go:171","msg":"trace[590843408] transaction","detail":"{read_only:false; response_revision:3088; number_of_response:1; }","duration":"111.518411ms","start":"2026-05-21T15:33:57.414962Z","end":"2026-05-21T15:33:57.526481Z","steps":["trace[590843408] 'process raft request' (duration: 111.240603ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:58.01629Z","caller":"traceutil/trace.go:171","msg":"trace[1644834882] transaction","detail":"{read_only:false; response_revision:3091; number_of_response:1; }","duration":"108.832515ms","start":"2026-05-21T15:33:57.907433Z","end":"2026-05-21T15:33:58.016266Z","steps":["trace[1644834882] 'process raft request' (duration: 108.625889ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:58.17878Z","caller":"traceutil/trace.go:171","msg":"trace[1789671736] linearizableReadLoop","detail":"{readStateIndex:3209; appliedIndex:3208; }","duration":"156.810672ms","start":"2026-05-21T15:33:58.021948Z","end":"2026-05-21T15:33:58.178758Z","steps":["trace[1789671736] 'read index received' (duration: 156.653067ms)","trace[1789671736] 'applied index is now lower than readState.Index' (duration: 156.375µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:33:58.179005Z","caller":"traceutil/trace.go:171","msg":"trace[946582570] transaction","detail":"{read_only:false; response_revision:3092; number_of_response:1; }","duration":"232.509935ms","start":"2026-05-21T15:33:57.946455Z","end":"2026-05-21T15:33:58.178965Z","steps":["trace[946582570] 'process raft request' (duration: 232.159976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:33:58.179045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.082329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:33:58.179171Z","caller":"traceutil/trace.go:171","msg":"trace[891930541] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:3092; }","duration":"157.220573ms","start":"2026-05-21T15:33:58.021932Z","end":"2026-05-21T15:33:58.179153Z","steps":["trace[891930541] 'agreement among raft nodes before linearized reading' (duration: 157.039708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:33:58.179311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.471977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T15:33:58.179388Z","caller":"traceutil/trace.go:171","msg":"trace[852781043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3092; }","duration":"148.58142ms","start":"2026-05-21T15:33:58.030788Z","end":"2026-05-21T15:33:58.179369Z","steps":["trace[852781043] 'agreement among raft nodes before linearized reading' (duration: 148.370004ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:59.51444Z","caller":"traceutil/trace.go:171","msg":"trace[1254593592] transaction","detail":"{read_only:false; response_revision:3098; number_of_response:1; }","duration":"134.091985ms","start":"2026-05-21T15:33:59.380296Z","end":"2026-05-21T15:33:59.514388Z","steps":["trace[1254593592] 'process raft request' (duration: 133.90406ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:33:59.603769Z","caller":"traceutil/trace.go:171","msg":"trace[1376397806] transaction","detail":"{read_only:false; response_revision:3099; number_of_response:1; }","duration":"211.856567ms","start":"2026-05-21T15:33:59.391887Z","end":"2026-05-21T15:33:59.603744Z","steps":["trace[1376397806] 'process raft request' (duration: 163.447346ms)","trace[1376397806] 'compare' (duration: 48.233377ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:33:59.877096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.715148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:33:59.877277Z","caller":"traceutil/trace.go:171","msg":"trace[475519455] transaction","detail":"{read_only:false; response_revision:3102; number_of_response:1; }","duration":"174.388313ms","start":"2026-05-21T15:33:59.70283Z","end":"2026-05-21T15:33:59.877218Z","steps":["trace[475519455] 'process raft request' (duration: 36.410067ms)","trace[475519455] 'compare' (duration: 137.441461ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:00.171549Z","caller":"traceutil/trace.go:171","msg":"trace[251134747] transaction","detail":"{read_only:false; response_revision:3103; number_of_response:1; }","duration":"293.322001ms","start":"2026-05-21T15:33:59.878198Z","end":"2026-05-21T15:34:00.17152Z","steps":["trace[251134747] 'process raft request' (duration: 293.032253ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:00.299199Z","caller":"traceutil/trace.go:171","msg":"trace[1754248573] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"277.975337ms","start":"2026-05-21T15:34:00.021195Z","end":"2026-05-21T15:34:00.29917Z","steps":["trace[1754248573] 'process raft request' (duration: 195.265212ms)","trace[1754248573] 'compare' (duration: 82.496259ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:00.299417Z","caller":"traceutil/trace.go:171","msg":"trace[754740574] transaction","detail":"{read_only:false; response_revision:3105; number_of_response:1; }","duration":"115.124928ms","start":"2026-05-21T15:34:00.18428Z","end":"2026-05-21T15:34:00.299405Z","steps":["trace[754740574] 'process raft request' (duration: 114.8298ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:00.29938Z","caller":"traceutil/trace.go:171","msg":"trace[213924648] linearizableReadLoop","detail":"{readStateIndex:3223; appliedIndex:3221; }","duration":"100.08986ms","start":"2026-05-21T15:34:00.199205Z","end":"2026-05-21T15:34:00.299295Z","steps":["trace[213924648] 'read index received' (duration: 17.22908ms)","trace[213924648] 'applied index is now lower than readState.Index' (duration: 82.85912ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:00.299676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.45916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:34:00.299755Z","caller":"traceutil/trace.go:171","msg":"trace[215047971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3105; }","duration":"100.579703ms","start":"2026-05-21T15:34:00.199162Z","end":"2026-05-21T15:34:00.299742Z","steps":["trace[215047971] 'agreement among raft nodes before linearized reading' (duration: 100.416888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:00.684079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.327496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:34:00.684186Z","caller":"traceutil/trace.go:171","msg":"trace[1605938003] linearizableReadLoop","detail":"{readStateIndex:3225; appliedIndex:3223; }","duration":"365.635058ms","start":"2026-05-21T15:34:00.318533Z","end":"2026-05-21T15:34:00.684168Z","steps":["trace[1605938003] 'read index received' (duration: 33.641678ms)","trace[1605938003] 'applied index is now lower than readState.Index' (duration: 331.99193ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:00.684297Z","caller":"traceutil/trace.go:171","msg":"trace[1548877499] transaction","detail":"{read_only:false; response_revision:3107; number_of_response:1; }","duration":"380.04645ms","start":"2026-05-21T15:34:00.304207Z","end":"2026-05-21T15:34:00.684254Z","steps":["trace[1548877499] 'process raft request' (duration: 106.454297ms)","trace[1548877499] 'compare' (duration: 272.894414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:00.684393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.873494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-21T15:34:00.684424Z","caller":"traceutil/trace.go:171","msg":"trace[155329231] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3107; }","duration":"365.953117ms","start":"2026-05-21T15:34:00.318461Z","end":"2026-05-21T15:34:00.684414Z","steps":["trace[155329231] 'agreement among raft nodes before linearized reading' (duration: 365.795802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:00.684458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:00.318389Z","time spent":"366.061949ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-21T15:34:00.68463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:00.304187Z","time spent":"380.215835ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:34:00.684745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.081289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-21T15:34:00.684946Z","caller":"traceutil/trace.go:171","msg":"trace[1483379610] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3107; }","duration":"133.434118ms","start":"2026-05-21T15:34:00.551489Z","end":"2026-05-21T15:34:00.684923Z","steps":["trace[1483379610] 'agreement among raft nodes before linearized reading' (duration: 132.862233ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:00.915629Z","caller":"traceutil/trace.go:171","msg":"trace[1205043389] linearizableReadLoop","detail":"{readStateIndex:3227; appliedIndex:3225; }","duration":"225.945227ms","start":"2026-05-21T15:34:00.689661Z","end":"2026-05-21T15:34:00.915607Z","steps":["trace[1205043389] 'read index received' (duration: 84.399292ms)","trace[1205043389] 'applied index is now lower than readState.Index' (duration: 141.544934ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:00.915747Z","caller":"traceutil/trace.go:171","msg":"trace[1097593617] transaction","detail":"{read_only:false; response_revision:3109; number_of_response:1; }","duration":"226.521442ms","start":"2026-05-21T15:34:00.689188Z","end":"2026-05-21T15:34:00.91571Z","steps":["trace[1097593617] 'process raft request' (duration: 226.276385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:00.915844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.159572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9283"} {"level":"info","ts":"2026-05-21T15:34:00.915878Z","caller":"traceutil/trace.go:171","msg":"trace[442086892] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:3109; }","duration":"226.230644ms","start":"2026-05-21T15:34:00.689638Z","end":"2026-05-21T15:34:00.915869Z","steps":["trace[442086892] 'agreement among raft nodes before linearized reading' (duration: 226.064639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:01.274055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.366343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:34:01.274152Z","caller":"traceutil/trace.go:171","msg":"trace[1352168649] linearizableReadLoop","detail":"{readStateIndex:3228; appliedIndex:3227; }","duration":"306.986026ms","start":"2026-05-21T15:34:00.96715Z","end":"2026-05-21T15:34:01.274136Z","steps":["trace[1352168649] 'read index received' (duration: 135.452927ms)","trace[1352168649] 'applied index is now lower than readState.Index' (duration: 171.532018ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:01.274358Z","caller":"traceutil/trace.go:171","msg":"trace[447085723] transaction","detail":"{read_only:false; response_revision:3110; number_of_response:1; }","duration":"308.246131ms","start":"2026-05-21T15:34:00.966098Z","end":"2026-05-21T15:34:01.274344Z","steps":["trace[447085723] 'process raft request' (duration: 136.507247ms)","trace[447085723] 'compare' (duration: 170.989893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:01.274449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:00.966071Z","time spent":"308.316123ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:34:01.274615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.760844ms","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-05-21T15:34:01.274665Z","caller":"traceutil/trace.go:171","msg":"trace[96496976] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3110; }","duration":"194.859756ms","start":"2026-05-21T15:34:01.079793Z","end":"2026-05-21T15:34:01.274653Z","steps":["trace[96496976] 'agreement among raft nodes before linearized reading' (duration: 194.692462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:01.274804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.633874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:34:01.274903Z","caller":"traceutil/trace.go:171","msg":"trace[876877224] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3110; }","duration":"307.777457ms","start":"2026-05-21T15:34:00.967102Z","end":"2026-05-21T15:34:01.27488Z","steps":["trace[876877224] 'agreement among raft nodes before linearized reading' (duration: 307.618442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:01.274961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:00.967044Z","time spent":"307.90339ms","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-21T15:34:02.49776Z","caller":"traceutil/trace.go:171","msg":"trace[804822051] transaction","detail":"{read_only:false; response_revision:3114; number_of_response:1; }","duration":"192.945699ms","start":"2026-05-21T15:34:02.304788Z","end":"2026-05-21T15:34:02.497733Z","steps":["trace[804822051] 'process raft request' (duration: 192.773925ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:02.543026Z","caller":"traceutil/trace.go:171","msg":"trace[869490938] linearizableReadLoop","detail":"{readStateIndex:3233; appliedIndex:3231; }","duration":"186.632435ms","start":"2026-05-21T15:34:02.356368Z","end":"2026-05-21T15:34:02.543Z","steps":["trace[869490938] 'read index received' (duration: 141.201765ms)","trace[869490938] 'applied index is now lower than readState.Index' (duration: 45.42953ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:02.543187Z","caller":"traceutil/trace.go:171","msg":"trace[372791580] transaction","detail":"{read_only:false; response_revision:3115; number_of_response:1; }","duration":"238.260155ms","start":"2026-05-21T15:34:02.304892Z","end":"2026-05-21T15:34:02.543153Z","steps":["trace[372791580] 'process raft request' (duration: 237.967977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:02.543422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.039655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:34:02.543474Z","caller":"traceutil/trace.go:171","msg":"trace[1715034053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3115; }","duration":"187.149609ms","start":"2026-05-21T15:34:02.356312Z","end":"2026-05-21T15:34:02.543461Z","steps":["trace[1715034053] 'agreement among raft nodes before linearized reading' (duration: 186.921883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:02.715441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.582577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:34:02.71564Z","caller":"traceutil/trace.go:171","msg":"trace[1170047] transaction","detail":"{read_only:false; response_revision:3116; number_of_response:1; }","duration":"168.063139ms","start":"2026-05-21T15:34:02.547536Z","end":"2026-05-21T15:34:02.715599Z","steps":["trace[1170047] 'process raft request' (duration: 58.245436ms)","trace[1170047] 'compare' (duration: 109.468554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:02.98069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.623494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:34:02.980903Z","caller":"traceutil/trace.go:171","msg":"trace[1718808092] transaction","detail":"{read_only:false; response_revision:3118; number_of_response:1; }","duration":"198.643579ms","start":"2026-05-21T15:34:02.782237Z","end":"2026-05-21T15:34:02.980881Z","steps":["trace[1718808092] 'process raft request' (duration: 42.740026ms)","trace[1718808092] 'compare' (duration: 155.414619ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:03.423978Z","caller":"traceutil/trace.go:171","msg":"trace[350312782] transaction","detail":"{read_only:false; response_revision:3120; number_of_response:1; }","duration":"115.894956ms","start":"2026-05-21T15:34:03.30806Z","end":"2026-05-21T15:34:03.423955Z","steps":["trace[350312782] 'process raft request' (duration: 115.752801ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:04.368832Z","caller":"traceutil/trace.go:171","msg":"trace[291235183] transaction","detail":"{read_only:false; response_revision:3123; number_of_response:1; }","duration":"143.785357ms","start":"2026-05-21T15:34:04.225007Z","end":"2026-05-21T15:34:04.368792Z","steps":["trace[291235183] 'process raft request' (duration: 143.465988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:04.867981Z","caller":"traceutil/trace.go:171","msg":"trace[752160723] linearizableReadLoop","detail":"{readStateIndex:3244; appliedIndex:3243; }","duration":"147.696824ms","start":"2026-05-21T15:34:04.720256Z","end":"2026-05-21T15:34:04.867953Z","steps":["trace[752160723] 'read index received' (duration: 112.692118ms)","trace[752160723] 'applied index is now lower than readState.Index' (duration: 35.003296ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:04.868219Z","caller":"traceutil/trace.go:171","msg":"trace[1297115945] transaction","detail":"{read_only:false; response_revision:3125; number_of_response:1; }","duration":"314.775854ms","start":"2026-05-21T15:34:04.553404Z","end":"2026-05-21T15:34:04.86818Z","steps":["trace[1297115945] 'process raft request' (duration: 279.630005ms)","trace[1297115945] 'compare' (duration: 34.664536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:04.868372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:04.553378Z","time spent":"314.916188ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:34:04.868629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.374204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T15:34:04.868865Z","caller":"traceutil/trace.go:171","msg":"trace[244591624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3125; }","duration":"148.655081ms","start":"2026-05-21T15:34:04.720197Z","end":"2026-05-21T15:34:04.868852Z","steps":["trace[244591624] 'agreement among raft nodes before linearized reading' (duration: 148.279091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:04.869364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.162891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T15:34:04.869418Z","caller":"traceutil/trace.go:171","msg":"trace[2003327044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3125; }","duration":"130.275284ms","start":"2026-05-21T15:34:04.73913Z","end":"2026-05-21T15:34:04.869405Z","steps":["trace[2003327044] 'agreement among raft nodes before linearized reading' (duration: 129.809791ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:05.114911Z","caller":"traceutil/trace.go:171","msg":"trace[1426309722] linearizableReadLoop","detail":"{readStateIndex:3246; appliedIndex:3245; }","duration":"130.488043ms","start":"2026-05-21T15:34:04.98439Z","end":"2026-05-21T15:34:05.114878Z","steps":["trace[1426309722] 'read index received' (duration: 37.629307ms)","trace[1426309722] 'applied index is now lower than readState.Index' (duration: 92.856736ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:05.115153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.74144ms","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-05-21T15:34:05.115227Z","caller":"traceutil/trace.go:171","msg":"trace[1807655316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3127; }","duration":"130.854722ms","start":"2026-05-21T15:34:04.984359Z","end":"2026-05-21T15:34:05.115214Z","steps":["trace[1807655316] 'agreement among raft nodes before linearized reading' (duration: 130.676057ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:05.115011Z","caller":"traceutil/trace.go:171","msg":"trace[1340484515] transaction","detail":"{read_only:false; response_revision:3127; number_of_response:1; }","duration":"241.658258ms","start":"2026-05-21T15:34:04.873287Z","end":"2026-05-21T15:34:05.114946Z","steps":["trace[1340484515] 'process raft request' (duration: 148.787032ms)","trace[1340484515] 'compare' (duration: 92.548427ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:05.555174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.012441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:34:05.555447Z","caller":"traceutil/trace.go:171","msg":"trace[1652916597] transaction","detail":"{read_only:false; response_revision:3129; number_of_response:1; }","duration":"211.376677ms","start":"2026-05-21T15:34:05.344045Z","end":"2026-05-21T15:34:05.555422Z","steps":["trace[1652916597] 'process raft request' (duration: 46.027786ms)","trace[1652916597] 'compare' (duration: 164.879618ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:05.964291Z","caller":"traceutil/trace.go:171","msg":"trace[1486656670] transaction","detail":"{read_only:false; response_revision:3130; number_of_response:1; }","duration":"144.9417ms","start":"2026-05-21T15:34:05.819324Z","end":"2026-05-21T15:34:05.964265Z","steps":["trace[1486656670] 'process raft request' (duration: 144.796715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:06.76286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.54897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:34:06.763014Z","caller":"traceutil/trace.go:171","msg":"trace[2002977208] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3133; }","duration":"104.774307ms","start":"2026-05-21T15:34:06.658204Z","end":"2026-05-21T15:34:06.762979Z","steps":["trace[2002977208] 'count revisions from in-memory index tree' (duration: 104.456968ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:07.27725Z","caller":"traceutil/trace.go:171","msg":"trace[1188152469] linearizableReadLoop","detail":"{readStateIndex:3255; appliedIndex:3254; }","duration":"157.962466ms","start":"2026-05-21T15:34:07.119263Z","end":"2026-05-21T15:34:07.277226Z","steps":["trace[1188152469] 'read index received' (duration: 157.76573ms)","trace[1188152469] 'applied index is now lower than readState.Index' (duration: 195.646µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:07.277382Z","caller":"traceutil/trace.go:171","msg":"trace[1103059901] transaction","detail":"{read_only:false; response_revision:3136; number_of_response:1; }","duration":"166.583183ms","start":"2026-05-21T15:34:07.110784Z","end":"2026-05-21T15:34:07.277367Z","steps":["trace[1103059901] 'process raft request' (duration: 166.286265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:07.277563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.318788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-05-21T15:34:07.277618Z","caller":"traceutil/trace.go:171","msg":"trace[161900439] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3136; }","duration":"123.419781ms","start":"2026-05-21T15:34:07.154185Z","end":"2026-05-21T15:34:07.277605Z","steps":["trace[161900439] 'agreement among raft nodes before linearized reading' (duration: 123.270126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:07.277961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.671536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T15:34:07.278535Z","caller":"traceutil/trace.go:171","msg":"trace[30855999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3136; }","duration":"158.80799ms","start":"2026-05-21T15:34:07.119218Z","end":"2026-05-21T15:34:07.278026Z","steps":["trace[30855999] 'agreement among raft nodes before linearized reading' (duration: 158.540172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:07.559836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.665031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:34:07.5599Z","caller":"traceutil/trace.go:171","msg":"trace[977951707] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3138; }","duration":"179.791585ms","start":"2026-05-21T15:34:07.380096Z","end":"2026-05-21T15:34:07.559887Z","steps":["trace[977951707] 'count revisions from in-memory index tree' (duration: 179.568737ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:07.560332Z","caller":"traceutil/trace.go:171","msg":"trace[1262125942] transaction","detail":"{read_only:false; response_revision:3139; number_of_response:1; }","duration":"121.985751ms","start":"2026-05-21T15:34:07.438326Z","end":"2026-05-21T15:34:07.560312Z","steps":["trace[1262125942] 'process raft request' (duration: 50.109611ms)","trace[1262125942] 'compare' (duration: 71.588442ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:08.109386Z","caller":"traceutil/trace.go:171","msg":"trace[741424794] linearizableReadLoop","detail":"{readStateIndex:3260; appliedIndex:3259; }","duration":"103.350994ms","start":"2026-05-21T15:34:08.006013Z","end":"2026-05-21T15:34:08.109364Z","steps":["trace[741424794] 'read index received' (duration: 103.065116ms)","trace[741424794] 'applied index is now lower than readState.Index' (duration: 285.048µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:08.109445Z","caller":"traceutil/trace.go:171","msg":"trace[2009762319] transaction","detail":"{read_only:false; response_revision:3141; number_of_response:1; }","duration":"135.447333ms","start":"2026-05-21T15:34:07.973968Z","end":"2026-05-21T15:34:08.109415Z","steps":["trace[2009762319] 'process raft request' (duration: 135.221646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:08.109782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.747095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T15:34:08.109893Z","caller":"traceutil/trace.go:171","msg":"trace[442426082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3141; }","duration":"103.878989ms","start":"2026-05-21T15:34:08.005978Z","end":"2026-05-21T15:34:08.109857Z","steps":["trace[442426082] 'agreement among raft nodes before linearized reading' (duration: 103.612461ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:08.23788Z","caller":"traceutil/trace.go:171","msg":"trace[2058740645] transaction","detail":"{read_only:false; response_revision:3142; number_of_response:1; }","duration":"123.245087ms","start":"2026-05-21T15:34:08.114604Z","end":"2026-05-21T15:34:08.237849Z","steps":["trace[2058740645] 'process raft request' (duration: 82.00825ms)","trace[2058740645] 'compare' (duration: 40.96075ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:08.530944Z","caller":"traceutil/trace.go:171","msg":"trace[1268007594] transaction","detail":"{read_only:false; response_revision:3143; number_of_response:1; }","duration":"122.924889ms","start":"2026-05-21T15:34:08.407996Z","end":"2026-05-21T15:34:08.530921Z","steps":["trace[1268007594] 'process raft request' (duration: 122.710153ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:09.495731Z","caller":"traceutil/trace.go:171","msg":"trace[1045589859] transaction","detail":"{read_only:false; response_revision:3149; number_of_response:1; }","duration":"184.92939ms","start":"2026-05-21T15:34:09.31075Z","end":"2026-05-21T15:34:09.495679Z","steps":["trace[1045589859] 'process raft request' (duration: 127.245928ms)","trace[1045589859] 'compare' (duration: 57.476357ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:09.854063Z","caller":"traceutil/trace.go:171","msg":"trace[1267120933] linearizableReadLoop","detail":"{readStateIndex:3270; appliedIndex:3269; }","duration":"232.405855ms","start":"2026-05-21T15:34:09.621609Z","end":"2026-05-21T15:34:09.854015Z","steps":["trace[1267120933] 'read index received' (duration: 161.893853ms)","trace[1267120933] 'applied index is now lower than readState.Index' (duration: 70.509932ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:09.854117Z","caller":"traceutil/trace.go:171","msg":"trace[155792105] transaction","detail":"{read_only:false; response_revision:3150; number_of_response:1; }","duration":"242.950522ms","start":"2026-05-21T15:34:09.61111Z","end":"2026-05-21T15:34:09.854061Z","steps":["trace[155792105] 'process raft request' (duration: 172.313047ms)","trace[155792105] 'compare' (duration: 70.203303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:09.854275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.627981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:34:09.854542Z","caller":"traceutil/trace.go:171","msg":"trace[1773295982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3150; }","duration":"232.94163ms","start":"2026-05-21T15:34:09.621571Z","end":"2026-05-21T15:34:09.854513Z","steps":["trace[1773295982] 'agreement among raft nodes before linearized reading' (duration: 232.59452ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:10.22037Z","caller":"traceutil/trace.go:171","msg":"trace[1991839493] transaction","detail":"{read_only:false; response_revision:3151; number_of_response:1; }","duration":"102.075717ms","start":"2026-05-21T15:34:10.118272Z","end":"2026-05-21T15:34:10.220348Z","steps":["trace[1991839493] 'process raft request' (duration: 101.924032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:10.616281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.261667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T15:34:10.616396Z","caller":"traceutil/trace.go:171","msg":"trace[1876919834] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3152; }","duration":"111.480851ms","start":"2026-05-21T15:34:10.504888Z","end":"2026-05-21T15:34:10.616369Z","steps":["trace[1876919834] 'count revisions from in-memory index tree' (duration: 111.132242ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:10.708492Z","caller":"traceutil/trace.go:171","msg":"trace[45907008] transaction","detail":"{read_only:false; response_revision:3153; number_of_response:1; }","duration":"172.174482ms","start":"2026-05-21T15:34:10.536286Z","end":"2026-05-21T15:34:10.70846Z","steps":["trace[45907008] 'process raft request' (duration: 171.984817ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:13.116976Z","caller":"traceutil/trace.go:171","msg":"trace[260535410] transaction","detail":"{read_only:false; response_revision:3164; number_of_response:1; }","duration":"121.666156ms","start":"2026-05-21T15:34:12.995268Z","end":"2026-05-21T15:34:13.116934Z","steps":["trace[260535410] 'process raft request' (duration: 121.269875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:13.638875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.11678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T15:34:13.639233Z","caller":"traceutil/trace.go:171","msg":"trace[486815809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3166; }","duration":"101.533161ms","start":"2026-05-21T15:34:13.537679Z","end":"2026-05-21T15:34:13.639212Z","steps":["trace[486815809] 'range keys from in-memory index tree' (duration: 100.911695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:13.996862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.403214ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T15:34:14.160614Z","caller":"traceutil/trace.go:171","msg":"trace[973142343] transaction","detail":"{read_only:false; response_revision:3169; number_of_response:1; }","duration":"208.496511ms","start":"2026-05-21T15:34:13.952092Z","end":"2026-05-21T15:34:14.160588Z","steps":["trace[973142343] 'process raft request' (duration: 208.317356ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:14.905084Z","caller":"traceutil/trace.go:171","msg":"trace[928463606] transaction","detail":"{read_only:false; response_revision:3174; number_of_response:1; }","duration":"120.987359ms","start":"2026-05-21T15:34:14.784067Z","end":"2026-05-21T15:34:14.905054Z","steps":["trace[928463606] 'process raft request' (duration: 67.034576ms)","trace[928463606] 'compare' (duration: 53.807369ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:14.957578Z","caller":"traceutil/trace.go:171","msg":"trace[1455152850] transaction","detail":"{read_only:false; response_revision:3175; number_of_response:1; }","duration":"128.368569ms","start":"2026-05-21T15:34:14.829184Z","end":"2026-05-21T15:34:14.957553Z","steps":["trace[1455152850] 'process raft request' (duration: 128.256156ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:16.087353Z","caller":"traceutil/trace.go:171","msg":"trace[1654185847] linearizableReadLoop","detail":"{readStateIndex:3301; appliedIndex:3300; }","duration":"159.824672ms","start":"2026-05-21T15:34:15.927506Z","end":"2026-05-21T15:34:16.087331Z","steps":["trace[1654185847] 'read index received' (duration: 159.631537ms)","trace[1654185847] 'applied index is now lower than readState.Index' (duration: 191.725µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:16.087491Z","caller":"traceutil/trace.go:171","msg":"trace[1226630289] transaction","detail":"{read_only:false; response_revision:3180; number_of_response:1; }","duration":"162.272936ms","start":"2026-05-21T15:34:15.925155Z","end":"2026-05-21T15:34:16.087428Z","steps":["trace[1226630289] 'process raft request' (duration: 162.01941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:16.087522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.990786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:34:16.087746Z","caller":"traceutil/trace.go:171","msg":"trace[1091045155] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:3180; }","duration":"160.226172ms","start":"2026-05-21T15:34:15.927484Z","end":"2026-05-21T15:34:16.08771Z","steps":["trace[1091045155] 'agreement among raft nodes before linearized reading' (duration: 159.933304ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:16.43927Z","caller":"traceutil/trace.go:171","msg":"trace[943036585] transaction","detail":"{read_only:false; response_revision:3182; number_of_response:1; }","duration":"100.790133ms","start":"2026-05-21T15:34:16.338449Z","end":"2026-05-21T15:34:16.439239Z","steps":["trace[943036585] 'process raft request' (duration: 35.351729ms)","trace[943036585] 'compare' (duration: 65.2949ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:16.672835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.468682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:34:16.67297Z","caller":"traceutil/trace.go:171","msg":"trace[1604420170] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3183; }","duration":"107.654057ms","start":"2026-05-21T15:34:16.565278Z","end":"2026-05-21T15:34:16.672932Z","steps":["trace[1604420170] 'range keys from in-memory index tree' (duration: 107.37368ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:17.272249Z","caller":"traceutil/trace.go:171","msg":"trace[1386602887] transaction","detail":"{read_only:false; response_revision:3186; number_of_response:1; }","duration":"101.710561ms","start":"2026-05-21T15:34:17.170452Z","end":"2026-05-21T15:34:17.272163Z","steps":["trace[1386602887] 'process raft request' (duration: 101.416934ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:17.398564Z","caller":"traceutil/trace.go:171","msg":"trace[1309454487] transaction","detail":"{read_only:false; response_revision:3187; number_of_response:1; }","duration":"121.635296ms","start":"2026-05-21T15:34:17.276896Z","end":"2026-05-21T15:34:17.398531Z","steps":["trace[1309454487] 'process raft request' (duration: 42.110247ms)","trace[1309454487] 'compare' (duration: 79.313813ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:17.902737Z","caller":"traceutil/trace.go:171","msg":"trace[2119225019] linearizableReadLoop","detail":"{readStateIndex:3310; appliedIndex:3309; }","duration":"211.053896ms","start":"2026-05-21T15:34:17.691658Z","end":"2026-05-21T15:34:17.902711Z","steps":["trace[2119225019] 'read index received' (duration: 141.036653ms)","trace[2119225019] 'applied index is now lower than readState.Index' (duration: 70.016213ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:17.902806Z","caller":"traceutil/trace.go:171","msg":"trace[1017905382] transaction","detail":"{read_only:false; response_revision:3189; number_of_response:1; }","duration":"216.828102ms","start":"2026-05-21T15:34:17.685942Z","end":"2026-05-21T15:34:17.90277Z","steps":["trace[1017905382] 'process raft request' (duration: 146.663924ms)","trace[1017905382] 'compare' (duration: 70.008993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:17.902924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.260533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T15:34:17.902963Z","caller":"traceutil/trace.go:171","msg":"trace[56168308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3189; }","duration":"211.359095ms","start":"2026-05-21T15:34:17.691594Z","end":"2026-05-21T15:34:17.902953Z","steps":["trace[56168308] 'agreement among raft nodes before linearized reading' (duration: 211.209012ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:18.385652Z","caller":"traceutil/trace.go:171","msg":"trace[1432863554] transaction","detail":"{read_only:false; response_revision:3191; number_of_response:1; }","duration":"132.755114ms","start":"2026-05-21T15:34:18.252864Z","end":"2026-05-21T15:34:18.385619Z","steps":["trace[1432863554] 'process raft request' (duration: 132.556418ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:18.846545Z","caller":"traceutil/trace.go:171","msg":"trace[1975728345] transaction","detail":"{read_only:false; response_revision:3194; number_of_response:1; }","duration":"161.911953ms","start":"2026-05-21T15:34:18.684603Z","end":"2026-05-21T15:34:18.846515Z","steps":["trace[1975728345] 'process raft request' (duration: 108.256851ms)","trace[1975728345] 'compare' (duration: 53.491917ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:19.423887Z","caller":"traceutil/trace.go:171","msg":"trace[217055956] transaction","detail":"{read_only:false; response_revision:3197; number_of_response:1; }","duration":"146.652847ms","start":"2026-05-21T15:34:19.277202Z","end":"2026-05-21T15:34:19.423855Z","steps":["trace[217055956] 'process raft request' (duration: 146.453071ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:20.507667Z","caller":"traceutil/trace.go:171","msg":"trace[1931393193] transaction","detail":"{read_only:false; response_revision:3201; number_of_response:1; }","duration":"115.731265ms","start":"2026-05-21T15:34:20.391908Z","end":"2026-05-21T15:34:20.507639Z","steps":["trace[1931393193] 'process raft request' (duration: 115.521589ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:20.709361Z","caller":"traceutil/trace.go:171","msg":"trace[68628279] transaction","detail":"{read_only:false; response_revision:3203; number_of_response:1; }","duration":"139.25036ms","start":"2026-05-21T15:34:20.570073Z","end":"2026-05-21T15:34:20.709324Z","steps":["trace[68628279] 'process raft request' (duration: 120.548813ms)","trace[68628279] 'compare' (duration: 18.448821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:21.69009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.272927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:34:21.69027Z","caller":"traceutil/trace.go:171","msg":"trace[750019] transaction","detail":"{read_only:false; response_revision:3207; number_of_response:1; }","duration":"202.16023ms","start":"2026-05-21T15:34:21.488074Z","end":"2026-05-21T15:34:21.690234Z","steps":["trace[750019] 'process raft request' (duration: 86.594636ms)","trace[750019] 'compare' (duration: 115.067642ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:21.690293Z","caller":"traceutil/trace.go:171","msg":"trace[1900382976] linearizableReadLoop","detail":"{readStateIndex:3329; appliedIndex:3328; }","duration":"197.655721ms","start":"2026-05-21T15:34:21.492562Z","end":"2026-05-21T15:34:21.690218Z","steps":["trace[1900382976] 'read index received' (duration: 82.045786ms)","trace[1900382976] 'applied index is now lower than readState.Index' (duration: 115.608165ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:21.690502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.911397ms","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-05-21T15:34:21.69057Z","caller":"traceutil/trace.go:171","msg":"trace[614191035] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:3207; }","duration":"198.046722ms","start":"2026-05-21T15:34:21.492508Z","end":"2026-05-21T15:34:21.690555Z","steps":["trace[614191035] 'agreement among raft nodes before linearized reading' (duration: 197.808226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:21.690849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.530144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:34:21.690925Z","caller":"traceutil/trace.go:171","msg":"trace[1798472046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3207; }","duration":"138.642227ms","start":"2026-05-21T15:34:21.552261Z","end":"2026-05-21T15:34:21.690903Z","steps":["trace[1798472046] 'agreement among raft nodes before linearized reading' (duration: 138.504752ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:21.847202Z","caller":"traceutil/trace.go:171","msg":"trace[391484137] transaction","detail":"{read_only:false; response_revision:3208; number_of_response:1; }","duration":"152.142533ms","start":"2026-05-21T15:34:21.695009Z","end":"2026-05-21T15:34:21.847151Z","steps":["trace[391484137] 'process raft request' (duration: 103.681908ms)","trace[391484137] 'compare' (duration: 47.675335ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:21.847466Z","caller":"traceutil/trace.go:171","msg":"trace[1262623786] linearizableReadLoop","detail":"{readStateIndex:3330; appliedIndex:3329; }","duration":"147.490831ms","start":"2026-05-21T15:34:21.699856Z","end":"2026-05-21T15:34:21.847347Z","steps":["trace[1262623786] 'read index received' (duration: 98.736167ms)","trace[1262623786] 'applied index is now lower than readState.Index' (duration: 48.752804ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:21.847672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.781789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-21T15:34:21.847769Z","caller":"traceutil/trace.go:171","msg":"trace[332438698] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3208; }","duration":"147.917873ms","start":"2026-05-21T15:34:21.699831Z","end":"2026-05-21T15:34:21.847749Z","steps":["trace[332438698] 'agreement among raft nodes before linearized reading' (duration: 147.622824ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:22.711211Z","caller":"traceutil/trace.go:171","msg":"trace[2004297636] linearizableReadLoop","detail":"{readStateIndex:3333; appliedIndex:3332; }","duration":"149.858114ms","start":"2026-05-21T15:34:22.561329Z","end":"2026-05-21T15:34:22.711187Z","steps":["trace[2004297636] 'read index received' (duration: 73.675609ms)","trace[2004297636] 'applied index is now lower than readState.Index' (duration: 76.181635ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:22.711257Z","caller":"traceutil/trace.go:171","msg":"trace[737570952] transaction","detail":"{read_only:false; response_revision:3211; number_of_response:1; }","duration":"197.076015ms","start":"2026-05-21T15:34:22.514144Z","end":"2026-05-21T15:34:22.71122Z","steps":["trace[737570952] 'process raft request' (duration: 120.868378ms)","trace[737570952] 'compare' (duration: 76.061752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:22.711493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.154853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-21T15:34:22.711953Z","caller":"traceutil/trace.go:171","msg":"trace[2006755298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3211; }","duration":"150.665496ms","start":"2026-05-21T15:34:22.561271Z","end":"2026-05-21T15:34:22.711937Z","steps":["trace[2006755298] 'agreement among raft nodes before linearized reading' (duration: 150.04603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:23.155997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.819611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:34:23.156118Z","caller":"traceutil/trace.go:171","msg":"trace[1987624943] linearizableReadLoop","detail":"{readStateIndex:3335; appliedIndex:3334; }","duration":"314.578814ms","start":"2026-05-21T15:34:22.841521Z","end":"2026-05-21T15:34:23.1561Z","steps":["trace[1987624943] 'read index received' (duration: 30.64µs)","trace[1987624943] 'applied index is now lower than readState.Index' (duration: 314.546384ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:23.156198Z","caller":"traceutil/trace.go:171","msg":"trace[1896849771] transaction","detail":"{read_only:false; response_revision:3213; number_of_response:1; }","duration":"436.320044ms","start":"2026-05-21T15:34:22.719866Z","end":"2026-05-21T15:34:23.156186Z","steps":["trace[1896849771] 'process raft request' (duration: 121.210946ms)","trace[1896849771] 'compare' (duration: 314.442181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:23.15627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:22.719839Z","time spent":"436.394847ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:34:23.15656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.001044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:34:23.156633Z","caller":"traceutil/trace.go:171","msg":"trace[441366550] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3213; }","duration":"315.139088ms","start":"2026-05-21T15:34:22.841476Z","end":"2026-05-21T15:34:23.156615Z","steps":["trace[441366550] 'agreement among raft nodes before linearized reading' (duration: 314.82182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:23.156663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:22.841459Z","time spent":"315.19716ms","remote":"127.0.0.1:40658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-21T15:34:23.38705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.112692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:34:23.38739Z","caller":"traceutil/trace.go:171","msg":"trace[2054456411] transaction","detail":"{read_only:false; response_revision:3214; number_of_response:1; }","duration":"483.038638ms","start":"2026-05-21T15:34:22.904282Z","end":"2026-05-21T15:34:23.387321Z","steps":["trace[2054456411] 'process raft request' (duration: 351.538386ms)","trace[2054456411] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:506; } (duration: 130.751242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:34:23.38756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:22.90426Z","time spent":"483.202352ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:34:23.387875Z","caller":"traceutil/trace.go:171","msg":"trace[103378983] linearizableReadLoop","detail":"{readStateIndex:3337; appliedIndex:3335; }","duration":"231.709278ms","start":"2026-05-21T15:34:23.156145Z","end":"2026-05-21T15:34:23.387855Z","steps":["trace[103378983] 'read index received' (duration: 99.756654ms)","trace[103378983] 'applied index is now lower than readState.Index' (duration: 131.950884ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:34:23.388033Z","caller":"traceutil/trace.go:171","msg":"trace[83920172] transaction","detail":"{read_only:false; response_revision:3215; number_of_response:1; }","duration":"289.474702ms","start":"2026-05-21T15:34:23.098527Z","end":"2026-05-21T15:34:23.388001Z","steps":["trace[83920172] 'process raft request' (duration: 288.63247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:23.388382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.934018ms","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-05-21T15:34:23.388433Z","caller":"traceutil/trace.go:171","msg":"trace[1597659560] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3215; }","duration":"308.035761ms","start":"2026-05-21T15:34:23.080385Z","end":"2026-05-21T15:34:23.388421Z","steps":["trace[1597659560] 'agreement among raft nodes before linearized reading' (duration: 307.552208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:34:23.38847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:34:23.08036Z","time spent":"308.102393ms","remote":"127.0.0.1:43772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-21T15:34:23.874881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.459534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T15:34:23.874988Z","caller":"traceutil/trace.go:171","msg":"trace[1445275304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3216; }","duration":"180.626129ms","start":"2026-05-21T15:34:23.694345Z","end":"2026-05-21T15:34:23.874971Z","steps":["trace[1445275304] 'range keys from in-memory index tree' (duration: 180.153896ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:24.155111Z","caller":"traceutil/trace.go:171","msg":"trace[1528861201] transaction","detail":"{read_only:false; response_revision:3218; number_of_response:1; }","duration":"276.491911ms","start":"2026-05-21T15:34:23.878595Z","end":"2026-05-21T15:34:24.155087Z","steps":["trace[1528861201] 'process raft request' (duration: 274.687793ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:34:24.204998Z","caller":"traceutil/trace.go:171","msg":"trace[442814398] transaction","detail":"{read_only:false; response_revision:3219; number_of_response:1; }","duration":"278.667586ms","start":"2026-05-21T15:34:23.926304Z","end":"2026-05-21T15:34:24.204972Z","steps":["trace[442814398] 'process raft request' (duration: 278.231595ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:35:35.719119Z","caller":"traceutil/trace.go:171","msg":"trace[435637650] transaction","detail":"{read_only:false; response_revision:3682; number_of_response:1; }","duration":"141.283808ms","start":"2026-05-21T15:35:35.577802Z","end":"2026-05-21T15:35:35.719086Z","steps":["trace[435637650] 'process raft request' (duration: 141.076363ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:35:41.33752Z","caller":"traceutil/trace.go:171","msg":"trace[1198486538] linearizableReadLoop","detail":"{readStateIndex:3856; appliedIndex:3855; }","duration":"123.386799ms","start":"2026-05-21T15:35:41.214089Z","end":"2026-05-21T15:35:41.337476Z","steps":["trace[1198486538] 'read index received' (duration: 81.658738ms)","trace[1198486538] 'applied index is now lower than readState.Index' (duration: 41.727071ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:35:41.337698Z","caller":"traceutil/trace.go:171","msg":"trace[1226075956] transaction","detail":"{read_only:false; response_revision:3714; number_of_response:1; }","duration":"159.476248ms","start":"2026-05-21T15:35:41.178083Z","end":"2026-05-21T15:35:41.337559Z","steps":["trace[1226075956] 'process raft request' (duration: 117.676097ms)","trace[1226075956] 'compare' (duration: 41.562216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:35:41.337895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.738716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T15:35:41.337993Z","caller":"traceutil/trace.go:171","msg":"trace[1772973096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3714; }","duration":"123.903981ms","start":"2026-05-21T15:35:41.214042Z","end":"2026-05-21T15:35:41.337946Z","steps":["trace[1772973096] 'agreement among raft nodes before linearized reading' (duration: 123.572894ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:35:41.944473Z","caller":"traceutil/trace.go:171","msg":"trace[950506837] transaction","detail":"{read_only:false; response_revision:3717; number_of_response:1; }","duration":"156.01881ms","start":"2026-05-21T15:35:41.788424Z","end":"2026-05-21T15:35:41.944443Z","steps":["trace[950506837] 'process raft request' (duration: 70.229226ms)","trace[950506837] 'compare' (duration: 85.285602ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:35:41.945327Z","caller":"traceutil/trace.go:171","msg":"trace[321209014] transaction","detail":"{read_only:false; response_revision:3719; number_of_response:1; }","duration":"119.199472ms","start":"2026-05-21T15:35:41.826097Z","end":"2026-05-21T15:35:41.945297Z","steps":["trace[321209014] 'process raft request' (duration: 119.12636ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:35:41.945496Z","caller":"traceutil/trace.go:171","msg":"trace[2057309067] transaction","detail":"{read_only:false; response_revision:3718; number_of_response:1; }","duration":"151.558787ms","start":"2026-05-21T15:35:41.793919Z","end":"2026-05-21T15:35:41.945478Z","steps":["trace[2057309067] 'process raft request' (duration: 151.148107ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:35:50.067566Z","caller":"traceutil/trace.go:171","msg":"trace[1021992340] transaction","detail":"{read_only:false; response_revision:3761; number_of_response:1; }","duration":"165.377742ms","start":"2026-05-21T15:35:49.902158Z","end":"2026-05-21T15:35:50.067535Z","steps":["trace[1021992340] 'process raft request' (duration: 165.196688ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:35:50.138808Z","caller":"traceutil/trace.go:171","msg":"trace[1970187521] linearizableReadLoop","detail":"{readStateIndex:3907; appliedIndex:3904; }","duration":"159.010175ms","start":"2026-05-21T15:35:49.979776Z","end":"2026-05-21T15:35:50.138786Z","steps":["trace[1970187521] 'read index received' (duration: 87.544441ms)","trace[1970187521] 'applied index is now lower than readState.Index' (duration: 71.464934ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:35:50.138982Z","caller":"traceutil/trace.go:171","msg":"trace[1232101688] transaction","detail":"{read_only:false; response_revision:3763; number_of_response:1; }","duration":"165.313369ms","start":"2026-05-21T15:35:49.973632Z","end":"2026-05-21T15:35:50.138945Z","steps":["trace[1232101688] 'process raft request' (duration: 165.125854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:35:50.139157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.355123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T15:35:50.139212Z","caller":"traceutil/trace.go:171","msg":"trace[335840832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3763; }","duration":"159.503186ms","start":"2026-05-21T15:35:49.979695Z","end":"2026-05-21T15:35:50.139198Z","steps":["trace[335840832] 'agreement among raft nodes before linearized reading' (duration: 159.24489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:35:50.139053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.909478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:35:50.138957Z","caller":"traceutil/trace.go:171","msg":"trace[418419492] transaction","detail":"{read_only:false; response_revision:3762; number_of_response:1; }","duration":"167.202182ms","start":"2026-05-21T15:35:49.971691Z","end":"2026-05-21T15:35:50.138893Z","steps":["trace[418419492] 'process raft request' (duration: 166.953905ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:35:50.139432Z","caller":"traceutil/trace.go:171","msg":"trace[127478309] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:3763; }","duration":"157.321787ms","start":"2026-05-21T15:35:49.982075Z","end":"2026-05-21T15:35:50.139397Z","steps":["trace[127478309] 'agreement among raft nodes before linearized reading' (duration: 156.917917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:35:50.334488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.934193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:35:50.334558Z","caller":"traceutil/trace.go:171","msg":"trace[58077011] transaction","detail":"{read_only:false; response_revision:3764; number_of_response:1; }","duration":"189.821705ms","start":"2026-05-21T15:35:50.144726Z","end":"2026-05-21T15:35:50.334548Z","steps":["trace[58077011] 'process raft request' (duration: 52.695928ms)","trace[58077011] 'compare' (duration: 136.718479ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:35:52.640024Z","caller":"traceutil/trace.go:171","msg":"trace[30306897] transaction","detail":"{read_only:false; response_revision:3775; number_of_response:1; }","duration":"132.749791ms","start":"2026-05-21T15:35:52.50724Z","end":"2026-05-21T15:35:52.63999Z","steps":["trace[30306897] 'process raft request' (duration: 132.561356ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:36:58.212053Z","caller":"traceutil/trace.go:171","msg":"trace[1242655449] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"160.436899ms","start":"2026-05-21T15:36:58.05159Z","end":"2026-05-21T15:36:58.212027Z","steps":["trace[1242655449] 'process raft request' (duration: 160.255675ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:36:58.46555Z","caller":"traceutil/trace.go:171","msg":"trace[1431268153] linearizableReadLoop","detail":"{readStateIndex:4917; appliedIndex:4915; }","duration":"221.159725ms","start":"2026-05-21T15:36:58.244373Z","end":"2026-05-21T15:36:58.465532Z","steps":["trace[1431268153] 'read index received' (duration: 175.445906ms)","trace[1431268153] 'applied index is now lower than readState.Index' (duration: 45.713159ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:36:58.465748Z","caller":"traceutil/trace.go:171","msg":"trace[204987722] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"261.872129ms","start":"2026-05-21T15:36:58.203838Z","end":"2026-05-21T15:36:58.46571Z","steps":["trace[204987722] 'process raft request' (duration: 261.615792ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:36:58.465783Z","caller":"traceutil/trace.go:171","msg":"trace[2023975619] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"356.452321ms","start":"2026-05-21T15:36:58.109312Z","end":"2026-05-21T15:36:58.465764Z","steps":["trace[2023975619] 'process raft request' (duration: 310.490917ms)","trace[2023975619] 'compare' (duration: 45.542025ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:36:58.465878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:36:58.109294Z","time spent":"356.521473ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:36:58.466235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.85188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-05-21T15:36:58.466269Z","caller":"traceutil/trace.go:171","msg":"trace[1199300103] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4735; }","duration":"221.929062ms","start":"2026-05-21T15:36:58.244333Z","end":"2026-05-21T15:36:58.466262Z","steps":["trace[1199300103] 'agreement among raft nodes before linearized reading' (duration: 221.282608ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:04.784978Z","caller":"traceutil/trace.go:171","msg":"trace[1320837418] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"298.126666ms","start":"2026-05-21T15:37:04.486824Z","end":"2026-05-21T15:37:04.78495Z","steps":["trace[1320837418] 'process raft request' (duration: 297.953693ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:04.821061Z","caller":"traceutil/trace.go:171","msg":"trace[1330679761] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"334.053024ms","start":"2026-05-21T15:37:04.486979Z","end":"2026-05-21T15:37:04.821032Z","steps":["trace[1330679761] 'process raft request' (duration: 333.84984ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:04.821226Z","caller":"traceutil/trace.go:171","msg":"trace[1207723769] linearizableReadLoop","detail":"{readStateIndex:5035; appliedIndex:5032; }","duration":"277.986021ms","start":"2026-05-21T15:37:04.543217Z","end":"2026-05-21T15:37:04.821203Z","steps":["trace[1207723769] 'read index received' (duration: 241.494881ms)","trace[1207723769] 'applied index is now lower than readState.Index' (duration: 36.48967ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:37:04.821254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:04.486971Z","time spent":"334.170266ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:37:04.821302Z","caller":"traceutil/trace.go:171","msg":"trace[1128105159] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"323.060251ms","start":"2026-05-21T15:37:04.498214Z","end":"2026-05-21T15:37:04.821274Z","steps":["trace[1128105159] 'process raft request' (duration: 322.781166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:04.821444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:04.498198Z","time spent":"323.169564ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:37:04.821456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.37765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:37:04.821523Z","caller":"traceutil/trace.go:171","msg":"trace[661297438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4850; }","duration":"269.480633ms","start":"2026-05-21T15:37:04.552028Z","end":"2026-05-21T15:37:04.821509Z","steps":["trace[661297438] 'agreement among raft nodes before linearized reading' (duration: 269.38213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:04.821993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.449136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T15:37:04.822058Z","caller":"traceutil/trace.go:171","msg":"trace[2090231668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4850; }","duration":"126.56444ms","start":"2026-05-21T15:37:04.69548Z","end":"2026-05-21T15:37:04.822044Z","steps":["trace[2090231668] 'agreement among raft nodes before linearized reading' (duration: 126.356235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:04.822825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.584396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:37:04.82288Z","caller":"traceutil/trace.go:171","msg":"trace[1363748856] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4850; }","duration":"279.692278ms","start":"2026-05-21T15:37:04.543173Z","end":"2026-05-21T15:37:04.822865Z","steps":["trace[1363748856] 'agreement among raft nodes before linearized reading' (duration: 278.172006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:05.200741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.189045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:37:05.200863Z","caller":"traceutil/trace.go:171","msg":"trace[1179482042] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4852; }","duration":"308.332418ms","start":"2026-05-21T15:37:04.892492Z","end":"2026-05-21T15:37:05.200824Z","steps":["trace[1179482042] 'range keys from in-memory index tree' (duration: 308.105423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:05.200918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:04.892473Z","time spent":"308.4301ms","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-21T15:37:05.845055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.26119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:37:05.845247Z","caller":"traceutil/trace.go:171","msg":"trace[1528459428] linearizableReadLoop","detail":"{readStateIndex:5047; appliedIndex:5046; }","duration":"293.092894ms","start":"2026-05-21T15:37:05.552137Z","end":"2026-05-21T15:37:05.84523Z","steps":["trace[1528459428] 'read index received' (duration: 121.08649ms)","trace[1528459428] 'applied index is now lower than readState.Index' (duration: 172.005004ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:37:05.845448Z","caller":"traceutil/trace.go:171","msg":"trace[860279116] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"401.774794ms","start":"2026-05-21T15:37:05.443638Z","end":"2026-05-21T15:37:05.845413Z","steps":["trace[860279116] 'process raft request' (duration: 229.047655ms)","trace[860279116] 'compare' (duration: 172.007804ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:37:05.845502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.35409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:37:05.845596Z","caller":"traceutil/trace.go:171","msg":"trace[248296057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4862; }","duration":"293.497474ms","start":"2026-05-21T15:37:05.552081Z","end":"2026-05-21T15:37:05.845579Z","steps":["trace[248296057] 'agreement among raft nodes before linearized reading' (duration: 293.35888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:05.845855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:05.44362Z","time spent":"401.963759ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:37:13.475533Z","caller":"traceutil/trace.go:171","msg":"trace[1243815800] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"161.983242ms","start":"2026-05-21T15:37:13.31353Z","end":"2026-05-21T15:37:13.475513Z","steps":["trace[1243815800] 'process raft request' (duration: 160.674215ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:13.478508Z","caller":"traceutil/trace.go:171","msg":"trace[1807062656] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5177; }","duration":"113.63311ms","start":"2026-05-21T15:37:13.364852Z","end":"2026-05-21T15:37:13.478485Z","steps":["trace[1807062656] 'read index received' (duration: 109.359521ms)","trace[1807062656] 'applied index is now lower than readState.Index' (duration: 4.272899ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:37:13.47963Z","caller":"traceutil/trace.go:171","msg":"trace[1262771382] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"161.202096ms","start":"2026-05-21T15:37:13.318418Z","end":"2026-05-21T15:37:13.47962Z","steps":["trace[1262771382] 'process raft request' (duration: 159.907839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:13.479856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.987538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-21T15:37:13.479887Z","caller":"traceutil/trace.go:171","msg":"trace[1137333312] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4989; }","duration":"115.064379ms","start":"2026-05-21T15:37:13.364813Z","end":"2026-05-21T15:37:13.479877Z","steps":["trace[1137333312] 'agreement among raft nodes before linearized reading' (duration: 114.956837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:18.449771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667392422522764614,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T15:37:18.672217Z","caller":"traceutil/trace.go:171","msg":"trace[253094810] linearizableReadLoop","detail":"{readStateIndex:5220; appliedIndex:5219; }","duration":"722.845843ms","start":"2026-05-21T15:37:17.949344Z","end":"2026-05-21T15:37:18.67219Z","steps":["trace[253094810] 'read index received' (duration: 722.591288ms)","trace[253094810] 'applied index is now lower than readState.Index' (duration: 253.675µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:37:18.672411Z","caller":"traceutil/trace.go:171","msg":"trace[619000075] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"787.534235ms","start":"2026-05-21T15:37:17.884859Z","end":"2026-05-21T15:37:18.672393Z","steps":["trace[619000075] 'process raft request' (duration: 787.164016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:18.672512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.931351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:37:18.672594Z","caller":"traceutil/trace.go:171","msg":"trace[1864041322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5029; }","duration":"120.074134ms","start":"2026-05-21T15:37:18.552497Z","end":"2026-05-21T15:37:18.672571Z","steps":["trace[1864041322] 'agreement among raft nodes before linearized reading' (duration: 119.904471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:18.672585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.034941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-21T15:37:18.672673Z","caller":"traceutil/trace.go:171","msg":"trace[1558377502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5029; }","duration":"706.182205ms","start":"2026-05-21T15:37:17.966472Z","end":"2026-05-21T15:37:18.672654Z","steps":["trace[1558377502] 'agreement among raft nodes before linearized reading' (duration: 705.910689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:18.672648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:17.88483Z","time spent":"787.671017ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:37:18.672717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:17.966453Z","time spent":"706.256106ms","remote":"127.0.0.1:43846","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-05-21T15:37:18.673019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.867517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16551"} {"level":"warn","ts":"2026-05-21T15:37:18.672989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.295041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-21T15:37:18.673054Z","caller":"traceutil/trace.go:171","msg":"trace[382201280] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5029; }","duration":"344.952997ms","start":"2026-05-21T15:37:18.328087Z","end":"2026-05-21T15:37:18.67304Z","steps":["trace[382201280] 'agreement among raft nodes before linearized reading' (duration: 344.849645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:18.673083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:18.328044Z","time spent":"345.03271ms","remote":"127.0.0.1:43766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16574,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-21T15:37:18.673095Z","caller":"traceutil/trace.go:171","msg":"trace[1388483199] 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:5029; }","duration":"317.497255ms","start":"2026-05-21T15:37:18.355575Z","end":"2026-05-21T15:37:18.673072Z","steps":["trace[1388483199] 'agreement among raft nodes before linearized reading' (duration: 317.164138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:18.673799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.693587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-05-21T15:37:18.673862Z","caller":"traceutil/trace.go:171","msg":"trace[1649864597] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5029; }","duration":"188.783149ms","start":"2026-05-21T15:37:18.485059Z","end":"2026-05-21T15:37:18.673842Z","steps":["trace[1649864597] 'agreement among raft nodes before linearized reading' (duration: 188.440492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:18.674115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.757713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-21T15:37:18.674169Z","caller":"traceutil/trace.go:171","msg":"trace[937843197] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5029; }","duration":"724.874555ms","start":"2026-05-21T15:37:17.94928Z","end":"2026-05-21T15:37:18.674154Z","steps":["trace[937843197] 'agreement among raft nodes before linearized reading' (duration: 723.824393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:18.674219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:17.949261Z","time spent":"724.942618ms","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":537,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-21T15:37:18.673388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:37:18.355551Z","time spent":"317.613657ms","remote":"127.0.0.1:43738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-05-21T15:37:45.656339Z","caller":"traceutil/trace.go:171","msg":"trace[878430637] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"163.970664ms","start":"2026-05-21T15:37:45.492332Z","end":"2026-05-21T15:37:45.656303Z","steps":["trace[878430637] 'process raft request' (duration: 138.733965ms)","trace[878430637] 'compare' (duration: 25.001473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:37:45.656381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.035882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:37:45.65718Z","caller":"traceutil/trace.go:171","msg":"trace[2135848294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5302; }","duration":"100.780356ms","start":"2026-05-21T15:37:45.556261Z","end":"2026-05-21T15:37:45.657041Z","steps":["trace[2135848294] 'agreement among raft nodes before linearized reading' (duration: 100.038401ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:47.038696Z","caller":"traceutil/trace.go:171","msg":"trace[1561410772] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"117.431527ms","start":"2026-05-21T15:37:46.921245Z","end":"2026-05-21T15:37:47.038677Z","steps":["trace[1561410772] 'process raft request' (duration: 117.303943ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:47.361589Z","caller":"traceutil/trace.go:171","msg":"trace[875081620] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"249.502111ms","start":"2026-05-21T15:37:47.112025Z","end":"2026-05-21T15:37:47.361527Z","steps":["trace[875081620] 'process raft request' (duration: 165.864498ms)","trace[875081620] 'compare' (duration: 83.50419ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:37:47.368195Z","caller":"traceutil/trace.go:171","msg":"trace[262958810] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"255.010944ms","start":"2026-05-21T15:37:47.11316Z","end":"2026-05-21T15:37:47.368171Z","steps":["trace[262958810] 'process raft request' (duration: 254.854681ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:47.368391Z","caller":"traceutil/trace.go:171","msg":"trace[940570906] linearizableReadLoop","detail":"{readStateIndex:5511; appliedIndex:5508; }","duration":"250.289617ms","start":"2026-05-21T15:37:47.118063Z","end":"2026-05-21T15:37:47.368353Z","steps":["trace[940570906] 'read index received' (duration: 159.819754ms)","trace[940570906] 'applied index is now lower than readState.Index' (duration: 90.467943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:37:47.368606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.101602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T15:37:47.368607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.518992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T15:37:47.368648Z","caller":"traceutil/trace.go:171","msg":"trace[1319492950] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5313; }","duration":"145.187394ms","start":"2026-05-21T15:37:47.223449Z","end":"2026-05-21T15:37:47.368636Z","steps":["trace[1319492950] 'agreement among raft nodes before linearized reading' (duration: 145.084573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:47.368642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.084783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T15:37:47.368671Z","caller":"traceutil/trace.go:171","msg":"trace[890403332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5313; }","duration":"250.602324ms","start":"2026-05-21T15:37:47.118058Z","end":"2026-05-21T15:37:47.36866Z","steps":["trace[890403332] 'agreement among raft nodes before linearized reading' (duration: 250.410809ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:47.3687Z","caller":"traceutil/trace.go:171","msg":"trace[1804907038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5313; }","duration":"250.164035ms","start":"2026-05-21T15:37:47.118522Z","end":"2026-05-21T15:37:47.368686Z","steps":["trace[1804907038] 'agreement among raft nodes before linearized reading' (duration: 249.999861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:47.36881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.363028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:37:47.368838Z","caller":"traceutil/trace.go:171","msg":"trace[1144791697] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5313; }","duration":"146.47739ms","start":"2026-05-21T15:37:47.222352Z","end":"2026-05-21T15:37:47.36883Z","steps":["trace[1144791697] 'agreement among raft nodes before linearized reading' (duration: 146.43004ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:47.368948Z","caller":"traceutil/trace.go:171","msg":"trace[1258378805] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"255.199517ms","start":"2026-05-21T15:37:47.113221Z","end":"2026-05-21T15:37:47.36842Z","steps":["trace[1258378805] 'process raft request' (duration: 254.896921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:47.369033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.610081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-05-21T15:37:47.369132Z","caller":"traceutil/trace.go:171","msg":"trace[936364087] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5313; }","duration":"141.747024ms","start":"2026-05-21T15:37:47.227366Z","end":"2026-05-21T15:37:47.369113Z","steps":["trace[936364087] 'agreement among raft nodes before linearized reading' (duration: 141.496679ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:48.90747Z","caller":"traceutil/trace.go:171","msg":"trace[1571111720] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"128.920229ms","start":"2026-05-21T15:37:48.778522Z","end":"2026-05-21T15:37:48.907442Z","steps":["trace[1571111720] 'process raft request' (duration: 128.764467ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:49.895291Z","caller":"traceutil/trace.go:171","msg":"trace[1787812438] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"164.718011ms","start":"2026-05-21T15:37:49.730546Z","end":"2026-05-21T15:37:49.895264Z","steps":["trace[1787812438] 'process raft request' (duration: 164.429635ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:49.895204Z","caller":"traceutil/trace.go:171","msg":"trace[2009507388] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5525; }","duration":"151.119382ms","start":"2026-05-21T15:37:49.744058Z","end":"2026-05-21T15:37:49.895177Z","steps":["trace[2009507388] 'read index received' (duration: 150.830886ms)","trace[2009507388] 'applied index is now lower than readState.Index' (duration: 287.296µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:37:49.895471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.761491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:37:49.895498Z","caller":"traceutil/trace.go:171","msg":"trace[914232245] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5327; }","duration":"120.812772ms","start":"2026-05-21T15:37:49.774677Z","end":"2026-05-21T15:37:49.89549Z","steps":["trace[914232245] 'agreement among raft nodes before linearized reading' (duration: 120.743151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:37:49.895538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.458519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-21T15:37:49.89558Z","caller":"traceutil/trace.go:171","msg":"trace[2040884666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5327; }","duration":"151.547511ms","start":"2026-05-21T15:37:49.74402Z","end":"2026-05-21T15:37:49.895567Z","steps":["trace[2040884666] 'agreement among raft nodes before linearized reading' (duration: 151.386988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:51.519014Z","caller":"traceutil/trace.go:171","msg":"trace[1953707847] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5532; }","duration":"123.873872ms","start":"2026-05-21T15:37:51.395118Z","end":"2026-05-21T15:37:51.518992Z","steps":["trace[1953707847] 'read index received' (duration: 105.587447ms)","trace[1953707847] 'applied index is now lower than readState.Index' (duration: 18.285615ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:37:51.519232Z","caller":"traceutil/trace.go:171","msg":"trace[13314878] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"127.405784ms","start":"2026-05-21T15:37:51.391795Z","end":"2026-05-21T15:37:51.519201Z","steps":["trace[13314878] 'process raft request' (duration: 109.003157ms)","trace[13314878] 'compare' (duration: 18.026549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:37:51.519251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.115586ms","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-05-21T15:37:51.5193Z","caller":"traceutil/trace.go:171","msg":"trace[1711244416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5334; }","duration":"124.20013ms","start":"2026-05-21T15:37:51.395089Z","end":"2026-05-21T15:37:51.519289Z","steps":["trace[1711244416] 'agreement among raft nodes before linearized reading' (duration: 123.990146ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:37:59.782544Z","caller":"traceutil/trace.go:171","msg":"trace[2004043972] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"101.104248ms","start":"2026-05-21T15:37:59.681407Z","end":"2026-05-21T15:37:59.782512Z","steps":["trace[2004043972] 'process raft request' (duration: 31.204675ms)","trace[2004043972] 'compare' (duration: 69.687568ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:38:05.674876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.703092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:38:05.67509Z","caller":"traceutil/trace.go:171","msg":"trace[1286415200] linearizableReadLoop","detail":"{readStateIndex:5638; appliedIndex:5636; }","duration":"122.350762ms","start":"2026-05-21T15:38:05.552721Z","end":"2026-05-21T15:38:05.675072Z","steps":["trace[1286415200] 'read index received' (duration: 37.25µs)","trace[1286415200] 'applied index is now lower than readState.Index' (duration: 122.312432ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:38:05.67511Z","caller":"traceutil/trace.go:171","msg":"trace[832515546] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"225.387942ms","start":"2026-05-21T15:38:05.449689Z","end":"2026-05-21T15:38:05.675077Z","steps":["trace[832515546] 'process raft request' (duration: 91.383364ms)","trace[832515546] 'compare' (duration: 133.460047ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:38:05.675188Z","caller":"traceutil/trace.go:171","msg":"trace[1673275303] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"225.000764ms","start":"2026-05-21T15:38:05.450177Z","end":"2026-05-21T15:38:05.675178Z","steps":["trace[1673275303] 'process raft request' (duration: 224.80673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:05.675334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.617417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:38:05.67536Z","caller":"traceutil/trace.go:171","msg":"trace[970173191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5436; }","duration":"122.677339ms","start":"2026-05-21T15:38:05.552674Z","end":"2026-05-21T15:38:05.675351Z","steps":["trace[970173191] 'agreement among raft nodes before linearized reading' (duration: 122.630897ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:38:05.885715Z","caller":"traceutil/trace.go:171","msg":"trace[870315650] linearizableReadLoop","detail":"{readStateIndex:5640; appliedIndex:5639; }","duration":"181.303239ms","start":"2026-05-21T15:38:05.704384Z","end":"2026-05-21T15:38:05.885687Z","steps":["trace[870315650] 'read index received' (duration: 41.588554ms)","trace[870315650] 'applied index is now lower than readState.Index' (duration: 139.713585ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:38:05.885823Z","caller":"traceutil/trace.go:171","msg":"trace[1194895494] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"203.997309ms","start":"2026-05-21T15:38:05.681787Z","end":"2026-05-21T15:38:05.885784Z","steps":["trace[1194895494] 'process raft request' (duration: 143.104683ms)","trace[1194895494] 'compare' (duration: 60.668682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:38:05.885922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.514153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:38:05.885991Z","caller":"traceutil/trace.go:171","msg":"trace[92053004] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:5438; }","duration":"181.608444ms","start":"2026-05-21T15:38:05.704368Z","end":"2026-05-21T15:38:05.885977Z","steps":["trace[92053004] 'agreement among raft nodes before linearized reading' (duration: 181.418961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:06.203659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.718912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T15:38:06.20374Z","caller":"traceutil/trace.go:171","msg":"trace[147350457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5438; }","duration":"134.851074ms","start":"2026-05-21T15:38:06.068869Z","end":"2026-05-21T15:38:06.20372Z","steps":["trace[147350457] 'range keys from in-memory index tree' (duration: 134.556518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:06.20379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.88625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T15:38:06.203886Z","caller":"traceutil/trace.go:171","msg":"trace[891226474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5438; }","duration":"154.048593ms","start":"2026-05-21T15:38:06.049814Z","end":"2026-05-21T15:38:06.203863Z","steps":["trace[891226474] 'range keys from in-memory index tree' (duration: 153.647206ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:38:09.228796Z","caller":"traceutil/trace.go:171","msg":"trace[1265079570] transaction","detail":"{read_only:false; response_revision:5458; number_of_response:1; }","duration":"186.061017ms","start":"2026-05-21T15:38:09.04271Z","end":"2026-05-21T15:38:09.228771Z","steps":["trace[1265079570] 'process raft request' (duration: 185.886584ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:38:09.436178Z","caller":"traceutil/trace.go:171","msg":"trace[868160382] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"164.358327ms","start":"2026-05-21T15:38:09.271785Z","end":"2026-05-21T15:38:09.436143Z","steps":["trace[868160382] 'process raft request' (duration: 67.852073ms)","trace[868160382] 'compare' (duration: 96.171027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:38:10.222938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:09.733625Z","time spent":"489.126911ms","remote":"127.0.0.1:37542","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T15:38:10.223795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.909068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:38:10.224019Z","caller":"traceutil/trace.go:171","msg":"trace[710116798] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"535.296675ms","start":"2026-05-21T15:38:09.688705Z","end":"2026-05-21T15:38:10.224002Z","steps":["trace[710116798] 'process raft request' (duration: 535.172553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:10.224095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:09.688584Z","time spent":"535.47642ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:38:10.224102Z","caller":"traceutil/trace.go:171","msg":"trace[1963954527] linearizableReadLoop","detail":"{readStateIndex:5666; appliedIndex:5663; }","duration":"472.189919ms","start":"2026-05-21T15:38:09.751866Z","end":"2026-05-21T15:38:10.224056Z","steps":["trace[1963954527] 'read index received' (duration: 118.577021ms)","trace[1963954527] 'applied index is now lower than readState.Index' (duration: 353.611558ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:38:10.22425Z","caller":"traceutil/trace.go:171","msg":"trace[966210630] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"532.092282ms","start":"2026-05-21T15:38:09.692147Z","end":"2026-05-21T15:38:10.224239Z","steps":["trace[966210630] 'process raft request' (duration: 531.818886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:10.224361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:09.692122Z","time spent":"532.160532ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T15:38:10.224313Z","caller":"traceutil/trace.go:171","msg":"trace[1148893225] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"736.446838ms","start":"2026-05-21T15:38:09.487832Z","end":"2026-05-21T15:38:10.224279Z","steps":["trace[1148893225] 'process raft request' (duration: 80.980019ms)","trace[1148893225] 'compare' (duration: 653.841166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:38:10.224585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.063788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T15:38:10.224701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.86328ms","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-05-21T15:38:10.224704Z","caller":"traceutil/trace.go:171","msg":"trace[1061970986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5462; }","duration":"439.266761ms","start":"2026-05-21T15:38:09.78541Z","end":"2026-05-21T15:38:10.224677Z","steps":["trace[1061970986] 'agreement among raft nodes before linearized reading' (duration: 438.954815ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:38:10.224751Z","caller":"traceutil/trace.go:171","msg":"trace[862208421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5462; }","duration":"385.944231ms","start":"2026-05-21T15:38:09.838795Z","end":"2026-05-21T15:38:10.224739Z","steps":["trace[862208421] 'agreement among raft nodes before linearized reading' (duration: 385.797578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:10.224775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:09.785389Z","time spent":"439.375964ms","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T15:38:10.224788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:09.838777Z","time spent":"386.003874ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T15:38:10.224841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.976124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T15:38:10.224891Z","caller":"traceutil/trace.go:171","msg":"trace[968667355] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:5462; }","duration":"473.053487ms","start":"2026-05-21T15:38:09.751826Z","end":"2026-05-21T15:38:10.22488Z","steps":["trace[968667355] 'agreement among raft nodes before linearized reading' (duration: 472.919593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:10.224923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:09.751813Z","time spent":"473.101637ms","remote":"127.0.0.1:43678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":61,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-21T15:38:10.225054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:09.487805Z","time spent":"736.930759ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:38:10.22502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.583729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T15:38:10.225475Z","caller":"traceutil/trace.go:171","msg":"trace[1975180813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5462; }","duration":"321.0922ms","start":"2026-05-21T15:38:09.904359Z","end":"2026-05-21T15:38:10.225452Z","steps":["trace[1975180813] 'agreement among raft nodes before linearized reading' (duration: 320.040038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:10.225634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:09.904335Z","time spent":"321.180211ms","remote":"127.0.0.1:43846","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":"info","ts":"2026-05-21T15:38:10.41756Z","caller":"traceutil/trace.go:171","msg":"trace[362205410] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"185.540144ms","start":"2026-05-21T15:38:10.23199Z","end":"2026-05-21T15:38:10.41753Z","steps":["trace[362205410] 'process raft request' (duration: 106.243189ms)","trace[362205410] 'compare' (duration: 78.963168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:38:10.905601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.320337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T15:38:10.905896Z","caller":"traceutil/trace.go:171","msg":"trace[1236724701] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"483.966765ms","start":"2026-05-21T15:38:10.421892Z","end":"2026-05-21T15:38:10.905858Z","steps":["trace[1236724701] 'process raft request' (duration: 230.309981ms)","trace[1236724701] 'compare' (duration: 253.204105ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:38:10.905708Z","caller":"traceutil/trace.go:171","msg":"trace[630918043] linearizableReadLoop","detail":"{readStateIndex:5670; appliedIndex:5669; }","duration":"350.291408ms","start":"2026-05-21T15:38:10.555395Z","end":"2026-05-21T15:38:10.905686Z","steps":["trace[630918043] 'read index received' (duration: 96.676465ms)","trace[630918043] 'applied index is now lower than readState.Index' (duration: 253.613433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T15:38:10.906074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:10.421866Z","time spent":"484.115567ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T15:38:10.906082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.678296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:38:10.906214Z","caller":"traceutil/trace.go:171","msg":"trace[1865126446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5466; }","duration":"350.82515ms","start":"2026-05-21T15:38:10.555343Z","end":"2026-05-21T15:38:10.906168Z","steps":["trace[1865126446] 'agreement among raft nodes before linearized reading' (duration: 350.688246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:10.906265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T15:38:10.555319Z","time spent":"350.935892ms","remote":"127.0.0.1:43592","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-05-21T15:38:11.481368Z","caller":"traceutil/trace.go:171","msg":"trace[1090240023] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"246.282901ms","start":"2026-05-21T15:38:11.235054Z","end":"2026-05-21T15:38:11.481337Z","steps":["trace[1090240023] 'process raft request' (duration: 246.066516ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:38:11.743533Z","caller":"traceutil/trace.go:171","msg":"trace[1290062917] linearizableReadLoop","detail":"{readStateIndex:5672; appliedIndex:5671; }","duration":"255.343235ms","start":"2026-05-21T15:38:11.488163Z","end":"2026-05-21T15:38:11.743506Z","steps":["trace[1290062917] 'read index received' (duration: 255.151721ms)","trace[1290062917] 'applied index is now lower than readState.Index' (duration: 190.384µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:38:11.743587Z","caller":"traceutil/trace.go:171","msg":"trace[587541415] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"256.343765ms","start":"2026-05-21T15:38:11.487214Z","end":"2026-05-21T15:38:11.743558Z","steps":["trace[587541415] 'process raft request' (duration: 256.1148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:11.743846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.070926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T15:38:11.743925Z","caller":"traceutil/trace.go:171","msg":"trace[658766129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5468; }","duration":"192.208409ms","start":"2026-05-21T15:38:11.551704Z","end":"2026-05-21T15:38:11.743913Z","steps":["trace[658766129] 'agreement among raft nodes before linearized reading' (duration: 192.086126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:11.743821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.631581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12046"} {"level":"info","ts":"2026-05-21T15:38:11.744132Z","caller":"traceutil/trace.go:171","msg":"trace[1545793988] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5468; }","duration":"255.931707ms","start":"2026-05-21T15:38:11.488158Z","end":"2026-05-21T15:38:11.74409Z","steps":["trace[1545793988] 'agreement among raft nodes before linearized reading' (duration: 255.476908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T15:38:11.930062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.333032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-21T15:38:11.930158Z","caller":"traceutil/trace.go:171","msg":"trace[1143994853] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5468; }","duration":"116.462926ms","start":"2026-05-21T15:38:11.813676Z","end":"2026-05-21T15:38:11.930139Z","steps":["trace[1143994853] 'range keys from in-memory index tree' (duration: 116.16895ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:38:15.007147Z","caller":"traceutil/trace.go:171","msg":"trace[933292080] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"131.655609ms","start":"2026-05-21T15:38:14.875461Z","end":"2026-05-21T15:38:15.007117Z","steps":["trace[933292080] 'process raft request' (duration: 88.168752ms)","trace[933292080] 'compare' (duration: 43.053308ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:38:15.257138Z","caller":"traceutil/trace.go:171","msg":"trace[921465321] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"139.414225ms","start":"2026-05-21T15:38:15.117697Z","end":"2026-05-21T15:38:15.257112Z","steps":["trace[921465321] 'process raft request' (duration: 139.223441ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:38:15.576635Z","caller":"traceutil/trace.go:171","msg":"trace[819041374] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"130.026705ms","start":"2026-05-21T15:38:15.446582Z","end":"2026-05-21T15:38:15.576609Z","steps":["trace[819041374] 'process raft request' (duration: 129.684628ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:38:15.576812Z","caller":"traceutil/trace.go:171","msg":"trace[464417398] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"132.26446ms","start":"2026-05-21T15:38:15.444524Z","end":"2026-05-21T15:38:15.576788Z","steps":["trace[464417398] 'process raft request' (duration: 74.584545ms)","trace[464417398] 'compare' (duration: 56.840388ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:38:24.793506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2919} {"level":"info","ts":"2026-05-21T15:38:24.868458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2919,"took":"73.562039ms","hash":3050925856,"current-db-size-bytes":53301248,"current-db-size":"53 MB","current-db-size-in-use-bytes":29212672,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-21T15:38:24.868513Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3050925856,"revision":2919,"compact-revision":-1} {"level":"info","ts":"2026-05-21T15:39:49.746337Z","caller":"traceutil/trace.go:171","msg":"trace[626137287] transaction","detail":"{read_only:false; response_revision:6076; number_of_response:1; }","duration":"127.382921ms","start":"2026-05-21T15:39:49.618917Z","end":"2026-05-21T15:39:49.7463Z","steps":["trace[626137287] 'process raft request' (duration: 99.20945ms)","trace[626137287] 'compare' (duration: 27.869886ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T15:39:49.746556Z","caller":"traceutil/trace.go:171","msg":"trace[1348976643] transaction","detail":"{read_only:false; response_revision:6077; number_of_response:1; }","duration":"125.968885ms","start":"2026-05-21T15:39:49.620482Z","end":"2026-05-21T15:39:49.746451Z","steps":["trace[1348976643] 'process raft request' (duration: 125.654448ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:39:49.747436Z","caller":"traceutil/trace.go:171","msg":"trace[432461048] transaction","detail":"{read_only:false; response_revision:6079; number_of_response:1; }","duration":"124.894004ms","start":"2026-05-21T15:39:49.622523Z","end":"2026-05-21T15:39:49.747417Z","steps":["trace[432461048] 'process raft request' (duration: 124.75248ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:39:49.746669Z","caller":"traceutil/trace.go:171","msg":"trace[1551740306] transaction","detail":"{read_only:false; response_revision:6078; number_of_response:1; }","duration":"125.704011ms","start":"2026-05-21T15:39:49.62093Z","end":"2026-05-21T15:39:49.746634Z","steps":["trace[1551740306] 'process raft request' (duration: 125.322273ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:41:49.468086Z","caller":"traceutil/trace.go:171","msg":"trace[2001223607] transaction","detail":"{read_only:false; response_revision:6948; number_of_response:1; }","duration":"113.986573ms","start":"2026-05-21T15:41:49.354059Z","end":"2026-05-21T15:41:49.468045Z","steps":["trace[2001223607] 'process raft request' (duration: 112.271882ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T15:43:24.798645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5571} {"level":"info","ts":"2026-05-21T15:43:24.874155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5571,"took":"73.757939ms","hash":3947764271,"current-db-size-bytes":53301248,"current-db-size":"53 MB","current-db-size-in-use-bytes":18477056,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-21T15:43:24.87421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3947764271,"revision":5571,"compact-revision":2919}