{"level":"warn","ts":"2026-04-09T01:57:28.993359Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T01:57:28.993468Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-04-09T01:57:28.99365Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T01:57:28.993667Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-09T01:57:28.993711Z","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-04-09T01:57:28.994572Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-04-09T01:57:28.994706Z","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.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-04-09T01:57:28.999956Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.661225ms"} {"level":"info","ts":"2026-04-09T01:57:29.013486Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-04-09T01:57:29.013766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T01:57:29.013873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-04-09T01:57:29.013975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T01:57:29.014075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-04-09T01:57:29.01421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-04-09T01:57:29.020861Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T01:57:29.0221Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T01:57:29.023333Z","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-04-09T01:57:29.025748Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T01:57:29.02596Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T01:57:29.026411Z","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-04-09T01:57:29.026553Z","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-04-09T01:57:29.0267Z","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-04-09T01:57:29.027076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-04-09T01:57:29.027236Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-09T01:57:29.037233Z","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-04-09T01:57:29.03749Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-09T01:57:29.037531Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T01:57:29.03757Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T01:57:29.037526Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-09T01:57:29.815184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T01:57:29.815366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T01:57:29.815438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-04-09T01:57:29.81548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-04-09T01:57:29.815502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-09T01:57:29.815528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-04-09T01:57:29.815551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-09T01:57:29.816923Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T01:57:29.817228Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T01:57:29.817476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T01:57:29.81758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T01:57:29.817799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T01:57:29.817834Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T01:57:29.819412Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T01:57:29.819549Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T01:57:29.819584Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T01:57:29.821145Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-04-09T01:57:29.821741Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T01:57:32.308629Z","caller":"traceutil/trace.go:171","msg":"trace[1529703314] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"167.012263ms","start":"2026-04-09T01:57:32.141582Z","end":"2026-04-09T01:57:32.308594Z","steps":["trace[1529703314] 'read index received' (duration: 166.77022ms)","trace[1529703314] 'applied index is now lower than readState.Index' (duration: 240.913µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:57:32.308685Z","caller":"traceutil/trace.go:171","msg":"trace[1166218136] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"167.289387ms","start":"2026-04-09T01:57:32.141364Z","end":"2026-04-09T01:57:32.308653Z","steps":["trace[1166218136] 'process raft request' (duration: 167.074364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:57:32.308978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.351099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T01:57:32.309107Z","caller":"traceutil/trace.go:171","msg":"trace[1222483893] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"167.504972ms","start":"2026-04-09T01:57:32.141577Z","end":"2026-04-09T01:57:32.309082Z","steps":["trace[1222483893] 'agreement among raft nodes before linearized reading' (duration: 167.191766ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:32.506151Z","caller":"traceutil/trace.go:171","msg":"trace[1827341374] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"189.695798ms","start":"2026-04-09T01:57:32.316425Z","end":"2026-04-09T01:57:32.506121Z","steps":["trace[1827341374] 'read index received' (duration: 189.142049ms)","trace[1827341374] 'applied index is now lower than readState.Index' (duration: 552.899µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:57:32.506224Z","caller":"traceutil/trace.go:171","msg":"trace[1330298216] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"191.286084ms","start":"2026-04-09T01:57:32.314893Z","end":"2026-04-09T01:57:32.506179Z","steps":["trace[1330298216] 'process raft request' (duration: 190.760685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:57:32.506497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.971054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T01:57:32.506645Z","caller":"traceutil/trace.go:171","msg":"trace[662426255] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:73; }","duration":"190.216277ms","start":"2026-04-09T01:57:32.3164Z","end":"2026-04-09T01:57:32.506616Z","steps":["trace[662426255] 'agreement among raft nodes before linearized reading' (duration: 189.862671ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:57.066201Z","caller":"traceutil/trace.go:171","msg":"trace[434583216] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"100.186462ms","start":"2026-04-09T01:57:56.965992Z","end":"2026-04-09T01:57:57.066178Z","steps":["trace[434583216] 'process raft request' (duration: 99.872198ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:57:59.474664Z","caller":"traceutil/trace.go:171","msg":"trace[755062105] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"353.331706ms","start":"2026-04-09T01:57:59.121304Z","end":"2026-04-09T01:57:59.474636Z","steps":["trace[755062105] 'process raft request' (duration: 352.786279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:57:59.47598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:57:59.121275Z","time spent":"353.512007ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:57:59.482051Z","caller":"traceutil/trace.go:171","msg":"trace[696012253] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"337.195628ms","start":"2026-04-09T01:57:59.144829Z","end":"2026-04-09T01:57:59.482024Z","steps":["trace[696012253] 'process raft request' (duration: 336.948075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:57:59.482201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:57:59.144812Z","time spent":"337.31248ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:57:59.482456Z","caller":"traceutil/trace.go:171","msg":"trace[1319884074] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:535; }","duration":"290.75321ms","start":"2026-04-09T01:57:59.191681Z","end":"2026-04-09T01:57:59.482434Z","steps":["trace[1319884074] 'read index received' (duration: 282.865209ms)","trace[1319884074] 'applied index is now lower than readState.Index' (duration: 7.867721ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:57:59.482656Z","caller":"traceutil/trace.go:171","msg":"trace[121023531] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"332.723176ms","start":"2026-04-09T01:57:59.149925Z","end":"2026-04-09T01:57:59.482648Z","steps":["trace[121023531] 'process raft request' (duration: 332.045958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:57:59.482819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:57:59.149916Z","time spent":"332.834977ms","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T01:57:59.482943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.234026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T01:57:59.483048Z","caller":"traceutil/trace.go:171","msg":"trace[1609652517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"291.376197ms","start":"2026-04-09T01:57:59.191651Z","end":"2026-04-09T01:57:59.483027Z","steps":["trace[1609652517] 'agreement among raft nodes before linearized reading' (duration: 291.059043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:57:59.483374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.19273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-09T01:57:59.483405Z","caller":"traceutil/trace.go:171","msg":"trace[685609779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"256.25949ms","start":"2026-04-09T01:57:59.227138Z","end":"2026-04-09T01:57:59.483398Z","steps":["trace[685609779] 'agreement among raft nodes before linearized reading' (duration: 256.196709ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:03.341362Z","caller":"traceutil/trace.go:171","msg":"trace[1324022472] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"118.71002ms","start":"2026-04-09T01:58:03.222618Z","end":"2026-04-09T01:58:03.341328Z","steps":["trace[1324022472] 'process raft request' (duration: 116.211892ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:05.419019Z","caller":"traceutil/trace.go:171","msg":"trace[1704928596] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"176.767029ms","start":"2026-04-09T01:58:05.24222Z","end":"2026-04-09T01:58:05.418987Z","steps":["trace[1704928596] 'process raft request' (duration: 176.160382ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:05.993811Z","caller":"traceutil/trace.go:171","msg":"trace[87301904] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"102.758541ms","start":"2026-04-09T01:58:05.891027Z","end":"2026-04-09T01:58:05.993785Z","steps":["trace[87301904] 'process raft request' (duration: 102.63906ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:06.11584Z","caller":"traceutil/trace.go:171","msg":"trace[621270356] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"217.287309ms","start":"2026-04-09T01:58:05.898528Z","end":"2026-04-09T01:58:06.115816Z","steps":["trace[621270356] 'process raft request' (duration: 215.54818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:07.104802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811134729075417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T01:58:07.1853Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017738503s","expected-duration":"1s"} {"level":"info","ts":"2026-04-09T01:58:07.185855Z","caller":"traceutil/trace.go:171","msg":"trace[496446476] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.019091287s","start":"2026-04-09T01:58:06.166739Z","end":"2026-04-09T01:58:07.18583Z","steps":["trace[496446476] 'process raft request' (duration: 1.018876364s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:07.186111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:58:06.166689Z","time spent":"1.019294219s","remote":"127.0.0.1:38104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:58:07.192142Z","caller":"traceutil/trace.go:171","msg":"trace[730352527] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"587.534153ms","start":"2026-04-09T01:58:06.604583Z","end":"2026-04-09T01:58:07.192117Z","steps":["trace[730352527] 'read index received' (duration: 580.990763ms)","trace[730352527] 'applied index is now lower than readState.Index' (duration: 6.542129ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:58:07.192266Z","caller":"traceutil/trace.go:171","msg":"trace[2044999341] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.012213483s","start":"2026-04-09T01:58:06.180016Z","end":"2026-04-09T01:58:07.19223Z","steps":["trace[2044999341] 'process raft request' (duration: 1.01199387s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:07.192328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.737945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:58:07.192402Z","caller":"traceutil/trace.go:171","msg":"trace[920293465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"587.882157ms","start":"2026-04-09T01:58:06.604501Z","end":"2026-04-09T01:58:07.192383Z","steps":["trace[920293465] 'agreement among raft nodes before linearized reading' (duration: 587.734766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:07.192423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.357864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T01:58:07.192431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:58:06.179986Z","time spent":"1.012374304s","remote":"127.0.0.1:38144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T01:58:07.192488Z","caller":"traceutil/trace.go:171","msg":"trace[887749686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"443.466086ms","start":"2026-04-09T01:58:06.749007Z","end":"2026-04-09T01:58:07.192473Z","steps":["trace[887749686] 'agreement among raft nodes before linearized reading' (duration: 443.253054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:07.192534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:58:06.748986Z","time spent":"443.535526ms","remote":"127.0.0.1:38182","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":"warn","ts":"2026-04-09T01:58:07.192438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:58:06.604477Z","time spent":"587.954417ms","remote":"127.0.0.1:37896","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-04-09T01:58:11.331383Z","caller":"traceutil/trace.go:171","msg":"trace[1938737338] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"129.525011ms","start":"2026-04-09T01:58:11.201839Z","end":"2026-04-09T01:58:11.331364Z","steps":["trace[1938737338] 'read index received' (duration: 4.873351ms)","trace[1938737338] 'applied index is now lower than readState.Index' (duration: 124.651ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:58:11.331481Z","caller":"traceutil/trace.go:171","msg":"trace[159101629] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"137.192659ms","start":"2026-04-09T01:58:11.194249Z","end":"2026-04-09T01:58:11.331441Z","steps":["trace[159101629] 'process raft request' (duration: 115.602296ms)","trace[159101629] 'compare' (duration: 21.391661ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:58:11.33175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.532826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T01:58:11.33178Z","caller":"traceutil/trace.go:171","msg":"trace[152240820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"120.636549ms","start":"2026-04-09T01:58:11.211137Z","end":"2026-04-09T01:58:11.331773Z","steps":["trace[152240820] 'agreement among raft nodes before linearized reading' (duration: 120.369296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:11.332267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.403169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-09T01:58:11.332345Z","caller":"traceutil/trace.go:171","msg":"trace[779373325] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:646; }","duration":"130.51864ms","start":"2026-04-09T01:58:11.201809Z","end":"2026-04-09T01:58:11.332328Z","steps":["trace[779373325] 'agreement among raft nodes before linearized reading' (duration: 130.261888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:23.312089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.141542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T01:58:23.312096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.339474ms","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-04-09T01:58:23.312175Z","caller":"traceutil/trace.go:171","msg":"trace[1952671256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:744; }","duration":"706.293633ms","start":"2026-04-09T01:58:22.60586Z","end":"2026-04-09T01:58:23.312154Z","steps":["trace[1952671256] 'range keys from in-memory index tree' (duration: 706.010691ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:23.312231Z","caller":"traceutil/trace.go:171","msg":"trace[976978747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:744; }","duration":"593.480205ms","start":"2026-04-09T01:58:22.718682Z","end":"2026-04-09T01:58:23.312162Z","steps":["trace[976978747] 'range keys from in-memory index tree' (duration: 593.222492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:23.312313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:58:22.718659Z","time spent":"593.614976ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T01:58:23.312387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:58:22.605826Z","time spent":"706.548367ms","remote":"127.0.0.1:37896","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-04-09T01:58:23.371859Z","caller":"traceutil/trace.go:171","msg":"trace[1900827939] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"116.451178ms","start":"2026-04-09T01:58:23.255375Z","end":"2026-04-09T01:58:23.371826Z","steps":["trace[1900827939] 'read index received' (duration: 116.371167ms)","trace[1900827939] 'applied index is now lower than readState.Index' (duration: 78.601µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:58:23.371931Z","caller":"traceutil/trace.go:171","msg":"trace[1387078733] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"119.841909ms","start":"2026-04-09T01:58:23.252046Z","end":"2026-04-09T01:58:23.371888Z","steps":["trace[1387078733] 'process raft request' (duration: 119.607026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:23.372216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.82943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-09T01:58:23.372275Z","caller":"traceutil/trace.go:171","msg":"trace[597110717] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:745; }","duration":"116.946661ms","start":"2026-04-09T01:58:23.255313Z","end":"2026-04-09T01:58:23.37226Z","steps":["trace[597110717] 'agreement among raft nodes before linearized reading' (duration: 116.673959ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:23.535702Z","caller":"traceutil/trace.go:171","msg":"trace[90639941] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"163.728545ms","start":"2026-04-09T01:58:23.371954Z","end":"2026-04-09T01:58:23.535683Z","steps":["trace[90639941] 'read index received' (duration: 152.855161ms)","trace[90639941] 'applied index is now lower than readState.Index' (duration: 10.872474ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:58:23.535735Z","caller":"traceutil/trace.go:171","msg":"trace[765812638] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"219.342815ms","start":"2026-04-09T01:58:23.316347Z","end":"2026-04-09T01:58:23.53569Z","steps":["trace[765812638] 'process raft request' (duration: 208.470241ms)","trace[765812638] 'compare' (duration: 10.737192ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:58:23.535908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.634ms","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-04-09T01:58:23.535945Z","caller":"traceutil/trace.go:171","msg":"trace[718579413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"112.721931ms","start":"2026-04-09T01:58:23.423212Z","end":"2026-04-09T01:58:23.535934Z","steps":["trace[718579413] 'agreement among raft nodes before linearized reading' (duration: 112.649111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:23.536012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.614645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:58:23.536128Z","caller":"traceutil/trace.go:171","msg":"trace[1589308713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"222.835126ms","start":"2026-04-09T01:58:23.313271Z","end":"2026-04-09T01:58:23.536106Z","steps":["trace[1589308713] 'agreement among raft nodes before linearized reading' (duration: 222.526914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:38.417197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.726025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-2vnbf\" ","response":"range_response_count:1 size:5065"} {"level":"info","ts":"2026-04-09T01:58:38.417278Z","caller":"traceutil/trace.go:171","msg":"trace[951781220] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-2vnbf; range_end:; response_count:1; response_revision:921; }","duration":"110.851497ms","start":"2026-04-09T01:58:38.306412Z","end":"2026-04-09T01:58:38.417263Z","steps":["trace[951781220] 'range keys from in-memory index tree' (duration: 110.546213ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:38.550004Z","caller":"traceutil/trace.go:171","msg":"trace[1804030502] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"129.44977ms","start":"2026-04-09T01:58:38.420532Z","end":"2026-04-09T01:58:38.549982Z","steps":["trace[1804030502] 'read index received' (duration: 129.363429ms)","trace[1804030502] 'applied index is now lower than readState.Index' (duration: 85.321µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:58:38.550166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.621201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-2vnbf\" ","response":"range_response_count:1 size:5065"} {"level":"info","ts":"2026-04-09T01:58:38.550196Z","caller":"traceutil/trace.go:171","msg":"trace[318765543] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-2vnbf; range_end:; response_count:1; response_revision:922; }","duration":"129.701723ms","start":"2026-04-09T01:58:38.420487Z","end":"2026-04-09T01:58:38.550189Z","steps":["trace[318765543] 'agreement among raft nodes before linearized reading' (duration: 129.572942ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:38.550324Z","caller":"traceutil/trace.go:171","msg":"trace[1211237504] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"201.651856ms","start":"2026-04-09T01:58:38.348653Z","end":"2026-04-09T01:58:38.550305Z","steps":["trace[1211237504] 'process raft request' (duration: 201.194812ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:38.707081Z","caller":"traceutil/trace.go:171","msg":"trace[1083351365] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"150.497204ms","start":"2026-04-09T01:58:38.55655Z","end":"2026-04-09T01:58:38.707047Z","steps":["trace[1083351365] 'process raft request' (duration: 150.240202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:41.341609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.214937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-75c4c745bc-7xb76\" ","response":"range_response_count:1 size:3544"} {"level":"info","ts":"2026-04-09T01:58:41.34167Z","caller":"traceutil/trace.go:171","msg":"trace[1965757298] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-75c4c745bc-7xb76; range_end:; response_count:1; response_revision:1024; }","duration":"105.292867ms","start":"2026-04-09T01:58:41.236363Z","end":"2026-04-09T01:58:41.341656Z","steps":["trace[1965757298] 'agreement among raft nodes before linearized reading' (duration: 105.147086ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:41.354745Z","caller":"traceutil/trace.go:171","msg":"trace[1987669502] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"105.056544ms","start":"2026-04-09T01:58:41.249675Z","end":"2026-04-09T01:58:41.354732Z","steps":["trace[1987669502] 'process raft request' (duration: 104.851933ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:41.354793Z","caller":"traceutil/trace.go:171","msg":"trace[681845989] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"104.956394ms","start":"2026-04-09T01:58:41.249809Z","end":"2026-04-09T01:58:41.354765Z","steps":["trace[681845989] 'process raft request' (duration: 104.830862ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:41.354922Z","caller":"traceutil/trace.go:171","msg":"trace[60326122] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"102.928246ms","start":"2026-04-09T01:58:41.251934Z","end":"2026-04-09T01:58:41.354863Z","steps":["trace[60326122] 'process raft request' (duration: 102.752924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:41.355782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.027915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:3516"} {"level":"info","ts":"2026-04-09T01:58:41.356247Z","caller":"traceutil/trace.go:171","msg":"trace[1671419327] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:1029; }","duration":"112.511749ms","start":"2026-04-09T01:58:41.243715Z","end":"2026-04-09T01:58:41.356227Z","steps":["trace[1671419327] 'agreement among raft nodes before linearized reading' (duration: 111.906364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:41.356155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.304617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4640"} {"level":"info","ts":"2026-04-09T01:58:41.35653Z","caller":"traceutil/trace.go:171","msg":"trace[1908239899] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1029; }","duration":"112.781001ms","start":"2026-04-09T01:58:41.243738Z","end":"2026-04-09T01:58:41.356519Z","steps":["trace[1908239899] 'agreement among raft nodes before linearized reading' (duration: 112.210726ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:42.506286Z","caller":"traceutil/trace.go:171","msg":"trace[1231341966] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"124.890762ms","start":"2026-04-09T01:58:42.381372Z","end":"2026-04-09T01:58:42.506262Z","steps":["trace[1231341966] 'process raft request' (duration: 124.764131ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:47.821944Z","caller":"traceutil/trace.go:171","msg":"trace[911971789] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"148.473455ms","start":"2026-04-09T01:58:47.673447Z","end":"2026-04-09T01:58:47.82192Z","steps":["trace[911971789] 'read index received' (duration: 148.233124ms)","trace[911971789] 'applied index is now lower than readState.Index' (duration: 239.321µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:58:47.822011Z","caller":"traceutil/trace.go:171","msg":"trace[1025452410] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"160.79133ms","start":"2026-04-09T01:58:47.66118Z","end":"2026-04-09T01:58:47.821972Z","steps":["trace[1025452410] 'process raft request' (duration: 160.550558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:47.822199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.727568ms","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-04-09T01:58:47.822384Z","caller":"traceutil/trace.go:171","msg":"trace[1427656351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1116; }","duration":"148.95576ms","start":"2026-04-09T01:58:47.673412Z","end":"2026-04-09T01:58:47.822368Z","steps":["trace[1427656351] 'agreement among raft nodes before linearized reading' (duration: 148.656297ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:47.961589Z","caller":"traceutil/trace.go:171","msg":"trace[1911027995] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"134.317945ms","start":"2026-04-09T01:58:47.827246Z","end":"2026-04-09T01:58:47.961564Z","steps":["trace[1911027995] 'process raft request' (duration: 134.255774ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:47.961808Z","caller":"traceutil/trace.go:171","msg":"trace[182358657] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"134.80937ms","start":"2026-04-09T01:58:47.826988Z","end":"2026-04-09T01:58:47.961798Z","steps":["trace[182358657] 'process raft request' (duration: 134.314375ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:47.961599Z","caller":"traceutil/trace.go:171","msg":"trace[503200350] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"135.240863ms","start":"2026-04-09T01:58:47.826327Z","end":"2026-04-09T01:58:47.961568Z","steps":["trace[503200350] 'process raft request' (duration: 53.557026ms)","trace[503200350] 'compare' (duration: 81.134092ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:58:49.497169Z","caller":"traceutil/trace.go:171","msg":"trace[54540879] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"111.881804ms","start":"2026-04-09T01:58:49.385263Z","end":"2026-04-09T01:58:49.497145Z","steps":["trace[54540879] 'process raft request' (duration: 111.662822ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:58:54.279502Z","caller":"traceutil/trace.go:171","msg":"trace[1251114311] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"250.293126ms","start":"2026-04-09T01:58:54.029188Z","end":"2026-04-09T01:58:54.279481Z","steps":["trace[1251114311] 'read index received' (duration: 250.056807ms)","trace[1251114311] 'applied index is now lower than readState.Index' (duration: 235.769µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:58:54.279734Z","caller":"traceutil/trace.go:171","msg":"trace[1315635181] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"408.561783ms","start":"2026-04-09T01:58:53.871131Z","end":"2026-04-09T01:58:54.279692Z","steps":["trace[1315635181] 'process raft request' (duration: 408.223479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:54.279907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.573203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-09T01:58:54.279939Z","caller":"traceutil/trace.go:171","msg":"trace[615812840] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1146; }","duration":"229.643496ms","start":"2026-04-09T01:58:54.050285Z","end":"2026-04-09T01:58:54.279928Z","steps":["trace[615812840] 'agreement among raft nodes before linearized reading' (duration: 229.592874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:54.27983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.631039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T01:58:54.28008Z","caller":"traceutil/trace.go:171","msg":"trace[371831728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1146; }","duration":"250.930963ms","start":"2026-04-09T01:58:54.02914Z","end":"2026-04-09T01:58:54.280071Z","steps":["trace[371831728] 'agreement among raft nodes before linearized reading' (duration: 250.560957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:58:54.280198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:58:53.871111Z","time spent":"408.794283ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:58:54.280291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.03338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-09T01:58:54.280439Z","caller":"traceutil/trace.go:171","msg":"trace[1180162704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1146; }","duration":"229.214377ms","start":"2026-04-09T01:58:54.051167Z","end":"2026-04-09T01:58:54.280381Z","steps":["trace[1180162704] 'agreement among raft nodes before linearized reading' (duration: 228.78616ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:06.493336Z","caller":"traceutil/trace.go:171","msg":"trace[1043086302] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"120.842306ms","start":"2026-04-09T01:59:06.372469Z","end":"2026-04-09T01:59:06.493311Z","steps":["trace[1043086302] 'process raft request' (duration: 120.793474ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:06.49355Z","caller":"traceutil/trace.go:171","msg":"trace[152314565] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"121.140556ms","start":"2026-04-09T01:59:06.372396Z","end":"2026-04-09T01:59:06.493537Z","steps":["trace[152314565] 'process raft request' (duration: 116.148244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:14.458239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.937906ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:59:14.45836Z","caller":"traceutil/trace.go:171","msg":"trace[1260038468] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1391; }","duration":"133.068341ms","start":"2026-04-09T01:59:14.325276Z","end":"2026-04-09T01:59:14.458344Z","steps":["trace[1260038468] 'range keys from in-memory index tree' (duration: 132.925886ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:16.930446Z","caller":"traceutil/trace.go:171","msg":"trace[250106544] linearizableReadLoop","detail":"{readStateIndex:1449; appliedIndex:1448; }","duration":"377.958824ms","start":"2026-04-09T01:59:16.552463Z","end":"2026-04-09T01:59:16.930422Z","steps":["trace[250106544] 'read index received' (duration: 377.881241ms)","trace[250106544] 'applied index is now lower than readState.Index' (duration: 76.823µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:59:16.930535Z","caller":"traceutil/trace.go:171","msg":"trace[955232596] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"380.545908ms","start":"2026-04-09T01:59:16.549956Z","end":"2026-04-09T01:59:16.930502Z","steps":["trace[955232596] 'process raft request' (duration: 380.310611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:16.930659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.18384ms","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-04-09T01:59:16.930707Z","caller":"traceutil/trace.go:171","msg":"trace[1303272353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1406; }","duration":"378.273923ms","start":"2026-04-09T01:59:16.55242Z","end":"2026-04-09T01:59:16.930694Z","steps":["trace[1303272353] 'agreement among raft nodes before linearized reading' (duration: 378.121628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:16.930718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:16.549926Z","time spent":"380.696403ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T01:59:16.930747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:16.552407Z","time spent":"378.330914ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T01:59:17.026927Z","caller":"traceutil/trace.go:171","msg":"trace[1919785028] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"302.042572ms","start":"2026-04-09T01:59:16.72484Z","end":"2026-04-09T01:59:17.026882Z","steps":["trace[1919785028] 'process raft request' (duration: 248.024425ms)","trace[1919785028] 'compare' (duration: 53.82734ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:59:17.027104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.482058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-09T01:59:17.027127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.352853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:59:17.02718Z","caller":"traceutil/trace.go:171","msg":"trace[2052721169] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1408; }","duration":"461.582402ms","start":"2026-04-09T01:59:16.565582Z","end":"2026-04-09T01:59:17.027164Z","steps":["trace[2052721169] 'agreement among raft nodes before linearized reading' (duration: 461.301553ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:17.027199Z","caller":"traceutil/trace.go:171","msg":"trace[793227750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1408; }","duration":"422.462716ms","start":"2026-04-09T01:59:16.604718Z","end":"2026-04-09T01:59:17.02718Z","steps":["trace[793227750] 'agreement among raft nodes before linearized reading' (duration: 422.174157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:17.027212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:16.565575Z","time spent":"461.631213ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-09T01:59:17.027245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:16.604695Z","time spent":"422.539229ms","remote":"127.0.0.1:37896","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-04-09T01:59:17.027742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:16.724819Z","time spent":"302.539068ms","remote":"127.0.0.1:38182","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-04-09T01:59:23.700635Z","caller":"traceutil/trace.go:171","msg":"trace[857996001] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1510; }","duration":"103.698086ms","start":"2026-04-09T01:59:23.596915Z","end":"2026-04-09T01:59:23.700613Z","steps":["trace[857996001] 'process raft request' (duration: 103.585822ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:25.23597Z","caller":"traceutil/trace.go:171","msg":"trace[295182528] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"236.287469ms","start":"2026-04-09T01:59:24.999654Z","end":"2026-04-09T01:59:25.235941Z","steps":["trace[295182528] 'process raft request' (duration: 236.131665ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:25.246238Z","caller":"traceutil/trace.go:171","msg":"trace[544905616] linearizableReadLoop","detail":"{readStateIndex:1574; appliedIndex:1572; }","duration":"187.361949ms","start":"2026-04-09T01:59:25.058852Z","end":"2026-04-09T01:59:25.246214Z","steps":["trace[544905616] 'read index received' (duration: 176.943976ms)","trace[544905616] 'applied index is now lower than readState.Index' (duration: 10.416503ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:59:25.246478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.603576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-09T01:59:25.246516Z","caller":"traceutil/trace.go:171","msg":"trace[751670692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1515; }","duration":"187.694319ms","start":"2026-04-09T01:59:25.05881Z","end":"2026-04-09T01:59:25.246504Z","steps":["trace[751670692] 'agreement among raft nodes before linearized reading' (duration: 187.507353ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:25.246826Z","caller":"traceutil/trace.go:171","msg":"trace[954685702] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"196.162404ms","start":"2026-04-09T01:59:25.050652Z","end":"2026-04-09T01:59:25.246815Z","steps":["trace[954685702] 'process raft request' (duration: 195.448973ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:25.362896Z","caller":"traceutil/trace.go:171","msg":"trace[1027637933] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"112.236141ms","start":"2026-04-09T01:59:25.250636Z","end":"2026-04-09T01:59:25.362872Z","steps":["trace[1027637933] 'process raft request' (duration: 78.90186ms)","trace[1027637933] 'compare' (duration: 33.231718ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:59:26.518381Z","caller":"traceutil/trace.go:171","msg":"trace[1915868117] linearizableReadLoop","detail":"{readStateIndex:1643; appliedIndex:1641; }","duration":"104.295443ms","start":"2026-04-09T01:59:26.414063Z","end":"2026-04-09T01:59:26.518358Z","steps":["trace[1915868117] 'read index received' (duration: 102.999185ms)","trace[1915868117] 'applied index is now lower than readState.Index' (duration: 1.295318ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:59:26.518542Z","caller":"traceutil/trace.go:171","msg":"trace[807196651] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"104.53915ms","start":"2026-04-09T01:59:26.413984Z","end":"2026-04-09T01:59:26.518523Z","steps":["trace[807196651] 'process raft request' (duration: 104.280683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:26.518732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.647293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1080"} {"level":"info","ts":"2026-04-09T01:59:26.519529Z","caller":"traceutil/trace.go:171","msg":"trace[2003573863] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"122.214136ms","start":"2026-04-09T01:59:26.395058Z","end":"2026-04-09T01:59:26.517272Z","steps":["trace[2003573863] 'process raft request' (duration: 121.955888ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:26.51955Z","caller":"traceutil/trace.go:171","msg":"trace[1521889622] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:1583; }","duration":"105.49771ms","start":"2026-04-09T01:59:26.414035Z","end":"2026-04-09T01:59:26.519533Z","steps":["trace[1521889622] 'agreement among raft nodes before linearized reading' (duration: 104.550021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:26.519123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.972514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1113"} {"level":"info","ts":"2026-04-09T01:59:26.523135Z","caller":"traceutil/trace.go:171","msg":"trace[1709037508] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:1583; }","duration":"109.042734ms","start":"2026-04-09T01:59:26.414081Z","end":"2026-04-09T01:59:26.523123Z","steps":["trace[1709037508] 'agreement among raft nodes before linearized reading' (duration: 104.778518ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:31.362272Z","caller":"traceutil/trace.go:171","msg":"trace[1395308429] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"103.322174ms","start":"2026-04-09T01:59:31.258936Z","end":"2026-04-09T01:59:31.362258Z","steps":["trace[1395308429] 'process raft request' (duration: 103.219741ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:31.366764Z","caller":"traceutil/trace.go:171","msg":"trace[110631382] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"101.299645ms","start":"2026-04-09T01:59:31.265457Z","end":"2026-04-09T01:59:31.366756Z","steps":["trace[110631382] 'process raft request' (duration: 101.10799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.174246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811134729077868,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T01:59:41.291385Z","caller":"traceutil/trace.go:171","msg":"trace[1122915732] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"686.893293ms","start":"2026-04-09T01:59:40.604468Z","end":"2026-04-09T01:59:41.291361Z","steps":["trace[1122915732] 'process raft request' (duration: 686.714259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.291524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.604457Z","time spent":"686.997056ms","remote":"127.0.0.1:38104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:59:41.441454Z","caller":"traceutil/trace.go:171","msg":"trace[857971553] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"803.329248ms","start":"2026-04-09T01:59:40.638095Z","end":"2026-04-09T01:59:41.441424Z","steps":["trace[857971553] 'process raft request' (duration: 750.140508ms)","trace[857971553] 'compare' (duration: 53.032615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:59:41.441826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.638067Z","time spent":"803.473132ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T01:59:41.441816Z","caller":"traceutil/trace.go:171","msg":"trace[2047472781] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"741.468638ms","start":"2026-04-09T01:59:40.700294Z","end":"2026-04-09T01:59:41.441763Z","steps":["trace[2047472781] 'process raft request' (duration: 741.091199ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:41.44185Z","caller":"traceutil/trace.go:171","msg":"trace[1311014971] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1870; }","duration":"768.445783ms","start":"2026-04-09T01:59:40.673379Z","end":"2026-04-09T01:59:41.441825Z","steps":["trace[1311014971] 'read index received' (duration: 617.803835ms)","trace[1311014971] 'applied index is now lower than readState.Index' (duration: 150.639968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:59:41.442054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.700266Z","time spent":"741.673262ms","remote":"127.0.0.1:38182","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-04-09T01:59:41.442298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.191949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:59:41.442351Z","caller":"traceutil/trace.go:171","msg":"trace[1619750798] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1804; }","duration":"684.27274ms","start":"2026-04-09T01:59:40.758065Z","end":"2026-04-09T01:59:41.442338Z","steps":["trace[1619750798] 'agreement among raft nodes before linearized reading' (duration: 684.193328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.442391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.758037Z","time spent":"684.345012ms","remote":"127.0.0.1:38302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-09T01:59:41.442411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.442147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:59:41.442462Z","caller":"traceutil/trace.go:171","msg":"trace[727360125] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1804; }","duration":"524.517529ms","start":"2026-04-09T01:59:40.917931Z","end":"2026-04-09T01:59:41.442449Z","steps":["trace[727360125] 'agreement among raft nodes before linearized reading' (duration: 524.420896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.442504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.917918Z","time spent":"524.576331ms","remote":"127.0.0.1:35978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T01:59:41.44265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.99967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-09T01:59:41.442677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.760976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6fb41658-84a7-4a6e-90d9-4a2534bfe3f0\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-04-09T01:59:41.442703Z","caller":"traceutil/trace.go:171","msg":"trace[1015429250] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1804; }","duration":"546.069804ms","start":"2026-04-09T01:59:40.89662Z","end":"2026-04-09T01:59:41.44269Z","steps":["trace[1015429250] 'agreement among raft nodes before linearized reading' (duration: 545.90772ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:41.442727Z","caller":"traceutil/trace.go:171","msg":"trace[1830366] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6fb41658-84a7-4a6e-90d9-4a2534bfe3f0; range_end:; response_count:1; response_revision:1804; }","duration":"240.84789ms","start":"2026-04-09T01:59:41.201863Z","end":"2026-04-09T01:59:41.442711Z","steps":["trace[1830366] 'agreement among raft nodes before linearized reading' (duration: 240.705796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.442737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.896607Z","time spent":"546.121206ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-09T01:59:41.442989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.646664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-09T01:59:41.44296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.5721ms","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-04-09T01:59:41.443048Z","caller":"traceutil/trace.go:171","msg":"trace[504126694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1804; }","duration":"644.728935ms","start":"2026-04-09T01:59:40.798308Z","end":"2026-04-09T01:59:41.443037Z","steps":["trace[504126694] 'agreement among raft nodes before linearized reading' (duration: 644.549651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.443092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.798297Z","time spent":"644.787817ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T01:59:41.443083Z","caller":"traceutil/trace.go:171","msg":"trace[426779870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1804; }","duration":"647.750865ms","start":"2026-04-09T01:59:40.795306Z","end":"2026-04-09T01:59:41.443057Z","steps":["trace[426779870] 'agreement among raft nodes before linearized reading' (duration: 647.539329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.443136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.795252Z","time spent":"647.873167ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T01:59:41.444479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.044291ms","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-04-09T01:59:41.444556Z","caller":"traceutil/trace.go:171","msg":"trace[1867966380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1804; }","duration":"771.190895ms","start":"2026-04-09T01:59:40.673348Z","end":"2026-04-09T01:59:41.444539Z","steps":["trace[1867966380] 'agreement among raft nodes before linearized reading' (duration: 768.773443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.445296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:40.673328Z","time spent":"771.260116ms","remote":"127.0.0.1:38182","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":"warn","ts":"2026-04-09T01:59:41.861935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.565536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T01:59:41.862191Z","caller":"traceutil/trace.go:171","msg":"trace[1076550159] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1878; }","duration":"246.299431ms","start":"2026-04-09T01:59:41.615786Z","end":"2026-04-09T01:59:41.862086Z","steps":["trace[1076550159] 'read index received' (duration: 46.371µs)","trace[1076550159] 'applied index is now lower than readState.Index' (duration: 246.2514ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:59:41.862289Z","caller":"traceutil/trace.go:171","msg":"trace[405354229] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"357.966919ms","start":"2026-04-09T01:59:41.504274Z","end":"2026-04-09T01:59:41.862241Z","steps":["trace[405354229] 'process raft request' (duration: 85.741095ms)","trace[405354229] 'compare' (duration: 271.186116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T01:59:41.862358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.275593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T01:59:41.862392Z","caller":"traceutil/trace.go:171","msg":"trace[1886837455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1809; }","duration":"243.308714ms","start":"2026-04-09T01:59:41.619072Z","end":"2026-04-09T01:59:41.86238Z","steps":["trace[1886837455] 'agreement among raft nodes before linearized reading' (duration: 243.240592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.86249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:41.504256Z","time spent":"358.124283ms","remote":"127.0.0.1:38182","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-04-09T01:59:41.862499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.69174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-09T01:59:41.862597Z","caller":"traceutil/trace.go:171","msg":"trace[1387987259] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1809; }","duration":"246.837025ms","start":"2026-04-09T01:59:41.615725Z","end":"2026-04-09T01:59:41.862562Z","steps":["trace[1387987259] 'agreement among raft nodes before linearized reading' (duration: 246.579608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:41.862698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.418983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-09T01:59:41.8629Z","caller":"traceutil/trace.go:171","msg":"trace[1181833136] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1809; }","duration":"103.706161ms","start":"2026-04-09T01:59:41.759164Z","end":"2026-04-09T01:59:41.86287Z","steps":["trace[1181833136] 'agreement among raft nodes before linearized reading' (duration: 103.185787ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:47.176277Z","caller":"traceutil/trace.go:171","msg":"trace[1859895476] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"108.866004ms","start":"2026-04-09T01:59:47.067382Z","end":"2026-04-09T01:59:47.176248Z","steps":["trace[1859895476] 'process raft request' (duration: 89.656701ms)","trace[1859895476] 'compare' (duration: 19.003108ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:59:55.727362Z","caller":"traceutil/trace.go:171","msg":"trace[797731695] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"128.264966ms","start":"2026-04-09T01:59:55.599062Z","end":"2026-04-09T01:59:55.727327Z","steps":["trace[797731695] 'process raft request' (duration: 127.962429ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:55.727556Z","caller":"traceutil/trace.go:171","msg":"trace[429237036] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"126.859904ms","start":"2026-04-09T01:59:55.600671Z","end":"2026-04-09T01:59:55.727531Z","steps":["trace[429237036] 'process raft request' (duration: 126.813123ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:55.727769Z","caller":"traceutil/trace.go:171","msg":"trace[415012955] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"127.391205ms","start":"2026-04-09T01:59:55.600342Z","end":"2026-04-09T01:59:55.727733Z","steps":["trace[415012955] 'process raft request' (duration: 126.837922ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T01:59:56.263541Z","caller":"traceutil/trace.go:171","msg":"trace[69207007] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1999; }","duration":"272.81094ms","start":"2026-04-09T01:59:55.990699Z","end":"2026-04-09T01:59:56.26351Z","steps":["trace[69207007] 'read index received' (duration: 272.551644ms)","trace[69207007] 'applied index is now lower than readState.Index' (duration: 257.816µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T01:59:56.263646Z","caller":"traceutil/trace.go:171","msg":"trace[1397224945] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"337.630209ms","start":"2026-04-09T01:59:55.92599Z","end":"2026-04-09T01:59:56.26362Z","steps":["trace[1397224945] 'process raft request' (duration: 337.18837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T01:59:56.263772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T01:59:55.92598Z","time spent":"337.718972ms","remote":"127.0.0.1:38182","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-04-09T01:59:56.26378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.049866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-09T01:59:56.263826Z","caller":"traceutil/trace.go:171","msg":"trace[54423714] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1927; }","duration":"273.151859ms","start":"2026-04-09T01:59:55.99066Z","end":"2026-04-09T01:59:56.263812Z","steps":["trace[54423714] 'agreement among raft nodes before linearized reading' (duration: 272.964574ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:11.919583Z","caller":"traceutil/trace.go:171","msg":"trace[1255931563] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2102; }","duration":"119.300773ms","start":"2026-04-09T02:00:11.800258Z","end":"2026-04-09T02:00:11.919558Z","steps":["trace[1255931563] 'read index received' (duration: 119.052338ms)","trace[1255931563] 'applied index is now lower than readState.Index' (duration: 247.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:00:11.919634Z","caller":"traceutil/trace.go:171","msg":"trace[781944755] transaction","detail":"{read_only:false; response_revision:2027; number_of_response:1; }","duration":"195.226435ms","start":"2026-04-09T02:00:11.724386Z","end":"2026-04-09T02:00:11.919613Z","steps":["trace[781944755] 'process raft request' (duration: 195.009701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:11.919846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.534737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-09T02:00:11.919946Z","caller":"traceutil/trace.go:171","msg":"trace[126514549] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2027; }","duration":"119.714382ms","start":"2026-04-09T02:00:11.80021Z","end":"2026-04-09T02:00:11.919924Z","steps":["trace[126514549] 'agreement among raft nodes before linearized reading' (duration: 119.480116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:11.920346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.409049ms","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-04-09T02:00:11.920467Z","caller":"traceutil/trace.go:171","msg":"trace[1234715539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2027; }","duration":"107.564811ms","start":"2026-04-09T02:00:11.812849Z","end":"2026-04-09T02:00:11.920413Z","steps":["trace[1234715539] 'agreement among raft nodes before linearized reading' (duration: 107.290756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:12.207784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.254676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:00:12.207972Z","caller":"traceutil/trace.go:171","msg":"trace[203543084] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"282.636325ms","start":"2026-04-09T02:00:11.925324Z","end":"2026-04-09T02:00:12.20796Z","steps":["trace[203543084] 'process raft request' (duration: 282.599954ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:12.208001Z","caller":"traceutil/trace.go:171","msg":"trace[2141452633] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"283.606254ms","start":"2026-04-09T02:00:11.924355Z","end":"2026-04-09T02:00:12.207961Z","steps":["trace[2141452633] 'process raft request' (duration: 116.123583ms)","trace[2141452633] 'compare' (duration: 167.179515ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:00:12.208015Z","caller":"traceutil/trace.go:171","msg":"trace[781691847] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"283.503982ms","start":"2026-04-09T02:00:11.924479Z","end":"2026-04-09T02:00:12.207984Z","steps":["trace[781691847] 'process raft request' (duration: 283.36619ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:12.208082Z","caller":"traceutil/trace.go:171","msg":"trace[1509922650] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"282.746147ms","start":"2026-04-09T02:00:11.925304Z","end":"2026-04-09T02:00:12.20805Z","steps":["trace[1509922650] 'process raft request' (duration: 282.600654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:12.721572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.162873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:00:12.721566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.467292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:00:12.72166Z","caller":"traceutil/trace.go:171","msg":"trace[699089436] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2034; }","duration":"169.620595ms","start":"2026-04-09T02:00:12.552021Z","end":"2026-04-09T02:00:12.721641Z","steps":["trace[699089436] 'count revisions from in-memory index tree' (duration: 169.37637ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:12.721979Z","caller":"traceutil/trace.go:171","msg":"trace[1675073224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2034; }","duration":"116.287626ms","start":"2026-04-09T02:00:12.605354Z","end":"2026-04-09T02:00:12.721642Z","steps":["trace[1675073224] 'range keys from in-memory index tree' (duration: 116.053831ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:13.483362Z","caller":"traceutil/trace.go:171","msg":"trace[142976852] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"132.739754ms","start":"2026-04-09T02:00:13.350579Z","end":"2026-04-09T02:00:13.483319Z","steps":["trace[142976852] 'process raft request' (duration: 125.15342ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:14.243764Z","caller":"traceutil/trace.go:171","msg":"trace[1208840238] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"514.233892ms","start":"2026-04-09T02:00:13.72949Z","end":"2026-04-09T02:00:14.243724Z","steps":["trace[1208840238] 'process raft request' (duration: 511.422786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:14.244016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:13.729464Z","time spent":"514.448226ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:00:14.244159Z","caller":"traceutil/trace.go:171","msg":"trace[1077765597] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"510.989266ms","start":"2026-04-09T02:00:13.733139Z","end":"2026-04-09T02:00:14.244128Z","steps":["trace[1077765597] 'process raft request' (duration: 510.522806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:14.244322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:13.73308Z","time spent":"511.156679ms","remote":"127.0.0.1:38182","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-04-09T02:00:14.243811Z","caller":"traceutil/trace.go:171","msg":"trace[1421490301] linearizableReadLoop","detail":"{readStateIndex:2115; appliedIndex:2112; }","duration":"308.055079ms","start":"2026-04-09T02:00:13.935734Z","end":"2026-04-09T02:00:14.243789Z","steps":["trace[1421490301] 'read index received' (duration: 305.13283ms)","trace[1421490301] 'applied index is now lower than readState.Index' (duration: 2.921399ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:00:14.244833Z","caller":"traceutil/trace.go:171","msg":"trace[1082961653] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"319.659023ms","start":"2026-04-09T02:00:13.925154Z","end":"2026-04-09T02:00:14.244813Z","steps":["trace[1082961653] 'process raft request' (duration: 318.588702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:14.244941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:13.925128Z","time spent":"319.765636ms","remote":"127.0.0.1:38182","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-04-09T02:00:14.244854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.11337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-09T02:00:14.24507Z","caller":"traceutil/trace.go:171","msg":"trace[342048124] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2038; }","duration":"309.373935ms","start":"2026-04-09T02:00:13.935682Z","end":"2026-04-09T02:00:14.245056Z","steps":["trace[342048124] 'agreement among raft nodes before linearized reading' (duration: 308.176481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:00:14.245146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:00:13.935659Z","time spent":"309.475137ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-09T02:00:42.836893Z","caller":"traceutil/trace.go:171","msg":"trace[2090775502] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"125.333597ms","start":"2026-04-09T02:00:42.711533Z","end":"2026-04-09T02:00:42.836867Z","steps":["trace[2090775502] 'process raft request' (duration: 125.168414ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:42.837748Z","caller":"traceutil/trace.go:171","msg":"trace[991462092] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"124.747276ms","start":"2026-04-09T02:00:42.71298Z","end":"2026-04-09T02:00:42.837727Z","steps":["trace[991462092] 'process raft request' (duration: 124.617144ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:26.688438Z","caller":"traceutil/trace.go:171","msg":"trace[67692387] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"152.019868ms","start":"2026-04-09T02:01:26.536402Z","end":"2026-04-09T02:01:26.688422Z","steps":["trace[67692387] 'process raft request' (duration: 151.893747ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:26.688652Z","caller":"traceutil/trace.go:171","msg":"trace[2023644395] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"152.207622ms","start":"2026-04-09T02:01:26.536401Z","end":"2026-04-09T02:01:26.688609Z","steps":["trace[2023644395] 'process raft request' (duration: 151.994438ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:26.697324Z","caller":"traceutil/trace.go:171","msg":"trace[719524029] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"150.433145ms","start":"2026-04-09T02:01:26.54688Z","end":"2026-04-09T02:01:26.697313Z","steps":["trace[719524029] 'process raft request' (duration: 150.230352ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:28.799736Z","caller":"traceutil/trace.go:171","msg":"trace[895177653] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"106.801768ms","start":"2026-04-09T02:01:28.69291Z","end":"2026-04-09T02:01:28.799712Z","steps":["trace[895177653] 'process raft request' (duration: 106.635936ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:34.913489Z","caller":"traceutil/trace.go:171","msg":"trace[2060994958] linearizableReadLoop","detail":"{readStateIndex:2663; appliedIndex:2662; }","duration":"174.86921ms","start":"2026-04-09T02:01:34.738599Z","end":"2026-04-09T02:01:34.913468Z","steps":["trace[2060994958] 'read index received' (duration: 174.758009ms)","trace[2060994958] 'applied index is now lower than readState.Index' (duration: 110.201µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:01:34.9136Z","caller":"traceutil/trace.go:171","msg":"trace[575396940] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"194.097671ms","start":"2026-04-09T02:01:34.719493Z","end":"2026-04-09T02:01:34.91359Z","steps":["trace[575396940] 'process raft request' (duration: 193.838447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:34.913772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.140793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:01:34.913865Z","caller":"traceutil/trace.go:171","msg":"trace[1564504638] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2568; }","duration":"175.289607ms","start":"2026-04-09T02:01:34.738556Z","end":"2026-04-09T02:01:34.913845Z","steps":["trace[1564504638] 'agreement among raft nodes before linearized reading' (duration: 175.128944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.414486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811134729079613,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T02:01:35.546925Z","caller":"traceutil/trace.go:171","msg":"trace[1410386268] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"710.892111ms","start":"2026-04-09T02:01:34.836006Z","end":"2026-04-09T02:01:35.546898Z","steps":["trace[1410386268] 'process raft request' (duration: 710.81174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.547133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.835999Z","time spent":"711.049193ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:01:35.547208Z","caller":"traceutil/trace.go:171","msg":"trace[1358171874] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"711.249816ms","start":"2026-04-09T02:01:34.835929Z","end":"2026-04-09T02:01:35.547179Z","steps":["trace[1358171874] 'process raft request' (duration: 710.737858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.547385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.835914Z","time spent":"711.396317ms","remote":"127.0.0.1:38182","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-04-09T02:01:35.547717Z","caller":"traceutil/trace.go:171","msg":"trace[1253544076] linearizableReadLoop","detail":"{readStateIndex:2666; appliedIndex:2664; }","duration":"634.148371ms","start":"2026-04-09T02:01:34.913554Z","end":"2026-04-09T02:01:35.547702Z","steps":["trace[1253544076] 'read index received' (duration: 633.300419ms)","trace[1253544076] 'applied index is now lower than readState.Index' (duration: 847.262µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:01:35.547957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.957665ms","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-04-09T02:01:35.548018Z","caller":"traceutil/trace.go:171","msg":"trace[1586031247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2571; }","duration":"712.036446ms","start":"2026-04-09T02:01:34.835964Z","end":"2026-04-09T02:01:35.548Z","steps":["trace[1586031247] 'agreement among raft nodes before linearized reading' (duration: 711.852104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.54805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.835959Z","time spent":"712.085086ms","remote":"127.0.0.1:38182","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-04-09T02:01:35.548137Z","caller":"traceutil/trace.go:171","msg":"trace[770527254] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"511.927853ms","start":"2026-04-09T02:01:35.036202Z","end":"2026-04-09T02:01:35.54813Z","steps":["trace[770527254] 'process raft request' (duration: 511.404716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.548198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:35.036177Z","time spent":"511.976374ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:01:35.548279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.811023ms","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-04-09T02:01:35.548295Z","caller":"traceutil/trace.go:171","msg":"trace[58813565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2571; }","duration":"711.825723ms","start":"2026-04-09T02:01:34.836464Z","end":"2026-04-09T02:01:35.54829Z","steps":["trace[58813565] 'agreement among raft nodes before linearized reading' (duration: 711.776173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.54831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.83645Z","time spent":"711.857294ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T02:01:35.548385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.321971ms","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-04-09T02:01:35.548404Z","caller":"traceutil/trace.go:171","msg":"trace[1545922871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2571; }","duration":"712.372021ms","start":"2026-04-09T02:01:34.836027Z","end":"2026-04-09T02:01:35.548399Z","steps":["trace[1545922871] 'agreement among raft nodes before linearized reading' (duration: 712.32074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.54842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.836009Z","time spent":"712.407252ms","remote":"127.0.0.1:38182","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":"warn","ts":"2026-04-09T02:01:35.548493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.647425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-09T02:01:35.548509Z","caller":"traceutil/trace.go:171","msg":"trace[1761721231] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2571; }","duration":"463.700205ms","start":"2026-04-09T02:01:35.084803Z","end":"2026-04-09T02:01:35.548503Z","steps":["trace[1761721231] 'agreement among raft nodes before linearized reading' (duration: 463.651575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.548524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:35.084782Z","time spent":"463.738986ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-09T02:01:35.548509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.838913ms","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-04-09T02:01:35.548577Z","caller":"traceutil/trace.go:171","msg":"trace[2120342412] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2571; }","duration":"598.954715ms","start":"2026-04-09T02:01:34.949602Z","end":"2026-04-09T02:01:35.548556Z","steps":["trace[2120342412] 'agreement among raft nodes before linearized reading' (duration: 598.818553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.54865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.763246ms","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-04-09T02:01:35.548652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.949572Z","time spent":"599.041236ms","remote":"127.0.0.1:55780","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-04-09T02:01:35.548724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.954133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-09T02:01:35.548742Z","caller":"traceutil/trace.go:171","msg":"trace[673092888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2571; }","duration":"465.010654ms","start":"2026-04-09T02:01:35.083725Z","end":"2026-04-09T02:01:35.548736Z","steps":["trace[673092888] 'agreement among raft nodes before linearized reading' (duration: 464.968544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.548757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:35.083704Z","time spent":"465.049134ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:01:35.548595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.645144ms","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-04-09T02:01:35.548812Z","caller":"traceutil/trace.go:171","msg":"trace[1977866254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2571; }","duration":"712.870968ms","start":"2026-04-09T02:01:34.835936Z","end":"2026-04-09T02:01:35.548807Z","steps":["trace[1977866254] 'agreement among raft nodes before linearized reading' (duration: 712.622865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.548826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.835932Z","time spent":"712.891419ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T02:01:35.548669Z","caller":"traceutil/trace.go:171","msg":"trace[149023601] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2571; }","duration":"712.810637ms","start":"2026-04-09T02:01:34.835853Z","end":"2026-04-09T02:01:35.548664Z","steps":["trace[149023601] 'agreement among raft nodes before linearized reading' (duration: 712.751886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.548877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.20286ms","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":"warn","ts":"2026-04-09T02:01:35.548873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.835832Z","time spent":"713.030831ms","remote":"127.0.0.1:38120","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":"info","ts":"2026-04-09T02:01:35.548895Z","caller":"traceutil/trace.go:171","msg":"trace[1128469210] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2571; }","duration":"587.256881ms","start":"2026-04-09T02:01:34.961632Z","end":"2026-04-09T02:01:35.548889Z","steps":["trace[1128469210] 'agreement among raft nodes before linearized reading' (duration: 587.227991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:01:35.548916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:01:34.961611Z","time spent":"587.298391ms","remote":"127.0.0.1:55818","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":"info","ts":"2026-04-09T02:02:08.18848Z","caller":"traceutil/trace.go:171","msg":"trace[429618547] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"409.437166ms","start":"2026-04-09T02:02:07.779018Z","end":"2026-04-09T02:02:08.188455Z","steps":["trace[429618547] 'process raft request' (duration: 409.275144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:02:08.188634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:02:07.779004Z","time spent":"409.556557ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:02:08.201336Z","caller":"traceutil/trace.go:171","msg":"trace[1772054263] linearizableReadLoop","detail":"{readStateIndex:2856; appliedIndex:2852; }","duration":"104.07798ms","start":"2026-04-09T02:02:08.097225Z","end":"2026-04-09T02:02:08.201303Z","steps":["trace[1772054263] 'read index received' (duration: 91.06538ms)","trace[1772054263] 'applied index is now lower than readState.Index' (duration: 13.01103ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:02:08.20156Z","caller":"traceutil/trace.go:171","msg":"trace[1301241605] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"421.978441ms","start":"2026-04-09T02:02:07.779575Z","end":"2026-04-09T02:02:08.201554Z","steps":["trace[1301241605] 'process raft request' (duration: 421.143839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:02:08.201662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:02:07.779558Z","time spent":"422.037181ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:02:08.201821Z","caller":"traceutil/trace.go:171","msg":"trace[762795020] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"421.93096ms","start":"2026-04-09T02:02:07.779869Z","end":"2026-04-09T02:02:08.2018Z","steps":["trace[762795020] 'process raft request' (duration: 421.373162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:02:08.202103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:02:07.779859Z","time spent":"422.121982ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:02:08.20227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.044393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a48dd71a8d4d9c\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-09T02:02:08.202305Z","caller":"traceutil/trace.go:171","msg":"trace[980487556] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a48dd71a8d4d9c; range_end:; response_count:1; response_revision:2754; }","duration":"105.117144ms","start":"2026-04-09T02:02:08.097179Z","end":"2026-04-09T02:02:08.202296Z","steps":["trace[980487556] 'agreement among raft nodes before linearized reading' (duration: 104.995802ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:02:08.201499Z","caller":"traceutil/trace.go:171","msg":"trace[399481459] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"421.894419ms","start":"2026-04-09T02:02:07.77958Z","end":"2026-04-09T02:02:08.201475Z","steps":["trace[399481459] 'process raft request' (duration: 421.586205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:02:08.202474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:02:07.779557Z","time spent":"422.889772ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:02:08.804302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.20478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:02:08.804703Z","caller":"traceutil/trace.go:171","msg":"trace[187309281] linearizableReadLoop","detail":"{readStateIndex:2857; appliedIndex:2856; }","duration":"546.819287ms","start":"2026-04-09T02:02:08.257865Z","end":"2026-04-09T02:02:08.804684Z","steps":["trace[187309281] 'read index received' (duration: 296.141231ms)","trace[187309281] 'applied index is now lower than readState.Index' (duration: 250.676566ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:02:08.804779Z","caller":"traceutil/trace.go:171","msg":"trace[419365154] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"599.763139ms","start":"2026-04-09T02:02:08.205Z","end":"2026-04-09T02:02:08.804763Z","steps":["trace[419365154] 'process raft request' (duration: 348.993612ms)","trace[419365154] 'compare' (duration: 249.51653ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:02:08.804851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:02:08.204982Z","time spent":"599.825609ms","remote":"127.0.0.1:37980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:02:09.186717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.829015ms","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-04-09T02:02:09.186804Z","caller":"traceutil/trace.go:171","msg":"trace[1163941938] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2755; }","duration":"928.990008ms","start":"2026-04-09T02:02:08.257792Z","end":"2026-04-09T02:02:09.186782Z","steps":["trace[1163941938] 'agreement among raft nodes before linearized reading' (duration: 546.946158ms)","trace[1163941938] 'count revisions from in-memory index tree' (duration: 381.910567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:02:09.186849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:02:08.257772Z","time spent":"929.065808ms","remote":"127.0.0.1:55768","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-04-09T02:02:09.187728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.331845ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T02:02:09.187935Z","caller":"traceutil/trace.go:171","msg":"trace[1701606124] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"130.346471ms","start":"2026-04-09T02:02:09.057576Z","end":"2026-04-09T02:02:09.187923Z","steps":["trace[1701606124] 'process raft request' (duration: 130.221229ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:02:09.188109Z","caller":"traceutil/trace.go:171","msg":"trace[58707686] linearizableReadLoop","detail":"{readStateIndex:2858; appliedIndex:2857; }","duration":"370.5841ms","start":"2026-04-09T02:02:08.817514Z","end":"2026-04-09T02:02:09.188098Z","steps":["trace[58707686] 'read index received' (duration: 46.241µs)","trace[58707686] 'applied index is now lower than readState.Index' (duration: 370.536919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:02:09.188322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.733441ms","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-04-09T02:02:09.188385Z","caller":"traceutil/trace.go:171","msg":"trace[378217645] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2756; }","duration":"370.909804ms","start":"2026-04-09T02:02:08.817465Z","end":"2026-04-09T02:02:09.188375Z","steps":["trace[378217645] 'agreement among raft nodes before linearized reading' (duration: 370.667291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:02:09.188417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:02:08.81744Z","time spent":"370.966694ms","remote":"127.0.0.1:38120","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":"info","ts":"2026-04-09T02:02:31.867571Z","caller":"traceutil/trace.go:171","msg":"trace[1982586840] transaction","detail":"{read_only:false; response_revision:2876; number_of_response:1; }","duration":"118.458387ms","start":"2026-04-09T02:02:31.749079Z","end":"2026-04-09T02:02:31.867537Z","steps":["trace[1982586840] 'process raft request' (duration: 118.237555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:25.122285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.668528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:03:25.122603Z","caller":"traceutil/trace.go:171","msg":"trace[876245456] transaction","detail":"{read_only:false; response_revision:3157; number_of_response:1; }","duration":"328.035795ms","start":"2026-04-09T02:03:24.794512Z","end":"2026-04-09T02:03:25.122548Z","steps":["trace[876245456] 'process raft request' (duration: 110.977982ms)","trace[876245456] 'compare' (duration: 216.492051ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:25.122636Z","caller":"traceutil/trace.go:171","msg":"trace[2068669617] linearizableReadLoop","detail":"{readStateIndex:3276; appliedIndex:3274; }","duration":"305.341227ms","start":"2026-04-09T02:03:24.817067Z","end":"2026-04-09T02:03:25.122408Z","steps":["trace[2068669617] 'read index received' (duration: 40.84243ms)","trace[2068669617] 'applied index is now lower than readState.Index' (duration: 264.497237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:25.122806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.716672ms","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-04-09T02:03:25.12285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:24.794498Z","time spent":"328.211332ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:03:25.122887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.699534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:03:25.123005Z","caller":"traceutil/trace.go:171","msg":"trace[1166540185] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:3157; }","duration":"101.869461ms","start":"2026-04-09T02:03:25.021123Z","end":"2026-04-09T02:03:25.122993Z","steps":["trace[1166540185] 'agreement among raft nodes before linearized reading' (duration: 101.682394ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:25.122895Z","caller":"traceutil/trace.go:171","msg":"trace[1470576957] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3157; }","duration":"305.842246ms","start":"2026-04-09T02:03:24.817036Z","end":"2026-04-09T02:03:25.122878Z","steps":["trace[1470576957] 'agreement among raft nodes before linearized reading' (duration: 305.591327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:25.123168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:24.817022Z","time spent":"306.130008ms","remote":"127.0.0.1:38120","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":"info","ts":"2026-04-09T02:03:30.425199Z","caller":"traceutil/trace.go:171","msg":"trace[1169544390] transaction","detail":"{read_only:false; response_revision:3183; number_of_response:1; }","duration":"132.981259ms","start":"2026-04-09T02:03:30.29219Z","end":"2026-04-09T02:03:30.425172Z","steps":["trace[1169544390] 'process raft request' (duration: 64.633189ms)","trace[1169544390] 'compare' (duration: 68.244666ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:36.869919Z","caller":"traceutil/trace.go:171","msg":"trace[365345638] transaction","detail":"{read_only:false; response_revision:3216; number_of_response:1; }","duration":"161.701711ms","start":"2026-04-09T02:03:36.708187Z","end":"2026-04-09T02:03:36.869888Z","steps":["trace[365345638] 'process raft request' (duration: 161.415951ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:37.028242Z","caller":"traceutil/trace.go:171","msg":"trace[1793837024] linearizableReadLoop","detail":"{readStateIndex:3338; appliedIndex:3337; }","duration":"152.095325ms","start":"2026-04-09T02:03:36.876089Z","end":"2026-04-09T02:03:37.028185Z","steps":["trace[1793837024] 'read index received' (duration: 150.939844ms)","trace[1793837024] 'applied index is now lower than readState.Index' (duration: 1.154641ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:37.028583Z","caller":"traceutil/trace.go:171","msg":"trace[737035158] transaction","detail":"{read_only:false; response_revision:3217; number_of_response:1; }","duration":"154.365879ms","start":"2026-04-09T02:03:36.874206Z","end":"2026-04-09T02:03:37.028572Z","steps":["trace[737035158] 'process raft request' (duration: 152.831713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:37.028563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.443199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1315"} {"level":"info","ts":"2026-04-09T02:03:37.028629Z","caller":"traceutil/trace.go:171","msg":"trace[1596838580] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3217; }","duration":"152.550662ms","start":"2026-04-09T02:03:36.876063Z","end":"2026-04-09T02:03:37.028614Z","steps":["trace[1596838580] 'agreement among raft nodes before linearized reading' (duration: 152.341074ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:42.410831Z","caller":"traceutil/trace.go:171","msg":"trace[839570904] transaction","detail":"{read_only:false; response_revision:3242; number_of_response:1; }","duration":"124.124674ms","start":"2026-04-09T02:03:42.286667Z","end":"2026-04-09T02:03:42.410791Z","steps":["trace[839570904] 'process raft request' (duration: 123.719081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:47.173963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.274383ms","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-04-09T02:03:47.174056Z","caller":"traceutil/trace.go:171","msg":"trace[443351438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3266; }","duration":"101.419017ms","start":"2026-04-09T02:03:47.072618Z","end":"2026-04-09T02:03:47.174037Z","steps":["trace[443351438] 'range keys from in-memory index tree' (duration: 101.083156ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:47.33241Z","caller":"traceutil/trace.go:171","msg":"trace[581645955] transaction","detail":"{read_only:false; response_revision:3267; number_of_response:1; }","duration":"154.39837ms","start":"2026-04-09T02:03:47.177992Z","end":"2026-04-09T02:03:47.33239Z","steps":["trace[581645955] 'process raft request' (duration: 154.281536ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:47.373033Z","caller":"traceutil/trace.go:171","msg":"trace[623637226] linearizableReadLoop","detail":"{readStateIndex:3391; appliedIndex:3389; }","duration":"133.547184ms","start":"2026-04-09T02:03:47.239469Z","end":"2026-04-09T02:03:47.373016Z","steps":["trace[623637226] 'read index received' (duration: 92.818908ms)","trace[623637226] 'applied index is now lower than readState.Index' (duration: 40.727566ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:47.373263Z","caller":"traceutil/trace.go:171","msg":"trace[80122692] transaction","detail":"{read_only:false; response_revision:3268; number_of_response:1; }","duration":"194.755934ms","start":"2026-04-09T02:03:47.178485Z","end":"2026-04-09T02:03:47.373241Z","steps":["trace[80122692] 'process raft request' (duration: 194.443382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:47.373368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.891706ms","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-04-09T02:03:47.374163Z","caller":"traceutil/trace.go:171","msg":"trace[62233830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3268; }","duration":"134.735265ms","start":"2026-04-09T02:03:47.239416Z","end":"2026-04-09T02:03:47.374151Z","steps":["trace[62233830] 'agreement among raft nodes before linearized reading' (duration: 133.852985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:02.592832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.1272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-09T02:04:02.592934Z","caller":"traceutil/trace.go:171","msg":"trace[390192295] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3431; }","duration":"172.268064ms","start":"2026-04-09T02:04:02.420644Z","end":"2026-04-09T02:04:02.592912Z","steps":["trace[390192295] 'range keys from in-memory index tree' (duration: 171.910663ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:02.642862Z","caller":"traceutil/trace.go:171","msg":"trace[467519691] linearizableReadLoop","detail":"{readStateIndex:3562; appliedIndex:3561; }","duration":"136.746576ms","start":"2026-04-09T02:04:02.5061Z","end":"2026-04-09T02:04:02.642847Z","steps":["trace[467519691] 'read index received' (duration: 136.678624ms)","trace[467519691] 'applied index is now lower than readState.Index' (duration: 66.912µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:04:02.643006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.889991ms","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-04-09T02:04:02.643048Z","caller":"traceutil/trace.go:171","msg":"trace[1045111519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3432; }","duration":"136.991765ms","start":"2026-04-09T02:04:02.506051Z","end":"2026-04-09T02:04:02.643043Z","steps":["trace[1045111519] 'agreement among raft nodes before linearized reading' (duration: 136.86981ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:02.642923Z","caller":"traceutil/trace.go:171","msg":"trace[1044976154] transaction","detail":"{read_only:false; response_revision:3432; number_of_response:1; }","duration":"160.80837ms","start":"2026-04-09T02:04:02.482094Z","end":"2026-04-09T02:04:02.642902Z","steps":["trace[1044976154] 'process raft request' (duration: 160.639855ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:03.051208Z","caller":"traceutil/trace.go:171","msg":"trace[736382496] transaction","detail":"{read_only:false; response_revision:3436; number_of_response:1; }","duration":"125.242075ms","start":"2026-04-09T02:04:02.925935Z","end":"2026-04-09T02:04:03.051177Z","steps":["trace[736382496] 'process raft request' (duration: 125.058899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:18.716513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.578075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:18.7166Z","caller":"traceutil/trace.go:171","msg":"trace[1751868322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3524; }","duration":"111.71293ms","start":"2026-04-09T02:04:18.604867Z","end":"2026-04-09T02:04:18.71658Z","steps":["trace[1751868322] 'range keys from in-memory index tree' (duration: 111.432621ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:18.967Z","caller":"traceutil/trace.go:171","msg":"trace[441546162] linearizableReadLoop","detail":"{readStateIndex:3659; appliedIndex:3658; }","duration":"170.212276ms","start":"2026-04-09T02:04:18.796768Z","end":"2026-04-09T02:04:18.96698Z","steps":["trace[441546162] 'read index received' (duration: 170.095222ms)","trace[441546162] 'applied index is now lower than readState.Index' (duration: 116.414µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:18.967126Z","caller":"traceutil/trace.go:171","msg":"trace[1048537059] transaction","detail":"{read_only:false; response_revision:3525; number_of_response:1; }","duration":"223.600515ms","start":"2026-04-09T02:04:18.743498Z","end":"2026-04-09T02:04:18.967099Z","steps":["trace[1048537059] 'process raft request' (duration: 223.353558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:18.967245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.458904ms","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-04-09T02:04:18.967307Z","caller":"traceutil/trace.go:171","msg":"trace[762350311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3525; }","duration":"170.558507ms","start":"2026-04-09T02:04:18.79674Z","end":"2026-04-09T02:04:18.967299Z","steps":["trace[762350311] 'agreement among raft nodes before linearized reading' (duration: 170.387021ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:19.163156Z","caller":"traceutil/trace.go:171","msg":"trace[1189870865] linearizableReadLoop","detail":"{readStateIndex:3660; appliedIndex:3659; }","duration":"196.018534ms","start":"2026-04-09T02:04:18.967111Z","end":"2026-04-09T02:04:19.163129Z","steps":["trace[1189870865] 'read index received' (duration: 102.649896ms)","trace[1189870865] 'applied index is now lower than readState.Index' (duration: 93.367778ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:19.16323Z","caller":"traceutil/trace.go:171","msg":"trace[913894912] transaction","detail":"{read_only:false; response_revision:3526; number_of_response:1; }","duration":"324.348251ms","start":"2026-04-09T02:04:18.838849Z","end":"2026-04-09T02:04:19.163197Z","steps":["trace[913894912] 'process raft request' (duration: 230.901191ms)","trace[913894912] 'compare' (duration: 93.231824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:04:19.163442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.786216ms","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-04-09T02:04:19.16351Z","caller":"traceutil/trace.go:171","msg":"trace[1571623954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3526; }","duration":"364.932821ms","start":"2026-04-09T02:04:18.798563Z","end":"2026-04-09T02:04:19.163496Z","steps":["trace[1571623954] 'agreement among raft nodes before linearized reading' (duration: 364.710964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:19.163545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:18.798518Z","time spent":"365.021633ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T02:04:19.163636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:18.838815Z","time spent":"324.592329ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:04:19.166474Z","caller":"traceutil/trace.go:171","msg":"trace[1885774037] transaction","detail":"{read_only:false; response_revision:3527; number_of_response:1; }","duration":"195.064894ms","start":"2026-04-09T02:04:18.971387Z","end":"2026-04-09T02:04:19.166452Z","steps":["trace[1885774037] 'process raft request' (duration: 194.983462ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:23.766527Z","caller":"traceutil/trace.go:171","msg":"trace[1084714009] transaction","detail":"{read_only:false; response_revision:3556; number_of_response:1; }","duration":"148.868556ms","start":"2026-04-09T02:04:23.617633Z","end":"2026-04-09T02:04:23.766502Z","steps":["trace[1084714009] 'process raft request' (duration: 148.717302ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:39.583502Z","caller":"traceutil/trace.go:171","msg":"trace[686652990] transaction","detail":"{read_only:false; response_revision:3637; number_of_response:1; }","duration":"110.058921ms","start":"2026-04-09T02:04:39.473427Z","end":"2026-04-09T02:04:39.583486Z","steps":["trace[686652990] 'process raft request' (duration: 22.723301ms)","trace[686652990] 'compare' (duration: 87.030412ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:39.583447Z","caller":"traceutil/trace.go:171","msg":"trace[414033387] linearizableReadLoop","detail":"{readStateIndex:3775; appliedIndex:3774; }","duration":"104.817144ms","start":"2026-04-09T02:04:39.478609Z","end":"2026-04-09T02:04:39.583426Z","steps":["trace[414033387] 'read index received' (duration: 17.496623ms)","trace[414033387] 'applied index is now lower than readState.Index' (duration: 87.319731ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:04:39.583743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.104792ms","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-04-09T02:04:39.583831Z","caller":"traceutil/trace.go:171","msg":"trace[831624925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3637; }","duration":"105.227206ms","start":"2026-04-09T02:04:39.478587Z","end":"2026-04-09T02:04:39.583814Z","steps":["trace[831624925] 'agreement among raft nodes before linearized reading' (duration: 104.991259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:43.738253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.326673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:43.738322Z","caller":"traceutil/trace.go:171","msg":"trace[410077580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3657; }","duration":"133.450826ms","start":"2026-04-09T02:04:43.604852Z","end":"2026-04-09T02:04:43.738303Z","steps":["trace[410077580] 'range keys from in-memory index tree' (duration: 133.205029ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:43.739032Z","caller":"traceutil/trace.go:171","msg":"trace[1487097401] transaction","detail":"{read_only:false; response_revision:3658; number_of_response:1; }","duration":"103.559026ms","start":"2026-04-09T02:04:43.635452Z","end":"2026-04-09T02:04:43.739011Z","steps":["trace[1487097401] 'process raft request' (duration: 93.200138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.16503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.631162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:44.165129Z","caller":"traceutil/trace.go:171","msg":"trace[1259560593] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:0; response_revision:3659; }","duration":"229.798987ms","start":"2026-04-09T02:04:43.935305Z","end":"2026-04-09T02:04:44.165104Z","steps":["trace[1259560593] 'range keys from in-memory index tree' (duration: 229.497387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.165529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.079641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T02:04:44.165689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.493079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-09T02:04:44.165671Z","caller":"traceutil/trace.go:171","msg":"trace[1007626942] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:3659; }","duration":"254.314686ms","start":"2026-04-09T02:04:43.911325Z","end":"2026-04-09T02:04:44.16564Z","steps":["trace[1007626942] 'count revisions from in-memory index tree' (duration: 253.954957ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:44.165814Z","caller":"traceutil/trace.go:171","msg":"trace[1768214986] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3659; }","duration":"273.684245ms","start":"2026-04-09T02:04:43.8921Z","end":"2026-04-09T02:04:44.165785Z","steps":["trace[1768214986] 'range keys from in-memory index tree' (duration: 273.272883ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:44.166361Z","caller":"traceutil/trace.go:171","msg":"trace[1928967552] transaction","detail":"{read_only:false; response_revision:3660; number_of_response:1; }","duration":"193.542239ms","start":"2026-04-09T02:04:43.972795Z","end":"2026-04-09T02:04:44.166337Z","steps":["trace[1928967552] 'process raft request' (duration: 141.119638ms)","trace[1928967552] 'compare' (duration: 50.991981ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:44.166342Z","caller":"traceutil/trace.go:171","msg":"trace[807229280] linearizableReadLoop","detail":"{readStateIndex:3799; appliedIndex:3798; }","duration":"179.999592ms","start":"2026-04-09T02:04:43.986292Z","end":"2026-04-09T02:04:44.166292Z","steps":["trace[807229280] 'read index received' (duration: 127.551631ms)","trace[807229280] 'applied index is now lower than readState.Index' (duration: 52.445781ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:04:44.166687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.123995ms","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-04-09T02:04:44.166754Z","caller":"traceutil/trace.go:171","msg":"trace[2071834904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3660; }","duration":"145.233219ms","start":"2026-04-09T02:04:44.021502Z","end":"2026-04-09T02:04:44.166735Z","steps":["trace[2071834904] 'agreement among raft nodes before linearized reading' (duration: 145.073785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.167233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.93787ms","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-04-09T02:04:44.167319Z","caller":"traceutil/trace.go:171","msg":"trace[563694929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3660; }","duration":"181.113524ms","start":"2026-04-09T02:04:43.986189Z","end":"2026-04-09T02:04:44.167303Z","steps":["trace[563694929] 'agreement among raft nodes before linearized reading' (duration: 180.221949ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:44.297167Z","caller":"traceutil/trace.go:171","msg":"trace[1069179870] linearizableReadLoop","detail":"{readStateIndex:3802; appliedIndex:3799; }","duration":"123.837556ms","start":"2026-04-09T02:04:44.173308Z","end":"2026-04-09T02:04:44.297145Z","steps":["trace[1069179870] 'read index received' (duration: 43.38149ms)","trace[1069179870] 'applied index is now lower than readState.Index' (duration: 80.454996ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:44.297209Z","caller":"traceutil/trace.go:171","msg":"trace[1639228306] transaction","detail":"{read_only:false; response_revision:3662; number_of_response:1; }","duration":"124.948957ms","start":"2026-04-09T02:04:44.172231Z","end":"2026-04-09T02:04:44.29718Z","steps":["trace[1639228306] 'process raft request' (duration: 116.240507ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:44.297279Z","caller":"traceutil/trace.go:171","msg":"trace[913030106] transaction","detail":"{read_only:false; response_revision:3663; number_of_response:1; }","duration":"124.279748ms","start":"2026-04-09T02:04:44.172962Z","end":"2026-04-09T02:04:44.297242Z","steps":["trace[913030106] 'process raft request' (duration: 124.134674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.297384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.986611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:44.297508Z","caller":"traceutil/trace.go:171","msg":"trace[352125303] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:3663; }","duration":"124.204747ms","start":"2026-04-09T02:04:44.173286Z","end":"2026-04-09T02:04:44.29749Z","steps":["trace[352125303] 'agreement among raft nodes before linearized reading' (duration: 123.95509ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:44.566354Z","caller":"traceutil/trace.go:171","msg":"trace[2096689701] transaction","detail":"{read_only:false; response_revision:3666; number_of_response:1; }","duration":"174.984921ms","start":"2026-04-09T02:04:44.391188Z","end":"2026-04-09T02:04:44.566173Z","steps":["trace[2096689701] 'process raft request' (duration: 123.148616ms)","trace[2096689701] 'compare' (duration: 51.506026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:04:49.525865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.84727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-04-09T02:04:49.525952Z","caller":"traceutil/trace.go:171","msg":"trace[1732555256] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-cookie-secret; range_end:; response_count:1; response_revision:3688; }","duration":"186.995173ms","start":"2026-04-09T02:04:49.338934Z","end":"2026-04-09T02:04:49.525929Z","steps":["trace[1732555256] 'range keys from in-memory index tree' (duration: 186.401937ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:00.572836Z","caller":"traceutil/trace.go:171","msg":"trace[41790540] linearizableReadLoop","detail":"{readStateIndex:3892; appliedIndex:3891; }","duration":"193.458185ms","start":"2026-04-09T02:05:00.37935Z","end":"2026-04-09T02:05:00.572808Z","steps":["trace[41790540] 'read index received' (duration: 119.100208ms)","trace[41790540] 'applied index is now lower than readState.Index' (duration: 74.356557ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:00.572971Z","caller":"traceutil/trace.go:171","msg":"trace[640663865] transaction","detail":"{read_only:false; response_revision:3750; number_of_response:1; }","duration":"224.902598ms","start":"2026-04-09T02:05:00.348054Z","end":"2026-04-09T02:05:00.572956Z","steps":["trace[640663865] 'process raft request' (duration: 150.465499ms)","trace[640663865] 'compare' (duration: 74.149912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:00.573161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.794603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-09T02:05:00.573197Z","caller":"traceutil/trace.go:171","msg":"trace[1589207199] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3750; }","duration":"193.878947ms","start":"2026-04-09T02:05:00.37931Z","end":"2026-04-09T02:05:00.573189Z","steps":["trace[1589207199] 'agreement among raft nodes before linearized reading' (duration: 193.754253ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:13.479281Z","caller":"traceutil/trace.go:171","msg":"trace[1858440270] transaction","detail":"{read_only:false; response_revision:3882; number_of_response:1; }","duration":"167.385452ms","start":"2026-04-09T02:05:13.311859Z","end":"2026-04-09T02:05:13.479244Z","steps":["trace[1858440270] 'process raft request' (duration: 167.168877ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.073759Z","caller":"traceutil/trace.go:171","msg":"trace[1296787272] transaction","detail":"{read_only:false; response_revision:3899; number_of_response:1; }","duration":"213.671185ms","start":"2026-04-09T02:05:15.860068Z","end":"2026-04-09T02:05:16.073739Z","steps":["trace[1296787272] 'process raft request' (duration: 213.45562ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.073678Z","caller":"traceutil/trace.go:171","msg":"trace[1848777895] linearizableReadLoop","detail":"{readStateIndex:4049; appliedIndex:4048; }","duration":"203.185073ms","start":"2026-04-09T02:05:15.870472Z","end":"2026-04-09T02:05:16.073657Z","steps":["trace[1848777895] 'read index received' (duration: 202.974438ms)","trace[1848777895] 'applied index is now lower than readState.Index' (duration: 209.845µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:16.073962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.463259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-hosts-overview\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:05:16.074177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.581658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-09T02:05:16.074296Z","caller":"traceutil/trace.go:171","msg":"trace[991400771] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3899; }","duration":"115.739612ms","start":"2026-04-09T02:05:15.958543Z","end":"2026-04-09T02:05:16.074283Z","steps":["trace[991400771] 'agreement among raft nodes before linearized reading' (duration: 115.515516ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.0743Z","caller":"traceutil/trace.go:171","msg":"trace[1697655441] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-hosts-overview; range_end:; response_count:0; response_revision:3899; }","duration":"203.590484ms","start":"2026-04-09T02:05:15.870437Z","end":"2026-04-09T02:05:16.074027Z","steps":["trace[1697655441] 'agreement among raft nodes before linearized reading' (duration: 203.441449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:16.074178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.495565ms","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-04-09T02:05:16.074421Z","caller":"traceutil/trace.go:171","msg":"trace[173273818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3899; }","duration":"129.780642ms","start":"2026-04-09T02:05:15.944624Z","end":"2026-04-09T02:05:16.074405Z","steps":["trace[173273818] 'agreement among raft nodes before linearized reading' (duration: 129.405723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:16.074609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.628105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:05:16.074671Z","caller":"traceutil/trace.go:171","msg":"trace[687890412] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:3899; }","duration":"181.743698ms","start":"2026-04-09T02:05:15.892915Z","end":"2026-04-09T02:05:16.074658Z","steps":["trace[687890412] 'agreement among raft nodes before linearized reading' (duration: 181.090222ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.975539Z","caller":"traceutil/trace.go:171","msg":"trace[1391513777] transaction","detail":"{read_only:false; response_revision:3906; number_of_response:1; }","duration":"144.032491ms","start":"2026-04-09T02:05:16.831481Z","end":"2026-04-09T02:05:16.975514Z","steps":["trace[1391513777] 'process raft request' (duration: 67.104018ms)","trace[1391513777] 'compare' (duration: 76.600346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:27.140753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.283411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-09T02:05:27.14084Z","caller":"traceutil/trace.go:171","msg":"trace[1948079163] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3962; }","duration":"129.418254ms","start":"2026-04-09T02:05:27.011401Z","end":"2026-04-09T02:05:27.140819Z","steps":["trace[1948079163] 'range keys from in-memory index tree' (duration: 129.081366ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:47.059572Z","caller":"traceutil/trace.go:171","msg":"trace[868816087] transaction","detail":"{read_only:false; response_revision:4365; number_of_response:1; }","duration":"124.111409ms","start":"2026-04-09T02:05:46.93544Z","end":"2026-04-09T02:05:47.059551Z","steps":["trace[868816087] 'process raft request' (duration: 81.326725ms)","trace[868816087] 'compare' (duration: 42.235271ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:47.059695Z","caller":"traceutil/trace.go:171","msg":"trace[681962932] transaction","detail":"{read_only:false; response_revision:4366; number_of_response:1; }","duration":"122.564212ms","start":"2026-04-09T02:05:46.937111Z","end":"2026-04-09T02:05:47.059675Z","steps":["trace[681962932] 'process raft request' (duration: 122.432759ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:47.105966Z","caller":"traceutil/trace.go:171","msg":"trace[433985582] transaction","detail":"{read_only:false; response_revision:4367; number_of_response:1; }","duration":"150.519417ms","start":"2026-04-09T02:05:46.955411Z","end":"2026-04-09T02:05:47.10593Z","steps":["trace[433985582] 'process raft request' (duration: 149.902233ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:47.106158Z","caller":"traceutil/trace.go:171","msg":"trace[543460011] linearizableReadLoop","detail":"{readStateIndex:4539; appliedIndex:4536; }","duration":"146.899425ms","start":"2026-04-09T02:05:46.959242Z","end":"2026-04-09T02:05:47.106142Z","steps":["trace[543460011] 'read index received' (duration: 57.610254ms)","trace[543460011] 'applied index is now lower than readState.Index' (duration: 89.288221ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:47.106288Z","caller":"traceutil/trace.go:171","msg":"trace[1089162796] transaction","detail":"{read_only:false; response_revision:4369; number_of_response:1; }","duration":"140.500589ms","start":"2026-04-09T02:05:46.965777Z","end":"2026-04-09T02:05:47.106278Z","steps":["trace[1089162796] 'process raft request' (duration: 140.174411ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:47.106648Z","caller":"traceutil/trace.go:171","msg":"trace[518002415] transaction","detail":"{read_only:false; response_revision:4368; number_of_response:1; }","duration":"145.764229ms","start":"2026-04-09T02:05:46.960234Z","end":"2026-04-09T02:05:47.105999Z","steps":["trace[518002415] 'process raft request' (duration: 145.637157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:47.106874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.617462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-zpg8q\" ","response":"range_response_count:1 size:2464"} {"level":"info","ts":"2026-04-09T02:05:47.106935Z","caller":"traceutil/trace.go:171","msg":"trace[806302539] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-zpg8q; range_end:; response_count:1; response_revision:4370; }","duration":"147.705983ms","start":"2026-04-09T02:05:46.959216Z","end":"2026-04-09T02:05:47.106922Z","steps":["trace[806302539] 'agreement among raft nodes before linearized reading' (duration: 147.280904ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:50.581817Z","caller":"traceutil/trace.go:171","msg":"trace[1872352126] transaction","detail":"{read_only:false; response_revision:4449; number_of_response:1; }","duration":"264.232076ms","start":"2026-04-09T02:05:50.317558Z","end":"2026-04-09T02:05:50.58179Z","steps":["trace[1872352126] 'process raft request' (duration: 264.080502ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:51.304027Z","caller":"traceutil/trace.go:171","msg":"trace[1441337421] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"376.994452ms","start":"2026-04-09T02:05:50.927007Z","end":"2026-04-09T02:05:51.304002Z","steps":["trace[1441337421] 'process raft request' (duration: 376.866509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:51.304167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:50.926992Z","time spent":"377.104165ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:05:51.320011Z","caller":"traceutil/trace.go:171","msg":"trace[1964142456] transaction","detail":"{read_only:false; response_revision:4452; number_of_response:1; }","duration":"355.294461ms","start":"2026-04-09T02:05:50.964701Z","end":"2026-04-09T02:05:51.319996Z","steps":["trace[1964142456] 'process raft request' (duration: 355.169619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:51.320101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:50.964675Z","time spent":"355.381813ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:05:51.320391Z","caller":"traceutil/trace.go:171","msg":"trace[730120908] linearizableReadLoop","detail":"{readStateIndex:4627; appliedIndex:4624; }","duration":"183.982242ms","start":"2026-04-09T02:05:51.136388Z","end":"2026-04-09T02:05:51.32037Z","steps":["trace[730120908] 'read index received' (duration: 167.707716ms)","trace[730120908] 'applied index is now lower than readState.Index' (duration: 16.273686ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:51.320425Z","caller":"traceutil/trace.go:171","msg":"trace[240010561] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"308.546567ms","start":"2026-04-09T02:05:51.011843Z","end":"2026-04-09T02:05:51.320389Z","steps":["trace[240010561] 'process raft request' (duration: 308.110846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:51.320677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.282338ms","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-04-09T02:05:51.320726Z","caller":"traceutil/trace.go:171","msg":"trace[2045822246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4454; }","duration":"184.34952ms","start":"2026-04-09T02:05:51.136365Z","end":"2026-04-09T02:05:51.320714Z","steps":["trace[2045822246] 'agreement among raft nodes before linearized reading' (duration: 184.218348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:51.320799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:51.011808Z","time spent":"308.804682ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:05:51.321033Z","caller":"traceutil/trace.go:171","msg":"trace[2115619171] transaction","detail":"{read_only:false; response_revision:4454; number_of_response:1; }","duration":"198.410197ms","start":"2026-04-09T02:05:51.122071Z","end":"2026-04-09T02:05:51.320481Z","steps":["trace[2115619171] 'process raft request' (duration: 198.198222ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:54.459163Z","caller":"traceutil/trace.go:171","msg":"trace[1545594579] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"137.592553ms","start":"2026-04-09T02:05:54.321514Z","end":"2026-04-09T02:05:54.459107Z","steps":["trace[1545594579] 'process raft request' (duration: 137.327098ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:55.653166Z","caller":"traceutil/trace.go:171","msg":"trace[1249878684] linearizableReadLoop","detail":"{readStateIndex:4748; appliedIndex:4746; }","duration":"223.830698ms","start":"2026-04-09T02:05:55.429313Z","end":"2026-04-09T02:05:55.653144Z","steps":["trace[1249878684] 'read index received' (duration: 49.514453ms)","trace[1249878684] 'applied index is now lower than readState.Index' (duration: 174.315085ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:55.653273Z","caller":"traceutil/trace.go:171","msg":"trace[199201354] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"234.2042ms","start":"2026-04-09T02:05:55.419024Z","end":"2026-04-09T02:05:55.653228Z","steps":["trace[199201354] 'process raft request' (duration: 179.541712ms)","trace[199201354] 'compare' (duration: 54.473284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:55.653415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.089163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-09T02:05:55.653495Z","caller":"traceutil/trace.go:171","msg":"trace[428026206] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4574; }","duration":"224.219986ms","start":"2026-04-09T02:05:55.429259Z","end":"2026-04-09T02:05:55.653479Z","steps":["trace[428026206] 'agreement among raft nodes before linearized reading' (duration: 223.998621ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:55.685995Z","caller":"traceutil/trace.go:171","msg":"trace[1468080816] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4576; }","duration":"160.647531ms","start":"2026-04-09T02:05:55.525328Z","end":"2026-04-09T02:05:55.685976Z","steps":["trace[1468080816] 'process raft request' (duration: 160.62113ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:55.686018Z","caller":"traceutil/trace.go:171","msg":"trace[1131839551] transaction","detail":"{read_only:false; response_revision:4575; number_of_response:1; }","duration":"177.828973ms","start":"2026-04-09T02:05:55.508161Z","end":"2026-04-09T02:05:55.68599Z","steps":["trace[1131839551] 'process raft request' (duration: 177.651738ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:55.686317Z","caller":"traceutil/trace.go:171","msg":"trace[326651045] transaction","detail":"{read_only:false; response_revision:4576; number_of_response:1; }","duration":"171.722987ms","start":"2026-04-09T02:05:55.514313Z","end":"2026-04-09T02:05:55.686036Z","steps":["trace[326651045] 'process raft request' (duration: 171.594044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:55.694495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.731677ms","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-04-09T02:05:55.69457Z","caller":"traceutil/trace.go:171","msg":"trace[240301233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4578; }","duration":"263.84714ms","start":"2026-04-09T02:05:55.430706Z","end":"2026-04-09T02:05:55.694553Z","steps":["trace[240301233] 'agreement among raft nodes before linearized reading' (duration: 263.645226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:55.694925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.941288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-8hmvs-tls\" ","response":"range_response_count:1 size:1581"} {"level":"info","ts":"2026-04-09T02:05:55.694961Z","caller":"traceutil/trace.go:171","msg":"trace[811648667] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-8hmvs-tls; range_end:; response_count:1; response_revision:4578; }","duration":"188.009229ms","start":"2026-04-09T02:05:55.506943Z","end":"2026-04-09T02:05:55.694952Z","steps":["trace[811648667] 'agreement among raft nodes before linearized reading' (duration: 187.937818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:55.695216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.410743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf2bac48-3499-4a5f-8bbc-4a3daddb8b2d\" ","response":"range_response_count:1 size:4079"} {"level":"info","ts":"2026-04-09T02:05:55.695397Z","caller":"traceutil/trace.go:171","msg":"trace[349231433] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf2bac48-3499-4a5f-8bbc-4a3daddb8b2d; range_end:; response_count:1; response_revision:4578; }","duration":"229.647418ms","start":"2026-04-09T02:05:55.465729Z","end":"2026-04-09T02:05:55.695377Z","steps":["trace[349231433] 'agreement among raft nodes before linearized reading' (duration: 229.209478ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:55.928407Z","caller":"traceutil/trace.go:171","msg":"trace[1260210843] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"235.087439ms","start":"2026-04-09T02:05:55.69326Z","end":"2026-04-09T02:05:55.928347Z","steps":["trace[1260210843] 'process raft request' (duration: 225.335542ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:55.941102Z","caller":"traceutil/trace.go:171","msg":"trace[2096219452] transaction","detail":"{read_only:false; response_revision:4582; number_of_response:1; }","duration":"213.493107ms","start":"2026-04-09T02:05:55.727593Z","end":"2026-04-09T02:05:55.941086Z","steps":["trace[2096219452] 'process raft request' (duration: 213.440156ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:55.941439Z","caller":"traceutil/trace.go:171","msg":"trace[717264021] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"243.202379ms","start":"2026-04-09T02:05:55.698211Z","end":"2026-04-09T02:05:55.941414Z","steps":["trace[717264021] 'process raft request' (duration: 242.692859ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:55.941574Z","caller":"traceutil/trace.go:171","msg":"trace[278229269] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"233.985604ms","start":"2026-04-09T02:05:55.707579Z","end":"2026-04-09T02:05:55.941565Z","steps":["trace[278229269] 'process raft request' (duration: 233.410711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:56.812364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.936984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:05:56.81259Z","caller":"traceutil/trace.go:171","msg":"trace[234231577] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"158.237147ms","start":"2026-04-09T02:05:56.654338Z","end":"2026-04-09T02:05:56.812576Z","steps":["trace[234231577] 'process raft request' (duration: 158.179906ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:56.812698Z","caller":"traceutil/trace.go:171","msg":"trace[1726482252] linearizableReadLoop","detail":"{readStateIndex:4788; appliedIndex:4787; }","duration":"327.618512ms","start":"2026-04-09T02:05:56.485054Z","end":"2026-04-09T02:05:56.812673Z","steps":["trace[1726482252] 'read index received' (duration: 91.231967ms)","trace[1726482252] 'applied index is now lower than readState.Index' (duration: 236.383825ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:56.812751Z","caller":"traceutil/trace.go:171","msg":"trace[832104251] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"329.771301ms","start":"2026-04-09T02:05:56.482932Z","end":"2026-04-09T02:05:56.812703Z","steps":["trace[832104251] 'process raft request' (duration: 93.426737ms)","trace[832104251] 'compare' (duration: 235.563446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:56.81287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.774625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:56.812951Z","caller":"traceutil/trace.go:171","msg":"trace[1164220037] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4613; }","duration":"327.868658ms","start":"2026-04-09T02:05:56.48504Z","end":"2026-04-09T02:05:56.812909Z","steps":["trace[1164220037] 'agreement among raft nodes before linearized reading' (duration: 327.759866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:56.813027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:56.485018Z","time spent":"327.97079ms","remote":"127.0.0.1:37994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" "} {"level":"warn","ts":"2026-04-09T02:05:56.813153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.08207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bf2bac48-3499-4a5f-8bbc-4a3daddb8b2d\" ","response":"range_response_count:1 size:4081"} {"level":"info","ts":"2026-04-09T02:05:56.813645Z","caller":"traceutil/trace.go:171","msg":"trace[897478450] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bf2bac48-3499-4a5f-8bbc-4a3daddb8b2d; range_end:; response_count:1; response_revision:4613; }","duration":"107.593391ms","start":"2026-04-09T02:05:56.706036Z","end":"2026-04-09T02:05:56.81363Z","steps":["trace[897478450] 'agreement among raft nodes before linearized reading' (duration: 106.932876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:56.813324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.949066ms","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-04-09T02:05:56.813873Z","caller":"traceutil/trace.go:171","msg":"trace[834847902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4613; }","duration":"205.55438ms","start":"2026-04-09T02:05:56.608289Z","end":"2026-04-09T02:05:56.813843Z","steps":["trace[834847902] 'agreement among raft nodes before linearized reading' (duration: 204.837594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:56.81334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.120635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:56.813979Z","caller":"traceutil/trace.go:171","msg":"trace[1243140050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4613; }","duration":"208.826512ms","start":"2026-04-09T02:05:56.605137Z","end":"2026-04-09T02:05:56.813963Z","steps":["trace[1243140050] 'agreement among raft nodes before linearized reading' (duration: 207.677026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:56.814122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:56.482906Z","time spent":"330.000845ms","remote":"127.0.0.1:37980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:05:56.813505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.430208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8afc3e49-871b-4df5-98cc-8ab270f0a5f1\" ","response":"range_response_count:1 size:4067"} {"level":"info","ts":"2026-04-09T02:05:56.814373Z","caller":"traceutil/trace.go:171","msg":"trace[967516400] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8afc3e49-871b-4df5-98cc-8ab270f0a5f1; range_end:; response_count:1; response_revision:4613; }","duration":"108.348927ms","start":"2026-04-09T02:05:56.70601Z","end":"2026-04-09T02:05:56.814359Z","steps":["trace[967516400] 'agreement among raft nodes before linearized reading' (duration: 107.07583ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:56.966767Z","caller":"traceutil/trace.go:171","msg":"trace[1257917828] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"117.41083ms","start":"2026-04-09T02:05:56.849328Z","end":"2026-04-09T02:05:56.966739Z","steps":["trace[1257917828] 'process raft request' (duration: 67.48193ms)","trace[1257917828] 'compare' (duration: 49.818137ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:56.971292Z","caller":"traceutil/trace.go:171","msg":"trace[1974808826] linearizableReadLoop","detail":"{readStateIndex:4792; appliedIndex:4790; }","duration":"120.524029ms","start":"2026-04-09T02:05:56.850723Z","end":"2026-04-09T02:05:56.971247Z","steps":["trace[1974808826] 'read index received' (duration: 66.097909ms)","trace[1974808826] 'applied index is now lower than readState.Index' (duration: 54.42505ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:56.971366Z","caller":"traceutil/trace.go:171","msg":"trace[183697490] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"121.972052ms","start":"2026-04-09T02:05:56.849339Z","end":"2026-04-09T02:05:56.971311Z","steps":["trace[183697490] 'process raft request' (duration: 121.801707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:56.971527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.790226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-09T02:05:56.971587Z","caller":"traceutil/trace.go:171","msg":"trace[387698727] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4616; }","duration":"120.882537ms","start":"2026-04-09T02:05:56.850691Z","end":"2026-04-09T02:05:56.971574Z","steps":["trace[387698727] 'agreement among raft nodes before linearized reading' (duration: 120.703574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:57.897089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.581131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-04-09T02:05:57.897161Z","caller":"traceutil/trace.go:171","msg":"trace[2090356677] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4635; }","duration":"377.686624ms","start":"2026-04-09T02:05:57.519453Z","end":"2026-04-09T02:05:57.897139Z","steps":["trace[2090356677] 'range keys from in-memory index tree' (duration: 377.423818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:57.897206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:57.51943Z","time spent":"377.763936ms","remote":"127.0.0.1:38124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1252,"request content":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"info","ts":"2026-04-09T02:05:57.900834Z","caller":"traceutil/trace.go:171","msg":"trace[712137085] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"285.775176ms","start":"2026-04-09T02:05:57.615037Z","end":"2026-04-09T02:05:57.900812Z","steps":["trace[712137085] 'process raft request' (duration: 285.555971ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:57.901241Z","caller":"traceutil/trace.go:171","msg":"trace[929208287] linearizableReadLoop","detail":"{readStateIndex:4813; appliedIndex:4811; }","duration":"200.209678ms","start":"2026-04-09T02:05:57.701014Z","end":"2026-04-09T02:05:57.901223Z","steps":["trace[929208287] 'read index received' (duration: 199.557974ms)","trace[929208287] 'applied index is now lower than readState.Index' (duration: 650.764µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:57.901467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.827513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:05:57.901507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.477914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-09T02:05:57.901516Z","caller":"traceutil/trace.go:171","msg":"trace[1199681629] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4637; }","duration":"182.912795ms","start":"2026-04-09T02:05:57.718589Z","end":"2026-04-09T02:05:57.901502Z","steps":["trace[1199681629] 'agreement among raft nodes before linearized reading' (duration: 182.827363ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:57.901534Z","caller":"traceutil/trace.go:171","msg":"trace[1659884321] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4637; }","duration":"200.543306ms","start":"2026-04-09T02:05:57.700982Z","end":"2026-04-09T02:05:57.901525Z","steps":["trace[1659884321] 'agreement among raft nodes before linearized reading' (duration: 200.435993ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:57.901703Z","caller":"traceutil/trace.go:171","msg":"trace[1237813994] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"243.022369ms","start":"2026-04-09T02:05:57.658673Z","end":"2026-04-09T02:05:57.901695Z","steps":["trace[1237813994] 'process raft request' (duration: 242.457116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:57.90179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.915597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:05:57.901835Z","caller":"traceutil/trace.go:171","msg":"trace[649928077] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4637; }","duration":"132.984779ms","start":"2026-04-09T02:05:57.768838Z","end":"2026-04-09T02:05:57.901823Z","steps":["trace[649928077] 'agreement among raft nodes before linearized reading' (duration: 132.911447ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:02.068678Z","caller":"traceutil/trace.go:171","msg":"trace[1784399105] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"133.062218ms","start":"2026-04-09T02:06:01.935598Z","end":"2026-04-09T02:06:02.06866Z","steps":["trace[1784399105] 'process raft request' (duration: 132.919844ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:02.072617Z","caller":"traceutil/trace.go:171","msg":"trace[891483921] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"117.510736ms","start":"2026-04-09T02:06:01.955098Z","end":"2026-04-09T02:06:02.072609Z","steps":["trace[891483921] 'process raft request' (duration: 117.140809ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:04.234877Z","caller":"traceutil/trace.go:171","msg":"trace[363015818] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"160.384385ms","start":"2026-04-09T02:06:04.074462Z","end":"2026-04-09T02:06:04.234846Z","steps":["trace[363015818] 'process raft request' (duration: 160.058488ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:04.234855Z","caller":"traceutil/trace.go:171","msg":"trace[1883234027] linearizableReadLoop","detail":"{readStateIndex:4922; appliedIndex:4921; }","duration":"156.937641ms","start":"2026-04-09T02:06:04.077853Z","end":"2026-04-09T02:06:04.234791Z","steps":["trace[1883234027] 'read index received' (duration: 156.623764ms)","trace[1883234027] 'applied index is now lower than readState.Index' (duration: 312.597µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:04.235344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.469462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-09T02:06:04.235407Z","caller":"traceutil/trace.go:171","msg":"trace[92993014] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4743; }","duration":"157.568013ms","start":"2026-04-09T02:06:04.077825Z","end":"2026-04-09T02:06:04.235393Z","steps":["trace[92993014] 'agreement among raft nodes before linearized reading' (duration: 157.178396ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:04.352281Z","caller":"traceutil/trace.go:171","msg":"trace[349177643] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"274.273146ms","start":"2026-04-09T02:06:04.077979Z","end":"2026-04-09T02:06:04.352253Z","steps":["trace[349177643] 'process raft request' (duration: 235.355239ms)","trace[349177643] 'compare' (duration: 38.451568ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:04.35249Z","caller":"traceutil/trace.go:171","msg":"trace[712211067] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4922; }","duration":"117.508881ms","start":"2026-04-09T02:06:04.234958Z","end":"2026-04-09T02:06:04.352467Z","steps":["trace[712211067] 'read index received' (duration: 78.388488ms)","trace[712211067] 'applied index is now lower than readState.Index' (duration: 39.088222ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:04.352645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.647486ms","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-04-09T02:06:04.352819Z","caller":"traceutil/trace.go:171","msg":"trace[787299473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4745; }","duration":"269.85335ms","start":"2026-04-09T02:06:04.082948Z","end":"2026-04-09T02:06:04.352802Z","steps":["trace[787299473] 'agreement among raft nodes before linearized reading' (duration: 269.514553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:04.352912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.705222ms","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-04-09T02:06:04.353014Z","caller":"traceutil/trace.go:171","msg":"trace[78515701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4745; }","duration":"272.894426ms","start":"2026-04-09T02:06:04.080098Z","end":"2026-04-09T02:06:04.352993Z","steps":["trace[78515701] 'agreement among raft nodes before linearized reading' (duration: 272.556819ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:04.353697Z","caller":"traceutil/trace.go:171","msg":"trace[1979771706] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"113.36672ms","start":"2026-04-09T02:06:04.240306Z","end":"2026-04-09T02:06:04.353672Z","steps":["trace[1979771706] 'process raft request' (duration: 111.919789ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:04.653758Z","caller":"traceutil/trace.go:171","msg":"trace[2008979916] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"116.737914ms","start":"2026-04-09T02:06:04.537003Z","end":"2026-04-09T02:06:04.653741Z","steps":["trace[2008979916] 'process raft request' (duration: 116.463548ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:04.653729Z","caller":"traceutil/trace.go:171","msg":"trace[1270625457] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"116.958769ms","start":"2026-04-09T02:06:04.536724Z","end":"2026-04-09T02:06:04.653683Z","steps":["trace[1270625457] 'process raft request' (duration: 116.5694ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:04.653993Z","caller":"traceutil/trace.go:171","msg":"trace[854443365] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"115.67422ms","start":"2026-04-09T02:06:04.538295Z","end":"2026-04-09T02:06:04.653969Z","steps":["trace[854443365] 'process raft request' (duration: 115.563238ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:04.654061Z","caller":"traceutil/trace.go:171","msg":"trace[2047977139] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"116.980079ms","start":"2026-04-09T02:06:04.537055Z","end":"2026-04-09T02:06:04.654035Z","steps":["trace[2047977139] 'process raft request' (duration: 116.592281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:04.988253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.788821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:04.988342Z","caller":"traceutil/trace.go:171","msg":"trace[1883985048] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4758; }","duration":"186.927664ms","start":"2026-04-09T02:06:04.80139Z","end":"2026-04-09T02:06:04.988318Z","steps":["trace[1883985048] 'count revisions from in-memory index tree' (duration: 186.662058ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:05.024934Z","caller":"traceutil/trace.go:171","msg":"trace[556084296] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4937; }","duration":"144.352124ms","start":"2026-04-09T02:06:04.880558Z","end":"2026-04-09T02:06:05.02491Z","steps":["trace[556084296] 'read index received' (duration: 144.220382ms)","trace[556084296] 'applied index is now lower than readState.Index' (duration: 130.752µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:05.024982Z","caller":"traceutil/trace.go:171","msg":"trace[617454101] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"172.698782ms","start":"2026-04-09T02:06:04.852255Z","end":"2026-04-09T02:06:05.024954Z","steps":["trace[617454101] 'process raft request' (duration: 172.470028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:05.025235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.655251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-09T02:06:05.025264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.576047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f9229bc-8cfd-411e-9a61-221f819645b2\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-09T02:06:05.02528Z","caller":"traceutil/trace.go:171","msg":"trace[422924226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4759; }","duration":"144.743794ms","start":"2026-04-09T02:06:04.880525Z","end":"2026-04-09T02:06:05.025269Z","steps":["trace[422924226] 'agreement among raft nodes before linearized reading' (duration: 144.554839ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:05.025337Z","caller":"traceutil/trace.go:171","msg":"trace[1739858557] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f9229bc-8cfd-411e-9a61-221f819645b2; range_end:; response_count:1; response_revision:4759; }","duration":"120.70151ms","start":"2026-04-09T02:06:04.904619Z","end":"2026-04-09T02:06:05.02532Z","steps":["trace[1739858557] 'agreement among raft nodes before linearized reading' (duration: 120.510326ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:06.544801Z","caller":"traceutil/trace.go:171","msg":"trace[389972080] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4957; }","duration":"100.262674ms","start":"2026-04-09T02:06:06.444519Z","end":"2026-04-09T02:06:06.544782Z","steps":["trace[389972080] 'read index received' (duration: 71.262876ms)","trace[389972080] 'applied index is now lower than readState.Index' (duration: 28.999098ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:06.544882Z","caller":"traceutil/trace.go:171","msg":"trace[1373649078] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"108.162725ms","start":"2026-04-09T02:06:06.436705Z","end":"2026-04-09T02:06:06.544868Z","steps":["trace[1373649078] 'process raft request' (duration: 99.598029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:06.544981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.453027ms","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-04-09T02:06:06.545006Z","caller":"traceutil/trace.go:171","msg":"trace[1810077832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4780; }","duration":"100.508039ms","start":"2026-04-09T02:06:06.44449Z","end":"2026-04-09T02:06:06.544999Z","steps":["trace[1810077832] 'agreement among raft nodes before linearized reading' (duration: 100.361926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:06.86598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.121816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-fwxgt\" ","response":"range_response_count:1 size:5147"} {"level":"info","ts":"2026-04-09T02:06:06.866076Z","caller":"traceutil/trace.go:171","msg":"trace[114861293] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-fwxgt; range_end:; response_count:1; response_revision:4781; }","duration":"186.257849ms","start":"2026-04-09T02:06:06.679794Z","end":"2026-04-09T02:06:06.866052Z","steps":["trace[114861293] 'range keys from in-memory index tree' (duration: 185.918922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:06.866162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.185092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:06:06.866214Z","caller":"traceutil/trace.go:171","msg":"trace[703827229] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4781; }","duration":"124.276364ms","start":"2026-04-09T02:06:06.741923Z","end":"2026-04-09T02:06:06.866199Z","steps":["trace[703827229] 'count revisions from in-memory index tree' (duration: 124.111741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:06.866353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.676204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8hmvs.18a48e10e823e78e\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-09T02:06:06.86642Z","caller":"traceutil/trace.go:171","msg":"trace[1637071846] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8hmvs.18a48e10e823e78e; range_end:; response_count:1; response_revision:4781; }","duration":"201.793687ms","start":"2026-04-09T02:06:06.66461Z","end":"2026-04-09T02:06:06.866403Z","steps":["trace[1637071846] 'range keys from in-memory index tree' (duration: 201.49445ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:13.234728Z","caller":"traceutil/trace.go:171","msg":"trace[2036335661] linearizableReadLoop","detail":"{readStateIndex:5071; appliedIndex:5070; }","duration":"161.356983ms","start":"2026-04-09T02:06:13.073357Z","end":"2026-04-09T02:06:13.234714Z","steps":["trace[2036335661] 'read index received' (duration: 161.22557ms)","trace[2036335661] 'applied index is now lower than readState.Index' (duration: 130.673µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:13.234793Z","caller":"traceutil/trace.go:171","msg":"trace[1364944213] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"188.558673ms","start":"2026-04-09T02:06:13.046203Z","end":"2026-04-09T02:06:13.234762Z","steps":["trace[1364944213] 'process raft request' (duration: 188.376329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:13.234878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.505215ms","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-04-09T02:06:13.234902Z","caller":"traceutil/trace.go:171","msg":"trace[279634668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4887; }","duration":"161.544056ms","start":"2026-04-09T02:06:13.073352Z","end":"2026-04-09T02:06:13.234896Z","steps":["trace[279634668] 'agreement among raft nodes before linearized reading' (duration: 161.455024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:17.807967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811134729085433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:06:18.21771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.858164Z","time spent":"359.537271ms","remote":"127.0.0.1:38482","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-09T02:06:18.218164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.875638Z","time spent":"342.5233ms","remote":"127.0.0.1:38498","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-09T02:06:18.219647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.25064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:06:18.21977Z","caller":"traceutil/trace.go:171","msg":"trace[1575300388] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"968.823359ms","start":"2026-04-09T02:06:17.250911Z","end":"2026-04-09T02:06:18.219734Z","steps":["trace[1575300388] 'process raft request' (duration: 835.386675ms)","trace[1575300388] 'compare' (duration: 131.665067ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:18.219742Z","caller":"traceutil/trace.go:171","msg":"trace[1211361155] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"912.126831ms","start":"2026-04-09T02:06:17.307597Z","end":"2026-04-09T02:06:18.219723Z","steps":["trace[1211361155] 'read index received' (duration: 778.745618ms)","trace[1211361155] 'applied index is now lower than readState.Index' (duration: 133.379683ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:18.219953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.250863Z","time spent":"968.989704ms","remote":"127.0.0.1:38182","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-04-09T02:06:18.220203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.747993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-04-09T02:06:18.220192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.584621ms","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-04-09T02:06:18.220246Z","caller":"traceutil/trace.go:171","msg":"trace[2097585459] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4926; }","duration":"867.844505ms","start":"2026-04-09T02:06:17.35239Z","end":"2026-04-09T02:06:18.220235Z","steps":["trace[2097585459] 'agreement among raft nodes before linearized reading' (duration: 867.636301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.220348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.35236Z","time spent":"867.977927ms","remote":"127.0.0.1:38104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T02:06:18.220729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.124489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:18.220768Z","caller":"traceutil/trace.go:171","msg":"trace[2061844544] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4926; }","duration":"506.2033ms","start":"2026-04-09T02:06:17.714553Z","end":"2026-04-09T02:06:18.220757Z","steps":["trace[2061844544] 'agreement among raft nodes before linearized reading' (duration: 506.138459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.220796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.714529Z","time spent":"506.258522ms","remote":"127.0.0.1:55756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:06:18.221061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.757654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-09T02:06:18.221141Z","caller":"traceutil/trace.go:171","msg":"trace[138424320] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4926; }","duration":"403.875327ms","start":"2026-04-09T02:06:17.817247Z","end":"2026-04-09T02:06:18.221123Z","steps":["trace[138424320] 'agreement among raft nodes before linearized reading' (duration: 403.660062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.221242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.817223Z","time spent":"403.993109ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-04-09T02:06:18.220348Z","caller":"traceutil/trace.go:171","msg":"trace[1449846747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4926; }","duration":"912.709924ms","start":"2026-04-09T02:06:17.307549Z","end":"2026-04-09T02:06:18.220259Z","steps":["trace[1449846747] 'agreement among raft nodes before linearized reading' (duration: 912.345896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.22241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.307515Z","time spent":"914.877049ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:06:18.22075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.072998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-09T02:06:18.222662Z","caller":"traceutil/trace.go:171","msg":"trace[1940896407] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4926; }","duration":"499.020398ms","start":"2026-04-09T02:06:17.723627Z","end":"2026-04-09T02:06:18.222647Z","steps":["trace[1940896407] 'agreement among raft nodes before linearized reading' (duration: 496.565636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.222724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.723616Z","time spent":"499.095361ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-09T02:06:18.221249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.158153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-09T02:06:18.222988Z","caller":"traceutil/trace.go:171","msg":"trace[1203135172] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4926; }","duration":"668.92764ms","start":"2026-04-09T02:06:17.554034Z","end":"2026-04-09T02:06:18.222961Z","steps":["trace[1203135172] 'agreement among raft nodes before linearized reading' (duration: 667.120442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.223175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.554015Z","time spent":"669.144645ms","remote":"127.0.0.1:48356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-09T02:06:18.223354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.186073ms","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-04-09T02:06:18.223383Z","caller":"traceutil/trace.go:171","msg":"trace[339498147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4926; }","duration":"763.267026ms","start":"2026-04-09T02:06:17.46011Z","end":"2026-04-09T02:06:18.223377Z","steps":["trace[339498147] 'agreement among raft nodes before linearized reading' (duration: 761.166632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.2234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.460084Z","time spent":"763.312056ms","remote":"127.0.0.1:38182","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-04-09T02:06:18.236715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.699973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:06:18.236831Z","caller":"traceutil/trace.go:171","msg":"trace[2033959406] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:4926; }","duration":"508.865936ms","start":"2026-04-09T02:06:17.727926Z","end":"2026-04-09T02:06:18.236792Z","steps":["trace[2033959406] 'agreement among raft nodes before linearized reading' (duration: 493.40524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.236879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:06:17.727915Z","time spent":"508.946568ms","remote":"127.0.0.1:38084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":37,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:06:18.379741Z","caller":"traceutil/trace.go:171","msg":"trace[1403775145] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"143.555088ms","start":"2026-04-09T02:06:18.23615Z","end":"2026-04-09T02:06:18.379705Z","steps":["trace[1403775145] 'process raft request' (duration: 143.289082ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.379844Z","caller":"traceutil/trace.go:171","msg":"trace[1841098548] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"143.418325ms","start":"2026-04-09T02:06:18.236393Z","end":"2026-04-09T02:06:18.379812Z","steps":["trace[1841098548] 'process raft request' (duration: 143.286772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.379939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.691121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-04-09T02:06:18.379753Z","caller":"traceutil/trace.go:171","msg":"trace[1516238417] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5112; }","duration":"109.03156ms","start":"2026-04-09T02:06:18.270698Z","end":"2026-04-09T02:06:18.37973Z","steps":["trace[1516238417] 'read index received' (duration: 108.828216ms)","trace[1516238417] 'applied index is now lower than readState.Index' (duration: 202.274µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:18.379991Z","caller":"traceutil/trace.go:171","msg":"trace[1090275385] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4930; }","duration":"143.807492ms","start":"2026-04-09T02:06:18.236173Z","end":"2026-04-09T02:06:18.37998Z","steps":["trace[1090275385] 'agreement among raft nodes before linearized reading' (duration: 143.630519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.379964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.54747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"info","ts":"2026-04-09T02:06:18.380052Z","caller":"traceutil/trace.go:171","msg":"trace[1397416489] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4930; }","duration":"136.682382ms","start":"2026-04-09T02:06:18.243357Z","end":"2026-04-09T02:06:18.380039Z","steps":["trace[1397416489] 'agreement among raft nodes before linearized reading' (duration: 136.450928ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.380009Z","caller":"traceutil/trace.go:171","msg":"trace[797357167] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"143.66689ms","start":"2026-04-09T02:06:18.236315Z","end":"2026-04-09T02:06:18.379982Z","steps":["trace[797357167] 'process raft request' (duration: 143.299673ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.405213Z","caller":"traceutil/trace.go:171","msg":"trace[970940066] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"111.858479ms","start":"2026-04-09T02:06:18.29333Z","end":"2026-04-09T02:06:18.405188Z","steps":["trace[970940066] 'process raft request' (duration: 111.584484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:18.405621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.443985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:11125"} {"level":"info","ts":"2026-04-09T02:06:18.405685Z","caller":"traceutil/trace.go:171","msg":"trace[2011183841] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4931; }","duration":"133.519786ms","start":"2026-04-09T02:06:18.272149Z","end":"2026-04-09T02:06:18.405669Z","steps":["trace[2011183841] 'agreement among raft nodes before linearized reading' (duration: 133.347463ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.773612Z","caller":"traceutil/trace.go:171","msg":"trace[1719560964] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"141.450354ms","start":"2026-04-09T02:06:18.632142Z","end":"2026-04-09T02:06:18.773592Z","steps":["trace[1719560964] 'process raft request' (duration: 137.584922ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.778148Z","caller":"traceutil/trace.go:171","msg":"trace[1037386682] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"144.968528ms","start":"2026-04-09T02:06:18.633152Z","end":"2026-04-09T02:06:18.77812Z","steps":["trace[1037386682] 'process raft request' (duration: 144.917137ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:18.778205Z","caller":"traceutil/trace.go:171","msg":"trace[1284122477] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"145.655052ms","start":"2026-04-09T02:06:18.632531Z","end":"2026-04-09T02:06:18.778186Z","steps":["trace[1284122477] 'process raft request' (duration: 145.397307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:19.852869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.542407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:19.853058Z","caller":"traceutil/trace.go:171","msg":"trace[313015964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4946; }","duration":"245.797303ms","start":"2026-04-09T02:06:19.607235Z","end":"2026-04-09T02:06:19.853033Z","steps":["trace[313015964] 'range keys from in-memory index tree' (duration: 245.464075ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:29.870973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-04-09T02:07:29.954452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"79.115585ms","hash":2194549973,"current-db-size-bytes":53792768,"current-db-size":"54 MB","current-db-size-in-use-bytes":28774400,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-09T02:07:29.954505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2194549973,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-04-09T02:07:35.40407Z","caller":"traceutil/trace.go:171","msg":"trace[765047325] linearizableReadLoop","detail":"{readStateIndex:5758; appliedIndex:5757; }","duration":"287.892376ms","start":"2026-04-09T02:07:35.116153Z","end":"2026-04-09T02:07:35.404045Z","steps":["trace[765047325] 'read index received' (duration: 287.708722ms)","trace[765047325] 'applied index is now lower than readState.Index' (duration: 182.544µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:35.404385Z","caller":"traceutil/trace.go:171","msg":"trace[330230256] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"335.488109ms","start":"2026-04-09T02:07:35.068885Z","end":"2026-04-09T02:07:35.404373Z","steps":["trace[330230256] 'process raft request' (duration: 335.002599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:35.404495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:07:35.068863Z","time spent":"335.559409ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:07:35.404827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.66656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-09T02:07:35.404877Z","caller":"traceutil/trace.go:171","msg":"trace[393857385] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5554; }","duration":"288.746311ms","start":"2026-04-09T02:07:35.116119Z","end":"2026-04-09T02:07:35.404866Z","steps":["trace[393857385] 'agreement among raft nodes before linearized reading' (duration: 288.604639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:35.405307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.778758ms","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-04-09T02:07:35.40539Z","caller":"traceutil/trace.go:171","msg":"trace[1005808849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5554; }","duration":"174.917752ms","start":"2026-04-09T02:07:35.230453Z","end":"2026-04-09T02:07:35.40537Z","steps":["trace[1005808849] 'agreement among raft nodes before linearized reading' (duration: 174.391152ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:56.9342Z","caller":"traceutil/trace.go:171","msg":"trace[2136048857] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"106.4199ms","start":"2026-04-09T02:07:56.827765Z","end":"2026-04-09T02:07:56.934185Z","steps":["trace[2136048857] 'process raft request' (duration: 106.326109ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:21.627826Z","caller":"traceutil/trace.go:171","msg":"trace[1555555169] linearizableReadLoop","detail":"{readStateIndex:6051; appliedIndex:6050; }","duration":"115.915241ms","start":"2026-04-09T02:08:21.511883Z","end":"2026-04-09T02:08:21.627798Z","steps":["trace[1555555169] 'read index received' (duration: 115.81541ms)","trace[1555555169] 'applied index is now lower than readState.Index' (duration: 98.391µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:21.627905Z","caller":"traceutil/trace.go:171","msg":"trace[59560552] transaction","detail":"{read_only:false; response_revision:5838; number_of_response:1; }","duration":"133.848943ms","start":"2026-04-09T02:08:21.494033Z","end":"2026-04-09T02:08:21.627882Z","steps":["trace[59560552] 'process raft request' (duration: 133.545618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:21.628278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.37589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:08:21.628361Z","caller":"traceutil/trace.go:171","msg":"trace[401661729] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5838; }","duration":"116.502942ms","start":"2026-04-09T02:08:21.511831Z","end":"2026-04-09T02:08:21.628334Z","steps":["trace[401661729] 'agreement among raft nodes before linearized reading' (duration: 116.106485ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:21.670724Z","caller":"traceutil/trace.go:171","msg":"trace[78820717] transaction","detail":"{read_only:false; response_revision:5839; number_of_response:1; }","duration":"104.046906ms","start":"2026-04-09T02:08:21.566645Z","end":"2026-04-09T02:08:21.670692Z","steps":["trace[78820717] 'process raft request' (duration: 103.434496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:21.886563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.236566ms","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-04-09T02:08:21.886667Z","caller":"traceutil/trace.go:171","msg":"trace[648898109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5839; }","duration":"162.384229ms","start":"2026-04-09T02:08:21.724259Z","end":"2026-04-09T02:08:21.886643Z","steps":["trace[648898109] 'range keys from in-memory index tree' (duration: 162.091114ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.003354Z","caller":"traceutil/trace.go:171","msg":"trace[128548919] transaction","detail":"{read_only:false; response_revision:5840; number_of_response:1; }","duration":"112.387831ms","start":"2026-04-09T02:08:21.89094Z","end":"2026-04-09T02:08:22.003327Z","steps":["trace[128548919] 'process raft request' (duration: 112.203167ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.048605Z","caller":"traceutil/trace.go:171","msg":"trace[1102230658] transaction","detail":"{read_only:false; response_revision:5843; number_of_response:1; }","duration":"157.547794ms","start":"2026-04-09T02:08:21.891037Z","end":"2026-04-09T02:08:22.048585Z","steps":["trace[1102230658] 'process raft request' (duration: 157.466922ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.048607Z","caller":"traceutil/trace.go:171","msg":"trace[1322796703] transaction","detail":"{read_only:false; response_revision:5841; number_of_response:1; }","duration":"157.601075ms","start":"2026-04-09T02:08:21.890958Z","end":"2026-04-09T02:08:22.04856Z","steps":["trace[1322796703] 'process raft request' (duration: 157.370052ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.048638Z","caller":"traceutil/trace.go:171","msg":"trace[1593972907] transaction","detail":"{read_only:false; response_revision:5842; number_of_response:1; }","duration":"157.646276ms","start":"2026-04-09T02:08:21.890957Z","end":"2026-04-09T02:08:22.048604Z","steps":["trace[1593972907] 'process raft request' (duration: 157.507824ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:25.993834Z","caller":"traceutil/trace.go:171","msg":"trace[357187941] transaction","detail":"{read_only:false; response_revision:6724; number_of_response:1; }","duration":"177.67286ms","start":"2026-04-09T02:10:25.816119Z","end":"2026-04-09T02:10:25.993792Z","steps":["trace[357187941] 'process raft request' (duration: 177.466786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:10:25.994082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.796746ms","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-04-09T02:10:25.994115Z","caller":"traceutil/trace.go:171","msg":"trace[712200871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6724; }","duration":"132.920287ms","start":"2026-04-09T02:10:25.861187Z","end":"2026-04-09T02:10:25.994107Z","steps":["trace[712200871] 'agreement among raft nodes before linearized reading' (duration: 132.803035ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:25.993969Z","caller":"traceutil/trace.go:171","msg":"trace[1344099036] linearizableReadLoop","detail":"{readStateIndex:6970; appliedIndex:6969; }","duration":"132.483401ms","start":"2026-04-09T02:10:25.861248Z","end":"2026-04-09T02:10:25.993732Z","steps":["trace[1344099036] 'read index received' (duration: 132.248886ms)","trace[1344099036] 'applied index is now lower than readState.Index' (duration: 233.855µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:12:09.993975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.039063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-09T02:12:09.994065Z","caller":"traceutil/trace.go:171","msg":"trace[1269311499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:7277; }","duration":"153.196008ms","start":"2026-04-09T02:12:09.840848Z","end":"2026-04-09T02:12:09.994044Z","steps":["trace[1269311499] 'range keys from in-memory index tree' (duration: 152.860686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:12:13.599402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.675005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:12:13.59952Z","caller":"traceutil/trace.go:171","msg":"trace[1143979052] transaction","detail":"{read_only:false; response_revision:7292; number_of_response:1; }","duration":"264.578024ms","start":"2026-04-09T02:12:13.334909Z","end":"2026-04-09T02:12:13.599487Z","steps":["trace[1143979052] 'process raft request' (duration: 36.735143ms)","trace[1143979052] 'compare' (duration: 227.536279ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:12:13.59991Z","caller":"traceutil/trace.go:171","msg":"trace[2125101416] linearizableReadLoop","detail":"{readStateIndex:7561; appliedIndex:7560; }","duration":"160.658287ms","start":"2026-04-09T02:12:13.439231Z","end":"2026-04-09T02:12:13.599889Z","steps":["trace[2125101416] 'read index received' (duration: 160.425988ms)","trace[2125101416] 'applied index is now lower than readState.Index' (duration: 231.509µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:12:13.59999Z","caller":"traceutil/trace.go:171","msg":"trace[166486942] transaction","detail":"{read_only:false; response_revision:7293; number_of_response:1; }","duration":"261.732128ms","start":"2026-04-09T02:12:13.338231Z","end":"2026-04-09T02:12:13.599963Z","steps":["trace[166486942] 'process raft request' (duration: 261.483539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:12:13.600142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.898906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-09T02:12:13.600193Z","caller":"traceutil/trace.go:171","msg":"trace[217632058] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7293; }","duration":"160.983929ms","start":"2026-04-09T02:12:13.439196Z","end":"2026-04-09T02:12:13.60018Z","steps":["trace[217632058] 'agreement among raft nodes before linearized reading' (duration: 160.828903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:12:13.600207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.157325ms","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-04-09T02:12:13.600255Z","caller":"traceutil/trace.go:171","msg":"trace[1613510503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7293; }","duration":"123.236949ms","start":"2026-04-09T02:12:13.477004Z","end":"2026-04-09T02:12:13.600241Z","steps":["trace[1613510503] 'agreement among raft nodes before linearized reading' (duration: 123.097674ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:12:29.877458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5510} {"level":"info","ts":"2026-04-09T02:12:29.95092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5510,"took":"71.941427ms","hash":4152335755,"current-db-size-bytes":53792768,"current-db-size":"54 MB","current-db-size-in-use-bytes":17956864,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-09T02:12:29.951196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4152335755,"revision":5510,"compact-revision":2866}