{"level":"warn","ts":"2026-05-21T19:33:32.788273Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:33:32.788396Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.44:2380","--initial-cluster=instance=https://199.19.213.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.44:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T19:33:32.788482Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:33:32.788497Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-05-21T19:33:32.788518Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T19:33:32.789239Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"]} {"level":"info","ts":"2026-05-21T19:33:32.789317Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.44:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T19:33:32.793195Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.619775ms"} {"level":"info","ts":"2026-05-21T19:33:32.800914Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4f2979fc0f638003","cluster-id":"ce06e7bc3b23612b"} {"level":"info","ts":"2026-05-21T19:33:32.800995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T19:33:32.801021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 0"} {"level":"info","ts":"2026-05-21T19:33:32.801037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4f2979fc0f638003 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T19:33:32.801046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 1"} {"level":"info","ts":"2026-05-21T19:33:32.801091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"warn","ts":"2026-05-21T19:33:32.806149Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T19:33:32.808549Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T19:33:32.810148Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T19:33:32.812544Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4f2979fc0f638003","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T19:33:32.812889Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4f2979fc0f638003","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T19:33:32.812904Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:33:32.813162Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:33:32.813189Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T19:33:32.813426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"info","ts":"2026-05-21T19:33:32.813616Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","added-peer-id":"4f2979fc0f638003","added-peer-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-05-21T19:33:32.815572Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T19:33:32.815766Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-05-21T19:33:32.815854Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-05-21T19:33:32.815881Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4f2979fc0f638003","initial-advertise-peer-urls":["https://199.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T19:33:32.815939Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T19:33:33.101284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T19:33:33.101361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T19:33:33.101416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgPreVoteResp from 4f2979fc0f638003 at term 1"} {"level":"info","ts":"2026-05-21T19:33:33.101437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became candidate at term 2"} {"level":"info","ts":"2026-05-21T19:33:33.101449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgVoteResp from 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-05-21T19:33:33.101466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became leader at term 2"} {"level":"info","ts":"2026-05-21T19:33:33.101485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4f2979fc0f638003 elected leader 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-05-21T19:33:33.103432Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:33:33.104126Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4f2979fc0f638003","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.44:2379]}","request-path":"/0/members/4f2979fc0f638003/attributes","cluster-id":"ce06e7bc3b23612b","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T19:33:33.104292Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:33:33.104384Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:33:33.104423Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:33:33.104424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:33:33.104471Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:33:33.104537Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T19:33:33.104623Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T19:33:33.106304Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.44:2379"} {"level":"info","ts":"2026-05-21T19:33:33.107812Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-21T19:33:39.833837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.013348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-21T19:33:39.833982Z","caller":"traceutil/trace.go:171","msg":"trace[608674497] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:285; }","duration":"179.218754ms","start":"2026-05-21T19:33:39.654722Z","end":"2026-05-21T19:33:39.833941Z","steps":["trace[608674497] 'range keys from in-memory index tree' (duration: 178.893115ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:55.323466Z","caller":"traceutil/trace.go:171","msg":"trace[2097613740] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"219.893753ms","start":"2026-05-21T19:33:55.103466Z","end":"2026-05-21T19:33:55.32336Z","steps":["trace[2097613740] 'read index received' (duration: 219.73888ms)","trace[2097613740] 'applied index is now lower than readState.Index' (duration: 153.393µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:55.323583Z","caller":"traceutil/trace.go:171","msg":"trace[115727841] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"284.919909ms","start":"2026-05-21T19:33:55.038618Z","end":"2026-05-21T19:33:55.323537Z","steps":["trace[115727841] 'process raft request' (duration: 284.56366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:55.323684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.193701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T19:33:55.323735Z","caller":"traceutil/trace.go:171","msg":"trace[1389095860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:387; }","duration":"220.322765ms","start":"2026-05-21T19:33:55.103399Z","end":"2026-05-21T19:33:55.323722Z","steps":["trace[1389095860] 'agreement among raft nodes before linearized reading' (duration: 220.198752ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:55.37885Z","caller":"traceutil/trace.go:171","msg":"trace[1177182712] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"268.680105ms","start":"2026-05-21T19:33:55.110145Z","end":"2026-05-21T19:33:55.378825Z","steps":["trace[1177182712] 'process raft request' (duration: 268.549022ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:13.176558Z","caller":"traceutil/trace.go:171","msg":"trace[1650747225] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"155.801322ms","start":"2026-05-21T19:34:13.020722Z","end":"2026-05-21T19:34:13.176523Z","steps":["trace[1650747225] 'read index received' (duration: 155.599647ms)","trace[1650747225] 'applied index is now lower than readState.Index' (duration: 200.105µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:13.176646Z","caller":"traceutil/trace.go:171","msg":"trace[1884368914] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"191.707478ms","start":"2026-05-21T19:34:12.98489Z","end":"2026-05-21T19:34:13.176597Z","steps":["trace[1884368914] 'process raft request' (duration: 191.459931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:13.176784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.018788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:13.176903Z","caller":"traceutil/trace.go:171","msg":"trace[1126638346] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:493; }","duration":"156.210743ms","start":"2026-05-21T19:34:13.020668Z","end":"2026-05-21T19:34:13.176879Z","steps":["trace[1126638346] 'agreement among raft nodes before linearized reading' (duration: 156.003097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:13.436247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.625039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:13.43636Z","caller":"traceutil/trace.go:171","msg":"trace[848558780] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:493; }","duration":"142.813934ms","start":"2026-05-21T19:34:13.293521Z","end":"2026-05-21T19:34:13.436335Z","steps":["trace[848558780] 'range keys from in-memory index tree' (duration: 142.422173ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:19.913857Z","caller":"traceutil/trace.go:171","msg":"trace[2105019699] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"106.433155ms","start":"2026-05-21T19:34:19.80739Z","end":"2026-05-21T19:34:19.913823Z","steps":["trace[2105019699] 'process raft request' (duration: 105.633715ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:20.665929Z","caller":"traceutil/trace.go:171","msg":"trace[38301125] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"198.971948ms","start":"2026-05-21T19:34:20.46689Z","end":"2026-05-21T19:34:20.665862Z","steps":["trace[38301125] 'read index received' (duration: 194.204943ms)","trace[38301125] 'applied index is now lower than readState.Index' (duration: 4.764784ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:20.666005Z","caller":"traceutil/trace.go:171","msg":"trace[466577263] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"203.65013ms","start":"2026-05-21T19:34:20.462191Z","end":"2026-05-21T19:34:20.665841Z","steps":["trace[466577263] 'process raft request' (duration: 201.34625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:20.666107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.202723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-21T19:34:20.666142Z","caller":"traceutil/trace.go:171","msg":"trace[741908692] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:603; }","duration":"199.300436ms","start":"2026-05-21T19:34:20.466831Z","end":"2026-05-21T19:34:20.666132Z","steps":["trace[741908692] 'agreement among raft nodes before linearized reading' (duration: 199.188933ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:20.970145Z","caller":"traceutil/trace.go:171","msg":"trace[1896592118] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"232.105581ms","start":"2026-05-21T19:34:20.738007Z","end":"2026-05-21T19:34:20.970113Z","steps":["trace[1896592118] 'process raft request' (duration: 193.793343ms)","trace[1896592118] 'compare' (duration: 38.041071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:21.543923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.342935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:34:21.54412Z","caller":"traceutil/trace.go:171","msg":"trace[1664964301] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"559.339544ms","start":"2026-05-21T19:34:20.984756Z","end":"2026-05-21T19:34:21.544095Z","steps":["trace[1664964301] 'read index received' (duration: 119.025323ms)","trace[1664964301] 'applied index is now lower than readState.Index' (duration: 440.311831ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:21.544218Z","caller":"traceutil/trace.go:171","msg":"trace[1335905458] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"568.104292ms","start":"2026-05-21T19:34:20.976079Z","end":"2026-05-21T19:34:21.544184Z","steps":["trace[1335905458] 'process raft request' (duration: 127.782702ms)","trace[1335905458] 'compare' (duration: 439.14813ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:21.544321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.525369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:34:21.544357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:20.976046Z","time spent":"568.248137ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:21.544444Z","caller":"traceutil/trace.go:171","msg":"trace[769352049] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:605; }","duration":"559.711294ms","start":"2026-05-21T19:34:20.984702Z","end":"2026-05-21T19:34:21.544413Z","steps":["trace[769352049] 'agreement among raft nodes before linearized reading' (duration: 559.500209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:21.544473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.084282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-54rj6\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-21T19:34:21.544529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:20.984681Z","time spent":"559.830067ms","remote":"127.0.0.1:54106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-21T19:34:21.544575Z","caller":"traceutil/trace.go:171","msg":"trace[1242365872] range","detail":"{range_begin:/registry/pods/kube-system/cilium-54rj6; range_end:; response_count:1; response_revision:605; }","duration":"400.247636ms","start":"2026-05-21T19:34:21.144296Z","end":"2026-05-21T19:34:21.544544Z","steps":["trace[1242365872] 'agreement among raft nodes before linearized reading' (duration: 399.940948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:21.544659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.144273Z","time spent":"400.37133ms","remote":"127.0.0.1:54050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-54rj6\" "} {"level":"warn","ts":"2026-05-21T19:34:21.544638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.553505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:34:21.544757Z","caller":"traceutil/trace.go:171","msg":"trace[280624606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"331.764911ms","start":"2026-05-21T19:34:21.212965Z","end":"2026-05-21T19:34:21.54473Z","steps":["trace[280624606] 'agreement among raft nodes before linearized reading' (duration: 331.295849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:21.544835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.212922Z","time spent":"331.902244ms","remote":"127.0.0.1:54130","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-05-21T19:34:21.810873Z","caller":"traceutil/trace.go:171","msg":"trace[1043128947] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"216.248468ms","start":"2026-05-21T19:34:21.594594Z","end":"2026-05-21T19:34:21.810843Z","steps":["trace[1043128947] 'read index received' (duration: 215.976651ms)","trace[1043128947] 'applied index is now lower than readState.Index' (duration: 270.727µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:21.810948Z","caller":"traceutil/trace.go:171","msg":"trace[2024154323] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"249.789892ms","start":"2026-05-21T19:34:21.561121Z","end":"2026-05-21T19:34:21.810911Z","steps":["trace[2024154323] 'process raft request' (duration: 249.488814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:21.811064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.471265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:34:21.811103Z","caller":"traceutil/trace.go:171","msg":"trace[315985441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"216.576507ms","start":"2026-05-21T19:34:21.594516Z","end":"2026-05-21T19:34:21.811093Z","steps":["trace[315985441] 'agreement among raft nodes before linearized reading' (duration: 216.469875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:21.811192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.86019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:21.811241Z","caller":"traceutil/trace.go:171","msg":"trace[1452301295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"179.988033ms","start":"2026-05-21T19:34:21.63124Z","end":"2026-05-21T19:34:21.811228Z","steps":["trace[1452301295] 'agreement among raft nodes before linearized reading' (duration: 179.778217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:22.446281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224390511163475473,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T19:34:22.613317Z","caller":"traceutil/trace.go:171","msg":"trace[1652720719] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"667.72118ms","start":"2026-05-21T19:34:21.945556Z","end":"2026-05-21T19:34:22.613277Z","steps":["trace[1652720719] 'read index received' (duration: 651.201809ms)","trace[1652720719] 'applied index is now lower than readState.Index' (duration: 16.518161ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:22.613376Z","caller":"traceutil/trace.go:171","msg":"trace[1321155376] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"651.165218ms","start":"2026-05-21T19:34:21.962174Z","end":"2026-05-21T19:34:22.613339Z","steps":["trace[1321155376] 'process raft request' (duration: 650.502621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:22.613509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.924235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:22.613556Z","caller":"traceutil/trace.go:171","msg":"trace[304515091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"668.051538ms","start":"2026-05-21T19:34:21.945491Z","end":"2026-05-21T19:34:22.613542Z","steps":["trace[304515091] 'agreement among raft nodes before linearized reading' (duration: 667.940645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:22.613598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.945465Z","time spent":"668.12421ms","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:34:22.613598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.96213Z","time spent":"651.346273ms","remote":"127.0.0.1:53972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:38.066365Z","caller":"traceutil/trace.go:171","msg":"trace[1142745525] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"427.404174ms","start":"2026-05-21T19:34:37.638929Z","end":"2026-05-21T19:34:38.066333Z","steps":["trace[1142745525] 'read index received' (duration: 427.161998ms)","trace[1142745525] 'applied index is now lower than readState.Index' (duration: 241.026µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:38.066454Z","caller":"traceutil/trace.go:171","msg":"trace[1241008004] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"448.615906ms","start":"2026-05-21T19:34:37.61779Z","end":"2026-05-21T19:34:38.066406Z","steps":["trace[1241008004] 'process raft request' (duration: 448.37876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.066677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.617753Z","time spent":"448.800792ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:38.066705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.760493ms","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-05-21T19:34:38.06672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.154891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:38.066747Z","caller":"traceutil/trace.go:171","msg":"trace[1339054471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"427.879066ms","start":"2026-05-21T19:34:37.638856Z","end":"2026-05-21T19:34:38.066735Z","steps":["trace[1339054471] 'agreement among raft nodes before linearized reading' (duration: 427.678441ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:38.066799Z","caller":"traceutil/trace.go:171","msg":"trace[210136906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"118.283284ms","start":"2026-05-21T19:34:37.948499Z","end":"2026-05-21T19:34:38.066782Z","steps":["trace[210136906] 'agreement among raft nodes before linearized reading' (duration: 118.172721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.066808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:37.63883Z","time spent":"427.968509ms","remote":"127.0.0.1:54130","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-05-21T19:34:38.066814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.074199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:34:38.066908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.324536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:38.06692Z","caller":"traceutil/trace.go:171","msg":"trace[1794480055] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:769; }","duration":"100.250374ms","start":"2026-05-21T19:34:37.966643Z","end":"2026-05-21T19:34:38.066893Z","steps":["trace[1794480055] 'agreement among raft nodes before linearized reading' (duration: 100.040639ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:38.06697Z","caller":"traceutil/trace.go:171","msg":"trace[1102357238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"166.446929ms","start":"2026-05-21T19:34:37.900509Z","end":"2026-05-21T19:34:38.066956Z","steps":["trace[1102357238] 'agreement among raft nodes before linearized reading' (duration: 166.205743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.066966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.118519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T19:34:38.067037Z","caller":"traceutil/trace.go:171","msg":"trace[1927795813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"169.235862ms","start":"2026-05-21T19:34:37.897779Z","end":"2026-05-21T19:34:38.067015Z","steps":["trace[1927795813] 'agreement among raft nodes before linearized reading' (duration: 169.030587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:38.067295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.814033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:34:38.06736Z","caller":"traceutil/trace.go:171","msg":"trace[1803464393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"173.948376ms","start":"2026-05-21T19:34:37.8934Z","end":"2026-05-21T19:34:38.067349Z","steps":["trace[1803464393] 'agreement among raft nodes before linearized reading' (duration: 173.190216ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:38.281755Z","caller":"traceutil/trace.go:171","msg":"trace[244056085] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"209.893183ms","start":"2026-05-21T19:34:38.071831Z","end":"2026-05-21T19:34:38.281724Z","steps":["trace[244056085] 'process raft request' (duration: 209.699558ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:38.282488Z","caller":"traceutil/trace.go:171","msg":"trace[516539517] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"209.699887ms","start":"2026-05-21T19:34:38.072769Z","end":"2026-05-21T19:34:38.282469Z","steps":["trace[516539517] 'process raft request' (duration: 209.672736ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:38.282737Z","caller":"traceutil/trace.go:171","msg":"trace[1027453578] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"210.607901ms","start":"2026-05-21T19:34:38.072093Z","end":"2026-05-21T19:34:38.282701Z","steps":["trace[1027453578] 'process raft request' (duration: 210.331024ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:38.282756Z","caller":"traceutil/trace.go:171","msg":"trace[1422717052] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"210.701894ms","start":"2026-05-21T19:34:38.072002Z","end":"2026-05-21T19:34:38.282704Z","steps":["trace[1422717052] 'process raft request' (duration: 210.333955ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:44.482824Z","caller":"traceutil/trace.go:171","msg":"trace[1515822398] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"171.128972ms","start":"2026-05-21T19:34:44.311661Z","end":"2026-05-21T19:34:44.48279Z","steps":["trace[1515822398] 'process raft request' (duration: 102.357298ms)","trace[1515822398] 'compare' (duration: 68.576489ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:44.483145Z","caller":"traceutil/trace.go:171","msg":"trace[2055452620] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"171.121741ms","start":"2026-05-21T19:34:44.311985Z","end":"2026-05-21T19:34:44.483107Z","steps":["trace[2055452620] 'process raft request' (duration: 170.755031ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:44.483288Z","caller":"traceutil/trace.go:171","msg":"trace[566842484] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"127.508385ms","start":"2026-05-21T19:34:44.355744Z","end":"2026-05-21T19:34:44.483252Z","steps":["trace[566842484] 'process raft request' (duration: 127.275619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:52.342717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224390511163476141,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:52.479278Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074354513s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:34:52.488097Z","caller":"traceutil/trace.go:171","msg":"trace[56662782] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:958; }","duration":"645.410209ms","start":"2026-05-21T19:34:51.842655Z","end":"2026-05-21T19:34:52.488065Z","steps":["trace[56662782] 'read index received' (duration: 637.163854ms)","trace[56662782] 'applied index is now lower than readState.Index' (duration: 8.245575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:52.48826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.119838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:52.488221Z","caller":"traceutil/trace.go:171","msg":"trace[1287729469] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.004524873s","start":"2026-05-21T19:34:51.483661Z","end":"2026-05-21T19:34:52.488186Z","steps":["trace[1287729469] 'process raft request' (duration: 1.004288067s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:52.488294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.635145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:34:52.488465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.910217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T19:34:52.488508Z","caller":"traceutil/trace.go:171","msg":"trace[1423866109] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:926; }","duration":"645.886842ms","start":"2026-05-21T19:34:51.842591Z","end":"2026-05-21T19:34:52.488477Z","steps":["trace[1423866109] 'agreement among raft nodes before linearized reading' (duration: 645.596424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:52.48834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.178455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:34:52.488567Z","caller":"traceutil/trace.go:171","msg":"trace[881399074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"357.079142ms","start":"2026-05-21T19:34:52.131469Z","end":"2026-05-21T19:34:52.488548Z","steps":["trace[881399074] 'agreement among raft nodes before linearized reading' (duration: 356.783014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:52.488579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:51.842559Z","time spent":"646.002425ms","remote":"127.0.0.1:53948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":97,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:34:52.488587Z","caller":"traceutil/trace.go:171","msg":"trace[1203576659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"102.468463ms","start":"2026-05-21T19:34:52.386102Z","end":"2026-05-21T19:34:52.488571Z","steps":["trace[1203576659] 'agreement among raft nodes before linearized reading' (duration: 102.099422ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:52.488597Z","caller":"traceutil/trace.go:171","msg":"trace[267256578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"539.491838ms","start":"2026-05-21T19:34:51.949054Z","end":"2026-05-21T19:34:52.488545Z","steps":["trace[267256578] 'agreement among raft nodes before linearized reading' (duration: 539.159189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:52.488583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:51.483631Z","time spent":"1.004812121s","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:52.488616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:52.131438Z","time spent":"357.163783ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T19:34:52.488659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:51.949019Z","time spent":"539.615741ms","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:35:08.658882Z","caller":"traceutil/trace.go:171","msg":"trace[943353693] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"106.935158ms","start":"2026-05-21T19:35:08.55192Z","end":"2026-05-21T19:35:08.658855Z","steps":["trace[943353693] 'process raft request' (duration: 106.760244ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:22.851591Z","caller":"traceutil/trace.go:171","msg":"trace[2131058675] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"115.588244ms","start":"2026-05-21T19:35:22.735985Z","end":"2026-05-21T19:35:22.851573Z","steps":["trace[2131058675] 'read index received' (duration: 115.258194ms)","trace[2131058675] 'applied index is now lower than readState.Index' (duration: 329.3µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:35:22.851716Z","caller":"traceutil/trace.go:171","msg":"trace[563913054] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"144.939059ms","start":"2026-05-21T19:35:22.706766Z","end":"2026-05-21T19:35:22.851705Z","steps":["trace[563913054] 'process raft request' (duration: 144.433925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:22.851938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.946122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:35:22.85197Z","caller":"traceutil/trace.go:171","msg":"trace[1767302576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"116.034015ms","start":"2026-05-21T19:35:22.735928Z","end":"2026-05-21T19:35:22.851962Z","steps":["trace[1767302576] 'agreement among raft nodes before linearized reading' (duration: 115.871611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:22.852081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.855652ms","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-05-21T19:35:22.852064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.263664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:35:22.852105Z","caller":"traceutil/trace.go:171","msg":"trace[1880657472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1302; }","duration":"112.882933ms","start":"2026-05-21T19:35:22.739217Z","end":"2026-05-21T19:35:22.8521Z","steps":["trace[1880657472] 'agreement among raft nodes before linearized reading' (duration: 112.824611ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:22.852125Z","caller":"traceutil/trace.go:171","msg":"trace[1937970170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"110.348467ms","start":"2026-05-21T19:35:22.741761Z","end":"2026-05-21T19:35:22.852109Z","steps":["trace[1937970170] 'agreement among raft nodes before linearized reading' (duration: 110.173772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:22.852149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.708414ms","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-05-21T19:35:22.852396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.957001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:35:22.852439Z","caller":"traceutil/trace.go:171","msg":"trace[1157776064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"114.066914ms","start":"2026-05-21T19:35:22.738343Z","end":"2026-05-21T19:35:22.85241Z","steps":["trace[1157776064] 'agreement among raft nodes before linearized reading' (duration: 113.510619ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:22.852518Z","caller":"traceutil/trace.go:171","msg":"trace[452453999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"114.146316ms","start":"2026-05-21T19:35:22.738341Z","end":"2026-05-21T19:35:22.852487Z","steps":["trace[452453999] 'agreement among raft nodes before linearized reading' (duration: 113.806667ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:46.875941Z","caller":"traceutil/trace.go:171","msg":"trace[7754824] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"167.012014ms","start":"2026-05-21T19:35:46.7089Z","end":"2026-05-21T19:35:46.875913Z","steps":["trace[7754824] 'process raft request' (duration: 166.87789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:52.274467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.822575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:52.274549Z","caller":"traceutil/trace.go:171","msg":"trace[1503951554] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1815; }","duration":"358.964858ms","start":"2026-05-21T19:35:51.915566Z","end":"2026-05-21T19:35:52.274531Z","steps":["trace[1503951554] 'count revisions from in-memory index tree' (duration: 358.774803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:52.274597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:51.915559Z","time spent":"359.02409ms","remote":"127.0.0.1:60974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:35:52.274814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.359818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:35:52.274863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.659202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:35:52.2749Z","caller":"traceutil/trace.go:171","msg":"trace[2074902066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1815; }","duration":"321.47854ms","start":"2026-05-21T19:35:51.953402Z","end":"2026-05-21T19:35:52.274881Z","steps":["trace[2074902066] 'range keys from in-memory index tree' (duration: 321.285476ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:35:52.274915Z","caller":"traceutil/trace.go:171","msg":"trace[239682689] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1815; }","duration":"378.755714ms","start":"2026-05-21T19:35:51.896146Z","end":"2026-05-21T19:35:52.274902Z","steps":["trace[239682689] 'count revisions from in-memory index tree' (duration: 378.564119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:35:52.274944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:51.953388Z","time spent":"321.546813ms","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:35:52.274951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:35:51.896118Z","time spent":"378.825086ms","remote":"127.0.0.1:54012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:36:35.318126Z","caller":"traceutil/trace.go:171","msg":"trace[625859660] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"174.233117ms","start":"2026-05-21T19:36:35.143868Z","end":"2026-05-21T19:36:35.318101Z","steps":["trace[625859660] 'process raft request' (duration: 173.983471ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:35.318106Z","caller":"traceutil/trace.go:171","msg":"trace[1980916885] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"129.52164ms","start":"2026-05-21T19:36:35.18855Z","end":"2026-05-21T19:36:35.318072Z","steps":["trace[1980916885] 'read index received' (duration: 129.249623ms)","trace[1980916885] 'applied index is now lower than readState.Index' (duration: 270.297µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:36:35.318481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.91427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T19:36:35.318593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.300315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T19:36:35.318606Z","caller":"traceutil/trace.go:171","msg":"trace[1194668620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2153; }","duration":"130.101755ms","start":"2026-05-21T19:36:35.188483Z","end":"2026-05-21T19:36:35.318585Z","steps":["trace[1194668620] 'agreement among raft nodes before linearized reading' (duration: 129.815188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:35.318633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.69293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T19:36:35.318812Z","caller":"traceutil/trace.go:171","msg":"trace[1775914446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2153; }","duration":"125.908136ms","start":"2026-05-21T19:36:35.192878Z","end":"2026-05-21T19:36:35.318786Z","steps":["trace[1775914446] 'agreement among raft nodes before linearized reading' (duration: 125.484415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:35.318591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.124375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T19:36:35.318668Z","caller":"traceutil/trace.go:171","msg":"trace[271244329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2153; }","duration":"121.429778ms","start":"2026-05-21T19:36:35.197222Z","end":"2026-05-21T19:36:35.318652Z","steps":["trace[271244329] 'agreement among raft nodes before linearized reading' (duration: 121.184922ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:35.318915Z","caller":"traceutil/trace.go:171","msg":"trace[1753022944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2153; }","duration":"120.475394ms","start":"2026-05-21T19:36:35.19841Z","end":"2026-05-21T19:36:35.318885Z","steps":["trace[1753022944] 'agreement among raft nodes before linearized reading' (duration: 119.977712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:35.318568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.748552ms","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-05-21T19:36:35.319074Z","caller":"traceutil/trace.go:171","msg":"trace[1831200660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2153; }","duration":"123.292947ms","start":"2026-05-21T19:36:35.195774Z","end":"2026-05-21T19:36:35.319066Z","steps":["trace[1831200660] 'agreement among raft nodes before linearized reading' (duration: 122.65584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:35.939184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.363197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:36:35.939461Z","caller":"traceutil/trace.go:171","msg":"trace[1244586698] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"335.289519ms","start":"2026-05-21T19:36:35.604143Z","end":"2026-05-21T19:36:35.939432Z","steps":["trace[1244586698] 'read index received' (duration: 39.681µs)","trace[1244586698] 'applied index is now lower than readState.Index' (duration: 335.246738ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:36:35.939614Z","caller":"traceutil/trace.go:171","msg":"trace[1722362022] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"545.606767ms","start":"2026-05-21T19:36:35.393961Z","end":"2026-05-21T19:36:35.939568Z","steps":["trace[1722362022] 'process raft request' (duration: 167.764967ms)","trace[1722362022] 'compare' (duration: 377.214673ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:36:35.939893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:35.393932Z","time spent":"545.780281ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:36:35.940008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.777697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-05-21T19:36:35.940002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.440303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-21T19:36:35.940108Z","caller":"traceutil/trace.go:171","msg":"trace[1267704288] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2160; }","duration":"280.928132ms","start":"2026-05-21T19:36:35.659159Z","end":"2026-05-21T19:36:35.940087Z","steps":["trace[1267704288] 'agreement among raft nodes before linearized reading' (duration: 280.720795ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:35.940147Z","caller":"traceutil/trace.go:171","msg":"trace[1949440080] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2160; }","duration":"120.632838ms","start":"2026-05-21T19:36:35.819489Z","end":"2026-05-21T19:36:35.940122Z","steps":["trace[1949440080] 'agreement among raft nodes before linearized reading' (duration: 120.229427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:35.940384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.021071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-21T19:36:35.940497Z","caller":"traceutil/trace.go:171","msg":"trace[1062707699] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2160; }","duration":"284.190876ms","start":"2026-05-21T19:36:35.65629Z","end":"2026-05-21T19:36:35.940481Z","steps":["trace[1062707699] 'agreement among raft nodes before linearized reading' (duration: 283.398646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:35.940256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.082029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b1abc18f40865f\" ","response":"range_response_count:1 size:845"} {"level":"info","ts":"2026-05-21T19:36:35.940667Z","caller":"traceutil/trace.go:171","msg":"trace[230626245] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b1abc18f40865f; range_end:; response_count:1; response_revision:2160; }","duration":"336.538471ms","start":"2026-05-21T19:36:35.604082Z","end":"2026-05-21T19:36:35.940621Z","steps":["trace[230626245] 'agreement among raft nodes before linearized reading' (duration: 335.489954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:36:35.940763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:36:35.60406Z","time spent":"336.675606ms","remote":"127.0.0.1:53948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b1abc18f40865f\" "} {"level":"info","ts":"2026-05-21T19:36:36.183457Z","caller":"traceutil/trace.go:171","msg":"trace[1327427197] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"240.610108ms","start":"2026-05-21T19:36:35.942812Z","end":"2026-05-21T19:36:36.183423Z","steps":["trace[1327427197] 'process raft request' (duration: 163.179887ms)","trace[1327427197] 'compare' (duration: 77.300547ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:36:36.183719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.960662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-05-21T19:36:36.183761Z","caller":"traceutil/trace.go:171","msg":"trace[1700500858] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2162; }","duration":"240.039304ms","start":"2026-05-21T19:36:35.943709Z","end":"2026-05-21T19:36:36.183748Z","steps":["trace[1700500858] 'agreement among raft nodes before linearized reading' (duration: 239.859309ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:36:36.183524Z","caller":"traceutil/trace.go:171","msg":"trace[418635710] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2235; }","duration":"239.741285ms","start":"2026-05-21T19:36:35.943739Z","end":"2026-05-21T19:36:36.183481Z","steps":["trace[418635710] 'read index received' (duration: 162.267524ms)","trace[418635710] 'applied index is now lower than readState.Index' (duration: 77.471261ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:36:36.18351Z","caller":"traceutil/trace.go:171","msg":"trace[1118657780] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"239.265683ms","start":"2026-05-21T19:36:35.94422Z","end":"2026-05-21T19:36:36.183485Z","steps":["trace[1118657780] 'process raft request' (duration: 239.199201ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:37:11.638642Z","caller":"traceutil/trace.go:171","msg":"trace[1634025129] linearizableReadLoop","detail":"{readStateIndex:2545; appliedIndex:2544; }","duration":"149.908543ms","start":"2026-05-21T19:37:11.488713Z","end":"2026-05-21T19:37:11.638621Z","steps":["trace[1634025129] 'read index received' (duration: 149.78545ms)","trace[1634025129] 'applied index is now lower than readState.Index' (duration: 122.103µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:37:11.638761Z","caller":"traceutil/trace.go:171","msg":"trace[371904320] transaction","detail":"{read_only:false; response_revision:2453; number_of_response:1; }","duration":"199.715056ms","start":"2026-05-21T19:37:11.439015Z","end":"2026-05-21T19:37:11.63873Z","steps":["trace[371904320] 'process raft request' (duration: 199.48884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:37:11.638833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.087587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T19:37:11.63886Z","caller":"traceutil/trace.go:171","msg":"trace[464086001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2453; }","duration":"150.167089ms","start":"2026-05-21T19:37:11.488686Z","end":"2026-05-21T19:37:11.638853Z","steps":["trace[464086001] 'agreement among raft nodes before linearized reading' (duration: 150.037025ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:37:43.088239Z","caller":"traceutil/trace.go:171","msg":"trace[1010005323] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"132.067266ms","start":"2026-05-21T19:37:42.956149Z","end":"2026-05-21T19:37:43.088217Z","steps":["trace[1010005323] 'process raft request' (duration: 131.930722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:37:43.666339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.746867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-05-21T19:37:43.666427Z","caller":"traceutil/trace.go:171","msg":"trace[1438458078] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2673; }","duration":"285.884082ms","start":"2026-05-21T19:37:43.380524Z","end":"2026-05-21T19:37:43.666408Z","steps":["trace[1438458078] 'range keys from in-memory index tree' (duration: 285.523622ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:37:43.944875Z","caller":"traceutil/trace.go:171","msg":"trace[2028852972] transaction","detail":"{read_only:false; response_revision:2674; number_of_response:1; }","duration":"246.644904ms","start":"2026-05-21T19:37:43.698154Z","end":"2026-05-21T19:37:43.944799Z","steps":["trace[2028852972] 'process raft request' (duration: 246.518041ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:37:43.946084Z","caller":"traceutil/trace.go:171","msg":"trace[629000529] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"162.911964ms","start":"2026-05-21T19:37:43.783138Z","end":"2026-05-21T19:37:43.94605Z","steps":["trace[629000529] 'process raft request' (duration: 162.448061ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:37:43.946046Z","caller":"traceutil/trace.go:171","msg":"trace[283727841] transaction","detail":"{read_only:false; response_revision:2675; number_of_response:1; }","duration":"183.851092ms","start":"2026-05-21T19:37:43.762158Z","end":"2026-05-21T19:37:43.946009Z","steps":["trace[283727841] 'process raft request' (duration: 183.332128ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:39:55.054929Z","caller":"traceutil/trace.go:171","msg":"trace[224915295] linearizableReadLoop","detail":"{readStateIndex:4449; appliedIndex:4448; }","duration":"101.229278ms","start":"2026-05-21T19:39:54.953678Z","end":"2026-05-21T19:39:55.054907Z","steps":["trace[224915295] 'read index received' (duration: 101.082534ms)","trace[224915295] 'applied index is now lower than readState.Index' (duration: 145.634µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:39:55.054972Z","caller":"traceutil/trace.go:171","msg":"trace[1756970330] transaction","detail":"{read_only:false; response_revision:4270; number_of_response:1; }","duration":"104.74139ms","start":"2026-05-21T19:39:54.950202Z","end":"2026-05-21T19:39:55.054944Z","steps":["trace[1756970330] 'process raft request' (duration: 104.542155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:39:55.055282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.573649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4736"} {"level":"info","ts":"2026-05-21T19:39:55.05539Z","caller":"traceutil/trace.go:171","msg":"trace[457798433] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4270; }","duration":"101.736852ms","start":"2026-05-21T19:39:54.953632Z","end":"2026-05-21T19:39:55.055369Z","steps":["trace[457798433] 'agreement among raft nodes before linearized reading' (duration: 101.455484ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:39:57.045101Z","caller":"traceutil/trace.go:171","msg":"trace[306105004] transaction","detail":"{read_only:false; response_revision:4311; number_of_response:1; }","duration":"165.282651ms","start":"2026-05-21T19:39:56.879795Z","end":"2026-05-21T19:39:57.045078Z","steps":["trace[306105004] 'process raft request' (duration: 165.151478ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:39:57.045841Z","caller":"traceutil/trace.go:171","msg":"trace[531050446] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4312; }","duration":"165.003004ms","start":"2026-05-21T19:39:56.880809Z","end":"2026-05-21T19:39:57.045812Z","steps":["trace[531050446] 'process raft request' (duration: 164.85014ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:00.172302Z","caller":"traceutil/trace.go:171","msg":"trace[267408875] transaction","detail":"{read_only:false; response_revision:4398; number_of_response:1; }","duration":"144.599425ms","start":"2026-05-21T19:40:00.027661Z","end":"2026-05-21T19:40:00.172261Z","steps":["trace[267408875] 'process raft request' (duration: 144.342468ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:01.157204Z","caller":"traceutil/trace.go:171","msg":"trace[350458108] linearizableReadLoop","detail":"{readStateIndex:4600; appliedIndex:4599; }","duration":"191.933843ms","start":"2026-05-21T19:40:00.965237Z","end":"2026-05-21T19:40:01.157171Z","steps":["trace[350458108] 'read index received' (duration: 121.827853ms)","trace[350458108] 'applied index is now lower than readState.Index' (duration: 70.10447ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:40:01.157242Z","caller":"traceutil/trace.go:171","msg":"trace[2139280854] transaction","detail":"{read_only:false; response_revision:4413; number_of_response:1; }","duration":"317.164176ms","start":"2026-05-21T19:40:00.840044Z","end":"2026-05-21T19:40:01.157208Z","steps":["trace[2139280854] 'process raft request' (duration: 247.036005ms)","trace[2139280854] 'compare' (duration: 69.820013ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:40:01.157362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.103509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:40:01.157408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:00.839995Z","time spent":"317.31358ms","remote":"127.0.0.1:54050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:40:01.157452Z","caller":"traceutil/trace.go:171","msg":"trace[43881582] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4413; }","duration":"192.236622ms","start":"2026-05-21T19:40:00.965191Z","end":"2026-05-21T19:40:01.157428Z","steps":["trace[43881582] 'agreement among raft nodes before linearized reading' (duration: 192.087998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:01.157478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.029617ms","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-05-21T19:40:01.157736Z","caller":"traceutil/trace.go:171","msg":"trace[65076673] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4413; }","duration":"182.299654ms","start":"2026-05-21T19:40:00.975415Z","end":"2026-05-21T19:40:01.157715Z","steps":["trace[65076673] 'agreement among raft nodes before linearized reading' (duration: 181.935215ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:02.598691Z","caller":"traceutil/trace.go:171","msg":"trace[1446398996] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"162.880559ms","start":"2026-05-21T19:40:02.435788Z","end":"2026-05-21T19:40:02.598669Z","steps":["trace[1446398996] 'process raft request' (duration: 162.742335ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:02.599269Z","caller":"traceutil/trace.go:171","msg":"trace[181219574] linearizableReadLoop","detail":"{readStateIndex:4683; appliedIndex:4683; }","duration":"151.852343ms","start":"2026-05-21T19:40:02.447393Z","end":"2026-05-21T19:40:02.599246Z","steps":["trace[181219574] 'read index received' (duration: 151.832873ms)","trace[181219574] 'applied index is now lower than readState.Index' (duration: 17.04µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:40:02.599532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.12381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34b059cf-42f4-4151-adfc-98f650f9593f\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-05-21T19:40:02.599606Z","caller":"traceutil/trace.go:171","msg":"trace[1945556986] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-34b059cf-42f4-4151-adfc-98f650f9593f; range_end:; response_count:1; response_revision:4493; }","duration":"152.247683ms","start":"2026-05-21T19:40:02.447345Z","end":"2026-05-21T19:40:02.599592Z","steps":["trace[1945556986] 'agreement among raft nodes before linearized reading' (duration: 152.011047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:02.8815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.171691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:40:02.881612Z","caller":"traceutil/trace.go:171","msg":"trace[1872617495] linearizableReadLoop","detail":"{readStateIndex:4684; appliedIndex:4683; }","duration":"282.24904ms","start":"2026-05-21T19:40:02.599344Z","end":"2026-05-21T19:40:02.881593Z","steps":["trace[1872617495] 'read index received' (duration: 129.780581ms)","trace[1872617495] 'applied index is now lower than readState.Index' (duration: 152.466829ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:40:02.881676Z","caller":"traceutil/trace.go:171","msg":"trace[271516938] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"383.255082ms","start":"2026-05-21T19:40:02.49838Z","end":"2026-05-21T19:40:02.881636Z","steps":["trace[271516938] 'process raft request' (duration: 230.850675ms)","trace[271516938] 'compare' (duration: 152.040317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:40:02.881809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.712804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-05-21T19:40:02.881839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:02.498356Z","time spent":"383.407765ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:40:02.881861Z","caller":"traceutil/trace.go:171","msg":"trace[2112374652] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4494; }","duration":"415.791576ms","start":"2026-05-21T19:40:02.466051Z","end":"2026-05-21T19:40:02.881843Z","steps":["trace[2112374652] 'agreement among raft nodes before linearized reading' (duration: 415.645633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:02.881911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:02.466035Z","time spent":"415.861118ms","remote":"127.0.0.1:53988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T19:40:02.881933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.292368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-21T19:40:02.881974Z","caller":"traceutil/trace.go:171","msg":"trace[222923945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4494; }","duration":"203.347079ms","start":"2026-05-21T19:40:02.678614Z","end":"2026-05-21T19:40:02.881961Z","steps":["trace[222923945] 'agreement among raft nodes before linearized reading' (duration: 203.233956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:02.882041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.650276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-21T19:40:02.882052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.287378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T19:40:02.882095Z","caller":"traceutil/trace.go:171","msg":"trace[1929991727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4494; }","duration":"206.714577ms","start":"2026-05-21T19:40:02.675364Z","end":"2026-05-21T19:40:02.882079Z","steps":["trace[1929991727] 'agreement among raft nodes before linearized reading' (duration: 206.525472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:02.882069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.359925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-21T19:40:02.882104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.470215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-21T19:40:02.882141Z","caller":"traceutil/trace.go:171","msg":"trace[10046878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4494; }","duration":"198.416221ms","start":"2026-05-21T19:40:02.683706Z","end":"2026-05-21T19:40:02.882122Z","steps":["trace[10046878] 'agreement among raft nodes before linearized reading' (duration: 198.122064ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:02.882154Z","caller":"traceutil/trace.go:171","msg":"trace[103653276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4494; }","duration":"202.503957ms","start":"2026-05-21T19:40:02.67963Z","end":"2026-05-21T19:40:02.882134Z","steps":["trace[103653276] 'agreement among raft nodes before linearized reading' (duration: 202.227041ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:02.88218Z","caller":"traceutil/trace.go:171","msg":"trace[2051185094] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4494; }","duration":"210.590987ms","start":"2026-05-21T19:40:02.671568Z","end":"2026-05-21T19:40:02.882159Z","steps":["trace[2051185094] 'agreement among raft nodes before linearized reading' (duration: 210.263489ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:07.143355Z","caller":"traceutil/trace.go:171","msg":"trace[1617641631] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"226.156552ms","start":"2026-05-21T19:40:06.91717Z","end":"2026-05-21T19:40:07.143327Z","steps":["trace[1617641631] 'process raft request' (duration: 225.351231ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:07.14337Z","caller":"traceutil/trace.go:171","msg":"trace[2099804617] linearizableReadLoop","detail":"{readStateIndex:4773; appliedIndex:4772; }","duration":"194.515561ms","start":"2026-05-21T19:40:06.948821Z","end":"2026-05-21T19:40:07.143336Z","steps":["trace[2099804617] 'read index received' (duration: 193.70317ms)","trace[2099804617] 'applied index is now lower than readState.Index' (duration: 810.951µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:40:07.143503Z","caller":"traceutil/trace.go:171","msg":"trace[215755196] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"149.181903ms","start":"2026-05-21T19:40:06.994291Z","end":"2026-05-21T19:40:07.143473Z","steps":["trace[215755196] 'process raft request' (duration: 149.023199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:07.14355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.723756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:40:07.143576Z","caller":"traceutil/trace.go:171","msg":"trace[980810208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4580; }","duration":"194.802758ms","start":"2026-05-21T19:40:06.948765Z","end":"2026-05-21T19:40:07.143567Z","steps":["trace[980810208] 'agreement among raft nodes before linearized reading' (duration: 194.716495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:07.143713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.105137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-21T19:40:07.143767Z","caller":"traceutil/trace.go:171","msg":"trace[289391411] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4580; }","duration":"170.19839ms","start":"2026-05-21T19:40:06.973553Z","end":"2026-05-21T19:40:07.143751Z","steps":["trace[289391411] 'agreement among raft nodes before linearized reading' (duration: 169.973874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:07.143818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.260894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-21T19:40:07.143808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.409837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T19:40:07.143843Z","caller":"traceutil/trace.go:171","msg":"trace[1377867053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4580; }","duration":"132.319995ms","start":"2026-05-21T19:40:07.011517Z","end":"2026-05-21T19:40:07.143836Z","steps":["trace[1377867053] 'agreement among raft nodes before linearized reading' (duration: 132.228823ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:07.143854Z","caller":"traceutil/trace.go:171","msg":"trace[481217489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4580; }","duration":"130.503319ms","start":"2026-05-21T19:40:07.01334Z","end":"2026-05-21T19:40:07.143844Z","steps":["trace[481217489] 'agreement among raft nodes before linearized reading' (duration: 130.328484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:07.143875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.522769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T19:40:07.143913Z","caller":"traceutil/trace.go:171","msg":"trace[388641710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4580; }","duration":"130.571191ms","start":"2026-05-21T19:40:07.013321Z","end":"2026-05-21T19:40:07.143893Z","steps":["trace[388641710] 'agreement among raft nodes before linearized reading' (duration: 130.506919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:07.143928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.885721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-21T19:40:07.143942Z","caller":"traceutil/trace.go:171","msg":"trace[2113359135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4580; }","duration":"154.927623ms","start":"2026-05-21T19:40:06.98901Z","end":"2026-05-21T19:40:07.143938Z","steps":["trace[2113359135] 'agreement among raft nodes before linearized reading' (duration: 154.882282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:07.144076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.826289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-21T19:40:07.144155Z","caller":"traceutil/trace.go:171","msg":"trace[234731205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4580; }","duration":"132.952362ms","start":"2026-05-21T19:40:07.011182Z","end":"2026-05-21T19:40:07.144134Z","steps":["trace[234731205] 'agreement among raft nodes before linearized reading' (duration: 132.658755ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:19.227137Z","caller":"traceutil/trace.go:171","msg":"trace[1015198645] linearizableReadLoop","detail":"{readStateIndex:4995; appliedIndex:4994; }","duration":"228.835844ms","start":"2026-05-21T19:40:18.998286Z","end":"2026-05-21T19:40:19.227122Z","steps":["trace[1015198645] 'read index received' (duration: 228.737181ms)","trace[1015198645] 'applied index is now lower than readState.Index' (duration: 97.753µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:40:19.227358Z","caller":"traceutil/trace.go:171","msg":"trace[1757868974] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"301.948543ms","start":"2026-05-21T19:40:18.925399Z","end":"2026-05-21T19:40:19.227348Z","steps":["trace[1757868974] 'process raft request' (duration: 301.625864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:19.227449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:18.925375Z","time spent":"302.009544ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:40:19.227629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.340748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-x2csz\" ","response":"range_response_count:1 size:7812"} {"level":"info","ts":"2026-05-21T19:40:19.227662Z","caller":"traceutil/trace.go:171","msg":"trace[137897354] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-x2csz; range_end:; response_count:1; response_revision:4792; }","duration":"229.369489ms","start":"2026-05-21T19:40:18.998282Z","end":"2026-05-21T19:40:19.227651Z","steps":["trace[137897354] 'agreement among raft nodes before linearized reading' (duration: 229.283597ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:19.633064Z","caller":"traceutil/trace.go:171","msg":"trace[853108852] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"400.312378ms","start":"2026-05-21T19:40:19.232731Z","end":"2026-05-21T19:40:19.633043Z","steps":["trace[853108852] 'process raft request' (duration: 398.744527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:19.633202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:19.232711Z","time spent":"400.429581ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:40:19.649657Z","caller":"traceutil/trace.go:171","msg":"trace[811441436] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"412.904468ms","start":"2026-05-21T19:40:19.236729Z","end":"2026-05-21T19:40:19.649634Z","steps":["trace[811441436] 'process raft request' (duration: 412.752454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:19.649882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:19.23672Z","time spent":"413.011572ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:40:19.650052Z","caller":"traceutil/trace.go:171","msg":"trace[1664788467] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"412.001704ms","start":"2026-05-21T19:40:19.23802Z","end":"2026-05-21T19:40:19.650022Z","steps":["trace[1664788467] 'process raft request' (duration: 411.790519ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:19.650121Z","caller":"traceutil/trace.go:171","msg":"trace[1608470919] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"413.325119ms","start":"2026-05-21T19:40:19.236773Z","end":"2026-05-21T19:40:19.650098Z","steps":["trace[1608470919] 'process raft request' (duration: 412.820787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:19.650209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:19.238011Z","time spent":"412.121218ms","remote":"127.0.0.1:54130","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-05-21T19:40:19.650237Z","caller":"traceutil/trace.go:171","msg":"trace[1647320018] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"410.896976ms","start":"2026-05-21T19:40:19.239327Z","end":"2026-05-21T19:40:19.650224Z","steps":["trace[1647320018] 'process raft request' (duration: 410.594568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:19.650244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:19.236768Z","time spent":"413.419711ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:40:19.650302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:19.23929Z","time spent":"410.978028ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:40:19.650362Z","caller":"traceutil/trace.go:171","msg":"trace[1357553793] linearizableReadLoop","detail":"{readStateIndex:5001; appliedIndex:4995; }","duration":"348.419704ms","start":"2026-05-21T19:40:19.301931Z","end":"2026-05-21T19:40:19.65035Z","steps":["trace[1357553793] 'read index received' (duration: 329.557849ms)","trace[1357553793] 'applied index is now lower than readState.Index' (duration: 18.860915ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:40:19.650444Z","caller":"traceutil/trace.go:171","msg":"trace[2037194672] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"411.036619ms","start":"2026-05-21T19:40:19.23939Z","end":"2026-05-21T19:40:19.650427Z","steps":["trace[2037194672] 'process raft request' (duration: 410.569767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:19.650535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.60598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-05-21T19:40:19.650549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:19.239372Z","time spent":"411.119872ms","remote":"127.0.0.1:54050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:40:19.650577Z","caller":"traceutil/trace.go:171","msg":"trace[368661701] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4798; }","duration":"348.700962ms","start":"2026-05-21T19:40:19.301863Z","end":"2026-05-21T19:40:19.650564Z","steps":["trace[368661701] 'agreement among raft nodes before linearized reading' (duration: 348.569538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:19.650608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:40:19.301846Z","time spent":"348.754483ms","remote":"127.0.0.1:53988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T19:40:19.650623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.312477ms","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-05-21T19:40:19.650707Z","caller":"traceutil/trace.go:171","msg":"trace[2006345787] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4798; }","duration":"258.42842ms","start":"2026-05-21T19:40:19.392252Z","end":"2026-05-21T19:40:19.650681Z","steps":["trace[2006345787] 'agreement among raft nodes before linearized reading' (duration: 258.305637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:19.650731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.985936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-21T19:40:19.650776Z","caller":"traceutil/trace.go:171","msg":"trace[2012347613] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4798; }","duration":"237.059268ms","start":"2026-05-21T19:40:19.413702Z","end":"2026-05-21T19:40:19.650762Z","steps":["trace[2012347613] 'agreement among raft nodes before linearized reading' (duration: 236.915005ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:19.849526Z","caller":"traceutil/trace.go:171","msg":"trace[835627061] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"193.506684ms","start":"2026-05-21T19:40:19.655984Z","end":"2026-05-21T19:40:19.849491Z","steps":["trace[835627061] 'process raft request' (duration: 144.199419ms)","trace[835627061] 'compare' (duration: 49.08511ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:40:19.851445Z","caller":"traceutil/trace.go:171","msg":"trace[668659941] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"194.676225ms","start":"2026-05-21T19:40:19.656753Z","end":"2026-05-21T19:40:19.85143Z","steps":["trace[668659941] 'process raft request' (duration: 194.516332ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:19.851631Z","caller":"traceutil/trace.go:171","msg":"trace[569264160] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"193.951866ms","start":"2026-05-21T19:40:19.657669Z","end":"2026-05-21T19:40:19.851621Z","steps":["trace[569264160] 'process raft request' (duration: 193.71798ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:19.851949Z","caller":"traceutil/trace.go:171","msg":"trace[1118102761] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"194.006428ms","start":"2026-05-21T19:40:19.65792Z","end":"2026-05-21T19:40:19.851926Z","steps":["trace[1118102761] 'process raft request' (duration: 193.655308ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:19.852246Z","caller":"traceutil/trace.go:171","msg":"trace[1366695586] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"194.063989ms","start":"2026-05-21T19:40:19.658162Z","end":"2026-05-21T19:40:19.852226Z","steps":["trace[1366695586] 'process raft request' (duration: 193.639288ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:19.852132Z","caller":"traceutil/trace.go:171","msg":"trace[709884262] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"193.593067ms","start":"2026-05-21T19:40:19.658515Z","end":"2026-05-21T19:40:19.852108Z","steps":["trace[709884262] 'process raft request' (duration: 193.369362ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:20.584377Z","caller":"traceutil/trace.go:171","msg":"trace[2024407784] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"234.683345ms","start":"2026-05-21T19:40:20.349676Z","end":"2026-05-21T19:40:20.58436Z","steps":["trace[2024407784] 'process raft request' (duration: 234.428378ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:20.585679Z","caller":"traceutil/trace.go:171","msg":"trace[1609962308] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"230.791953ms","start":"2026-05-21T19:40:20.354861Z","end":"2026-05-21T19:40:20.585653Z","steps":["trace[1609962308] 'process raft request' (duration: 230.584877ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:26.332393Z","caller":"traceutil/trace.go:171","msg":"trace[68187802] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"270.119407ms","start":"2026-05-21T19:40:26.062224Z","end":"2026-05-21T19:40:26.332344Z","steps":["trace[68187802] 'process raft request' (duration: 269.886341ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:26.548813Z","caller":"traceutil/trace.go:171","msg":"trace[1347253654] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5172; }","duration":"170.14577ms","start":"2026-05-21T19:40:26.378649Z","end":"2026-05-21T19:40:26.548795Z","steps":["trace[1347253654] 'read index received' (duration: 170.047318ms)","trace[1347253654] 'applied index is now lower than readState.Index' (duration: 97.812µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:40:26.548848Z","caller":"traceutil/trace.go:171","msg":"trace[859101891] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"170.696284ms","start":"2026-05-21T19:40:26.378126Z","end":"2026-05-21T19:40:26.548822Z","steps":["trace[859101891] 'process raft request' (duration: 170.53359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:26.549091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.855881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:40:26.549127Z","caller":"traceutil/trace.go:171","msg":"trace[1731681717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4965; }","duration":"136.069496ms","start":"2026-05-21T19:40:26.413049Z","end":"2026-05-21T19:40:26.549118Z","steps":["trace[1731681717] 'agreement among raft nodes before linearized reading' (duration: 135.84812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:26.549188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.998441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-05-21T19:40:26.549251Z","caller":"traceutil/trace.go:171","msg":"trace[654309684] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:4965; }","duration":"134.097784ms","start":"2026-05-21T19:40:26.415137Z","end":"2026-05-21T19:40:26.549234Z","steps":["trace[654309684] 'agreement among raft nodes before linearized reading' (duration: 133.895079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:40:26.549391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.702916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-05-21T19:40:26.54964Z","caller":"traceutil/trace.go:171","msg":"trace[1432301336] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4965; }","duration":"171.027574ms","start":"2026-05-21T19:40:26.378594Z","end":"2026-05-21T19:40:26.549622Z","steps":["trace[1432301336] 'agreement among raft nodes before linearized reading' (duration: 170.268674ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:32.860559Z","caller":"traceutil/trace.go:171","msg":"trace[949137935] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"129.214375ms","start":"2026-05-21T19:40:32.731317Z","end":"2026-05-21T19:40:32.860531Z","steps":["trace[949137935] 'process raft request' (duration: 129.047621ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:36.356421Z","caller":"traceutil/trace.go:171","msg":"trace[1192194444] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"115.926415ms","start":"2026-05-21T19:40:36.240468Z","end":"2026-05-21T19:40:36.356395Z","steps":["trace[1192194444] 'process raft request' (duration: 115.825842ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:36.356537Z","caller":"traceutil/trace.go:171","msg":"trace[375078936] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"114.817647ms","start":"2026-05-21T19:40:36.241699Z","end":"2026-05-21T19:40:36.356517Z","steps":["trace[375078936] 'process raft request' (duration: 114.647093ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:40:36.356458Z","caller":"traceutil/trace.go:171","msg":"trace[1972064274] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"116.381848ms","start":"2026-05-21T19:40:36.240005Z","end":"2026-05-21T19:40:36.356386Z","steps":["trace[1972064274] 'process raft request' (duration: 116.137291ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:41:06.186751Z","caller":"traceutil/trace.go:171","msg":"trace[1223831993] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"110.819631ms","start":"2026-05-21T19:41:06.075908Z","end":"2026-05-21T19:41:06.186728Z","steps":["trace[1223831993] 'process raft request' (duration: 110.707148ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:43:33.333443Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3183} {"level":"info","ts":"2026-05-21T19:43:33.426028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3183,"took":"91.188219ms","hash":452263746,"current-db-size-bytes":60108800,"current-db-size":"60 MB","current-db-size-in-use-bytes":33882112,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-21T19:43:33.426101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":452263746,"revision":3183,"compact-revision":-1} {"level":"info","ts":"2026-05-21T19:45:29.291385Z","caller":"traceutil/trace.go:171","msg":"trace[1054379772] linearizableReadLoop","detail":"{readStateIndex:7683; appliedIndex:7681; }","duration":"138.014768ms","start":"2026-05-21T19:45:29.153337Z","end":"2026-05-21T19:45:29.291352Z","steps":["trace[1054379772] 'read index received' (duration: 28.254315ms)","trace[1054379772] 'applied index is now lower than readState.Index' (duration: 109.759543ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:29.291489Z","caller":"traceutil/trace.go:171","msg":"trace[1745846087] transaction","detail":"{read_only:false; response_revision:7346; number_of_response:1; }","duration":"138.168742ms","start":"2026-05-21T19:45:29.153262Z","end":"2026-05-21T19:45:29.291431Z","steps":["trace[1745846087] 'process raft request' (duration: 69.118137ms)","trace[1745846087] 'compare' (duration: 68.82369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:29.291687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.129572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-21T19:45:29.291761Z","caller":"traceutil/trace.go:171","msg":"trace[1628848704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7346; }","duration":"138.265556ms","start":"2026-05-21T19:45:29.153479Z","end":"2026-05-21T19:45:29.291745Z","steps":["trace[1628848704] 'agreement among raft nodes before linearized reading' (duration: 138.088232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:29.291817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.377794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-21T19:45:29.29187Z","caller":"traceutil/trace.go:171","msg":"trace[1990201758] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7346; }","duration":"109.450566ms","start":"2026-05-21T19:45:29.182403Z","end":"2026-05-21T19:45:29.291853Z","steps":["trace[1990201758] 'agreement among raft nodes before linearized reading' (duration: 109.341553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:29.291691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.329307ms","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-05-21T19:45:29.291897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.881818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:45:29.291958Z","caller":"traceutil/trace.go:171","msg":"trace[480332498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7346; }","duration":"134.99231ms","start":"2026-05-21T19:45:29.156951Z","end":"2026-05-21T19:45:29.291943Z","steps":["trace[480332498] 'agreement among raft nodes before linearized reading' (duration: 134.802495ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:29.291962Z","caller":"traceutil/trace.go:171","msg":"trace[361652694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7346; }","duration":"138.606494ms","start":"2026-05-21T19:45:29.153328Z","end":"2026-05-21T19:45:29.291935Z","steps":["trace[361652694] 'agreement among raft nodes before linearized reading' (duration: 138.212494ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:33.603992Z","caller":"traceutil/trace.go:171","msg":"trace[1529787747] linearizableReadLoop","detail":"{readStateIndex:7714; appliedIndex:7713; }","duration":"125.979036ms","start":"2026-05-21T19:45:33.477983Z","end":"2026-05-21T19:45:33.603962Z","steps":["trace[1529787747] 'read index received' (duration: 74.112487ms)","trace[1529787747] 'applied index is now lower than readState.Index' (duration: 51.861369ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:33.604317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.301425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-21T19:45:33.604393Z","caller":"traceutil/trace.go:171","msg":"trace[1917547769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7375; }","duration":"126.420918ms","start":"2026-05-21T19:45:33.477956Z","end":"2026-05-21T19:45:33.604377Z","steps":["trace[1917547769] 'agreement among raft nodes before linearized reading' (duration: 126.177042ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:33.604466Z","caller":"traceutil/trace.go:171","msg":"trace[686973131] transaction","detail":"{read_only:false; response_revision:7375; number_of_response:1; }","duration":"134.18754ms","start":"2026-05-21T19:45:33.469965Z","end":"2026-05-21T19:45:33.604152Z","steps":["trace[686973131] 'process raft request' (duration: 82.087644ms)","trace[686973131] 'compare' (duration: 51.781137ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:34.451987Z","caller":"traceutil/trace.go:171","msg":"trace[1202580127] transaction","detail":"{read_only:false; response_revision:7382; number_of_response:1; }","duration":"118.778699ms","start":"2026-05-21T19:45:34.333146Z","end":"2026-05-21T19:45:34.451924Z","steps":["trace[1202580127] 'process raft request' (duration: 94.887027ms)","trace[1202580127] 'compare' (duration: 23.633975ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:34.840824Z","caller":"traceutil/trace.go:171","msg":"trace[777607316] linearizableReadLoop","detail":"{readStateIndex:7723; appliedIndex:7722; }","duration":"109.429385ms","start":"2026-05-21T19:45:34.731361Z","end":"2026-05-21T19:45:34.84079Z","steps":["trace[777607316] 'read index received' (duration: 109.155058ms)","trace[777607316] 'applied index is now lower than readState.Index' (duration: 273.307µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:34.840993Z","caller":"traceutil/trace.go:171","msg":"trace[819995668] transaction","detail":"{read_only:false; response_revision:7384; number_of_response:1; }","duration":"113.552123ms","start":"2026-05-21T19:45:34.727387Z","end":"2026-05-21T19:45:34.84094Z","steps":["trace[819995668] 'process raft request' (duration: 113.174663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:34.841199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.812135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127741"} {"level":"info","ts":"2026-05-21T19:45:34.841336Z","caller":"traceutil/trace.go:171","msg":"trace[1959872916] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:7384; }","duration":"109.98628ms","start":"2026-05-21T19:45:34.731332Z","end":"2026-05-21T19:45:34.841318Z","steps":["trace[1959872916] 'agreement among raft nodes before linearized reading' (duration: 109.643881ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:35.47694Z","caller":"traceutil/trace.go:171","msg":"trace[1259439859] transaction","detail":"{read_only:false; response_revision:7386; number_of_response:1; }","duration":"252.781493ms","start":"2026-05-21T19:45:35.224109Z","end":"2026-05-21T19:45:35.47689Z","steps":["trace[1259439859] 'process raft request' (duration: 252.31201ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:37.700932Z","caller":"traceutil/trace.go:171","msg":"trace[624422831] transaction","detail":"{read_only:false; response_revision:7399; number_of_response:1; }","duration":"169.319433ms","start":"2026-05-21T19:45:37.531564Z","end":"2026-05-21T19:45:37.700884Z","steps":["trace[624422831] 'process raft request' (duration: 74.811305ms)","trace[624422831] 'compare' (duration: 94.141449ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:37.70101Z","caller":"traceutil/trace.go:171","msg":"trace[87601101] transaction","detail":"{read_only:false; response_revision:7400; number_of_response:1; }","duration":"169.319142ms","start":"2026-05-21T19:45:37.531627Z","end":"2026-05-21T19:45:37.700946Z","steps":["trace[87601101] 'process raft request' (duration: 169.109996ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:37.701144Z","caller":"traceutil/trace.go:171","msg":"trace[2118178760] transaction","detail":"{read_only:false; response_revision:7401; number_of_response:1; }","duration":"169.19621ms","start":"2026-05-21T19:45:37.531847Z","end":"2026-05-21T19:45:37.701043Z","steps":["trace[2118178760] 'process raft request' (duration: 168.937343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:39.194408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.586105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:45:39.194503Z","caller":"traceutil/trace.go:171","msg":"trace[912035367] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:7409; }","duration":"100.74674ms","start":"2026-05-21T19:45:39.093735Z","end":"2026-05-21T19:45:39.194482Z","steps":["trace[912035367] 'count revisions from in-memory index tree' (duration: 100.447452ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:39.666376Z","caller":"traceutil/trace.go:171","msg":"trace[1091600033] transaction","detail":"{read_only:false; response_revision:7410; number_of_response:1; }","duration":"133.627705ms","start":"2026-05-21T19:45:39.532713Z","end":"2026-05-21T19:45:39.666341Z","steps":["trace[1091600033] 'process raft request' (duration: 133.472851ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:40.2543Z","caller":"traceutil/trace.go:171","msg":"trace[1312938752] transaction","detail":"{read_only:false; response_revision:7417; number_of_response:1; }","duration":"108.603325ms","start":"2026-05-21T19:45:40.145668Z","end":"2026-05-21T19:45:40.254271Z","steps":["trace[1312938752] 'process raft request' (duration: 107.554508ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:40.411347Z","caller":"traceutil/trace.go:171","msg":"trace[43037845] linearizableReadLoop","detail":"{readStateIndex:7761; appliedIndex:7760; }","duration":"114.014936ms","start":"2026-05-21T19:45:40.297284Z","end":"2026-05-21T19:45:40.411299Z","steps":["trace[43037845] 'read index received' (duration: 81.675335ms)","trace[43037845] 'applied index is now lower than readState.Index' (duration: 32.337931ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:40.41143Z","caller":"traceutil/trace.go:171","msg":"trace[1720295177] transaction","detail":"{read_only:false; response_revision:7419; number_of_response:1; }","duration":"121.331676ms","start":"2026-05-21T19:45:40.290046Z","end":"2026-05-21T19:45:40.411378Z","steps":["trace[1720295177] 'process raft request' (duration: 88.919753ms)","trace[1720295177] 'compare' (duration: 32.162486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:40.411711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.403555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-05-21T19:45:40.41181Z","caller":"traceutil/trace.go:171","msg":"trace[1869233593] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:7419; }","duration":"114.55995ms","start":"2026-05-21T19:45:40.297231Z","end":"2026-05-21T19:45:40.411791Z","steps":["trace[1869233593] 'agreement among raft nodes before linearized reading' (duration: 114.275302ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:43.091954Z","caller":"traceutil/trace.go:171","msg":"trace[599838660] linearizableReadLoop","detail":"{readStateIndex:7777; appliedIndex:7776; }","duration":"158.874741ms","start":"2026-05-21T19:45:42.933049Z","end":"2026-05-21T19:45:43.091924Z","steps":["trace[599838660] 'read index received' (duration: 83.953033ms)","trace[599838660] 'applied index is now lower than readState.Index' (duration: 74.920408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:43.09255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.422346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:45:43.092765Z","caller":"traceutil/trace.go:171","msg":"trace[1521113810] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:7434; }","duration":"159.739084ms","start":"2026-05-21T19:45:42.933001Z","end":"2026-05-21T19:45:43.09274Z","steps":["trace[1521113810] 'agreement among raft nodes before linearized reading' (duration: 159.064776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:43.09285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.529077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:45:43.092963Z","caller":"traceutil/trace.go:171","msg":"trace[935456620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7434; }","duration":"142.729771ms","start":"2026-05-21T19:45:42.950221Z","end":"2026-05-21T19:45:43.092951Z","steps":["trace[935456620] 'agreement among raft nodes before linearized reading' (duration: 142.545267ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:43.092167Z","caller":"traceutil/trace.go:171","msg":"trace[1479930820] transaction","detail":"{read_only:false; response_revision:7434; number_of_response:1; }","duration":"177.424483ms","start":"2026-05-21T19:45:42.914705Z","end":"2026-05-21T19:45:43.09213Z","steps":["trace[1479930820] 'process raft request' (duration: 102.418013ms)","trace[1479930820] 'compare' (duration: 74.656351ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:44.118946Z","caller":"traceutil/trace.go:171","msg":"trace[2142421425] linearizableReadLoop","detail":"{readStateIndex:7781; appliedIndex:7779; }","duration":"188.597664ms","start":"2026-05-21T19:45:43.930299Z","end":"2026-05-21T19:45:44.118897Z","steps":["trace[2142421425] 'read index received' (duration: 71.319875ms)","trace[2142421425] 'applied index is now lower than readState.Index' (duration: 117.275579ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:44.119018Z","caller":"traceutil/trace.go:171","msg":"trace[1236837318] transaction","detail":"{read_only:false; response_revision:7438; number_of_response:1; }","duration":"204.388424ms","start":"2026-05-21T19:45:43.914552Z","end":"2026-05-21T19:45:44.118941Z","steps":["trace[1236837318] 'process raft request' (duration: 152.931856ms)","trace[1236837318] 'compare' (duration: 51.108819ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:44.11925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.883441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T19:45:44.11938Z","caller":"traceutil/trace.go:171","msg":"trace[1044989503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7438; }","duration":"189.101696ms","start":"2026-05-21T19:45:43.930243Z","end":"2026-05-21T19:45:44.119345Z","steps":["trace[1044989503] 'agreement among raft nodes before linearized reading' (duration: 188.777538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:44.119764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.752258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T19:45:44.11985Z","caller":"traceutil/trace.go:171","msg":"trace[1988192326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7438; }","duration":"188.918432ms","start":"2026-05-21T19:45:43.930913Z","end":"2026-05-21T19:45:44.119832Z","steps":["trace[1988192326] 'agreement among raft nodes before linearized reading' (duration: 188.229474ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:44.395688Z","caller":"traceutil/trace.go:171","msg":"trace[1481877547] linearizableReadLoop","detail":"{readStateIndex:7784; appliedIndex:7783; }","duration":"125.914244ms","start":"2026-05-21T19:45:44.269738Z","end":"2026-05-21T19:45:44.395652Z","steps":["trace[1481877547] 'read index received' (duration: 125.547374ms)","trace[1481877547] 'applied index is now lower than readState.Index' (duration: 365.49µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:44.395825Z","caller":"traceutil/trace.go:171","msg":"trace[547809882] transaction","detail":"{read_only:false; response_revision:7441; number_of_response:1; }","duration":"269.878237ms","start":"2026-05-21T19:45:44.125908Z","end":"2026-05-21T19:45:44.395787Z","steps":["trace[547809882] 'process raft request' (duration: 269.508168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:44.395928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.14679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:45:44.396005Z","caller":"traceutil/trace.go:171","msg":"trace[1898923416] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:7441; }","duration":"126.317003ms","start":"2026-05-21T19:45:44.269665Z","end":"2026-05-21T19:45:44.395982Z","steps":["trace[1898923416] 'agreement among raft nodes before linearized reading' (duration: 126.149609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:44.712073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.945578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-21T19:45:44.712188Z","caller":"traceutil/trace.go:171","msg":"trace[1521837247] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7441; }","duration":"243.159613ms","start":"2026-05-21T19:45:44.468999Z","end":"2026-05-21T19:45:44.712158Z","steps":["trace[1521837247] 'range keys from in-memory index tree' (duration: 242.676082ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:45.189436Z","caller":"traceutil/trace.go:171","msg":"trace[942191818] linearizableReadLoop","detail":"{readStateIndex:7787; appliedIndex:7786; }","duration":"310.332871ms","start":"2026-05-21T19:45:44.879071Z","end":"2026-05-21T19:45:45.189404Z","steps":["trace[942191818] 'read index received' (duration: 263.103112ms)","trace[942191818] 'applied index is now lower than readState.Index' (duration: 47.228409ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:45.18945Z","caller":"traceutil/trace.go:171","msg":"trace[1932799298] transaction","detail":"{read_only:false; response_revision:7444; number_of_response:1; }","duration":"428.337518ms","start":"2026-05-21T19:45:44.761079Z","end":"2026-05-21T19:45:45.189417Z","steps":["trace[1932799298] 'process raft request' (duration: 381.080179ms)","trace[1932799298] 'compare' (duration: 47.031753ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:45.189588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.492195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:45:45.189635Z","caller":"traceutil/trace.go:171","msg":"trace[2126482294] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:7444; }","duration":"310.592937ms","start":"2026-05-21T19:45:44.879031Z","end":"2026-05-21T19:45:45.189624Z","steps":["trace[2126482294] 'agreement among raft nodes before linearized reading' (duration: 310.488035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:45.189673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:44.879012Z","time spent":"310.651738ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":2,"response size":30,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:45:45.189705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:44.761064Z","time spent":"428.549914ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:45:45.189728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.4159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:45:45.189921Z","caller":"traceutil/trace.go:171","msg":"trace[964634561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7444; }","duration":"238.656386ms","start":"2026-05-21T19:45:44.951242Z","end":"2026-05-21T19:45:45.189898Z","steps":["trace[964634561] 'agreement among raft nodes before linearized reading' (duration: 238.40233ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:45.681939Z","caller":"traceutil/trace.go:171","msg":"trace[1405434965] linearizableReadLoop","detail":"{readStateIndex:7789; appliedIndex:7788; }","duration":"262.339552ms","start":"2026-05-21T19:45:45.419573Z","end":"2026-05-21T19:45:45.681913Z","steps":["trace[1405434965] 'read index received' (duration: 262.109716ms)","trace[1405434965] 'applied index is now lower than readState.Index' (duration: 228.955µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:45.682059Z","caller":"traceutil/trace.go:171","msg":"trace[2006019299] transaction","detail":"{read_only:false; response_revision:7446; number_of_response:1; }","duration":"429.061977ms","start":"2026-05-21T19:45:45.252937Z","end":"2026-05-21T19:45:45.681999Z","steps":["trace[2006019299] 'process raft request' (duration: 428.813221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:45.682259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.67185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-05-21T19:45:45.682309Z","caller":"traceutil/trace.go:171","msg":"trace[1182906749] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7446; }","duration":"262.801465ms","start":"2026-05-21T19:45:45.419497Z","end":"2026-05-21T19:45:45.682298Z","steps":["trace[1182906749] 'agreement among raft nodes before linearized reading' (duration: 262.6073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:45.6823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:45.252917Z","time spent":"429.249143ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:45:45.682399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.48721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:45:45.682469Z","caller":"traceutil/trace.go:171","msg":"trace[1442913690] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:7446; }","duration":"161.622343ms","start":"2026-05-21T19:45:45.520831Z","end":"2026-05-21T19:45:45.682453Z","steps":["trace[1442913690] 'agreement among raft nodes before linearized reading' (duration: 161.49338ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:46.120443Z","caller":"traceutil/trace.go:171","msg":"trace[580393406] linearizableReadLoop","detail":"{readStateIndex:7790; appliedIndex:7789; }","duration":"326.402168ms","start":"2026-05-21T19:45:45.794017Z","end":"2026-05-21T19:45:46.120419Z","steps":["trace[580393406] 'read index received' (duration: 326.197982ms)","trace[580393406] 'applied index is now lower than readState.Index' (duration: 203.266µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:46.120583Z","caller":"traceutil/trace.go:171","msg":"trace[1767723389] transaction","detail":"{read_only:false; response_revision:7447; number_of_response:1; }","duration":"379.751805ms","start":"2026-05-21T19:45:45.740759Z","end":"2026-05-21T19:45:46.120511Z","steps":["trace[1767723389] 'process raft request' (duration: 379.458986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.12072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.688845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-21T19:45:46.120763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:45.740712Z","time spent":"379.95502ms","remote":"127.0.0.1:54300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:45:46.120792Z","caller":"traceutil/trace.go:171","msg":"trace[1633198853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7447; }","duration":"326.813788ms","start":"2026-05-21T19:45:45.793959Z","end":"2026-05-21T19:45:46.120773Z","steps":["trace[1633198853] 'agreement among raft nodes before linearized reading' (duration: 326.584732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.120822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:45.793938Z","time spent":"326.87868ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:45:46.120923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.499101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-21T19:45:46.121035Z","caller":"traceutil/trace.go:171","msg":"trace[1367189156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7447; }","duration":"113.666615ms","start":"2026-05-21T19:45:46.007305Z","end":"2026-05-21T19:45:46.120972Z","steps":["trace[1367189156] 'agreement among raft nodes before linearized reading' (duration: 113.349277ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:46.56193Z","caller":"traceutil/trace.go:171","msg":"trace[1677762168] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7447; }","duration":"430.389082ms","start":"2026-05-21T19:45:46.131526Z","end":"2026-05-21T19:45:46.561915Z","steps":["trace[1677762168] 'process raft request' (duration: 430.3169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.562041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.131504Z","time spent":"430.482724ms","remote":"127.0.0.1:54300","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:45:46.562074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.492203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-21T19:45:46.562114Z","caller":"traceutil/trace.go:171","msg":"trace[703589763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7447; }","duration":"430.553875ms","start":"2026-05-21T19:45:46.13155Z","end":"2026-05-21T19:45:46.562103Z","steps":["trace[703589763] 'agreement among raft nodes before linearized reading' (duration: 430.420661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.562145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.131534Z","time spent":"430.605808ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:45:46.56193Z","caller":"traceutil/trace.go:171","msg":"trace[119341216] linearizableReadLoop","detail":"{readStateIndex:7791; appliedIndex:7790; }","duration":"430.34345ms","start":"2026-05-21T19:45:46.131566Z","end":"2026-05-21T19:45:46.56191Z","steps":["trace[119341216] 'read index received' (duration: 430.33489ms)","trace[119341216] 'applied index is now lower than readState.Index' (duration: 7.47µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:46.604518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.077246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:45:46.60463Z","caller":"traceutil/trace.go:171","msg":"trace[752416501] transaction","detail":"{read_only:false; response_revision:7449; number_of_response:1; }","duration":"472.960718ms","start":"2026-05-21T19:45:46.131644Z","end":"2026-05-21T19:45:46.604605Z","steps":["trace[752416501] 'process raft request' (duration: 472.747613ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:46.604607Z","caller":"traceutil/trace.go:171","msg":"trace[770647442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7449; }","duration":"194.189759ms","start":"2026-05-21T19:45:46.410396Z","end":"2026-05-21T19:45:46.604586Z","steps":["trace[770647442] 'agreement among raft nodes before linearized reading' (duration: 194.069856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.604653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.213258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-21T19:45:46.604716Z","caller":"traceutil/trace.go:171","msg":"trace[960514944] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7449; }","duration":"437.31587ms","start":"2026-05-21T19:45:46.167383Z","end":"2026-05-21T19:45:46.604699Z","steps":["trace[960514944] 'agreement among raft nodes before linearized reading' (duration: 437.151826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.604771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.167362Z","time spent":"437.397413ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-05-21T19:45:46.604831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.131615Z","time spent":"473.06641ms","remote":"127.0.0.1:54130","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-05-21T19:45:46.604899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.954893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T19:45:46.604939Z","caller":"traceutil/trace.go:171","msg":"trace[1262516798] transaction","detail":"{read_only:false; response_revision:7448; number_of_response:1; }","duration":"473.286307ms","start":"2026-05-21T19:45:46.131634Z","end":"2026-05-21T19:45:46.604921Z","steps":["trace[1262516798] 'process raft request' (duration: 472.608699ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:46.60499Z","caller":"traceutil/trace.go:171","msg":"trace[1758400425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7449; }","duration":"204.137358ms","start":"2026-05-21T19:45:46.400832Z","end":"2026-05-21T19:45:46.604969Z","steps":["trace[1758400425] 'agreement among raft nodes before linearized reading' (duration: 203.825929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.604839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.459765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-21T19:45:46.605045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.131622Z","time spent":"473.371908ms","remote":"127.0.0.1:54130","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-05-21T19:45:46.605099Z","caller":"traceutil/trace.go:171","msg":"trace[1659876404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7449; }","duration":"437.750023ms","start":"2026-05-21T19:45:46.167334Z","end":"2026-05-21T19:45:46.605084Z","steps":["trace[1659876404] 'agreement among raft nodes before linearized reading' (duration: 437.385343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.605151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.167315Z","time spent":"437.823574ms","remote":"127.0.0.1:54130","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-05-21T19:45:46.605249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.448844ms","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-05-21T19:45:46.604628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.724599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-05-21T19:45:46.605326Z","caller":"traceutil/trace.go:171","msg":"trace[2010828644] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7449; }","duration":"127.550236ms","start":"2026-05-21T19:45:46.477754Z","end":"2026-05-21T19:45:46.605304Z","steps":["trace[2010828644] 'agreement among raft nodes before linearized reading' (duration: 127.378452ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:46.605354Z","caller":"traceutil/trace.go:171","msg":"trace[1235133947] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:7449; }","duration":"171.476939ms","start":"2026-05-21T19:45:46.433852Z","end":"2026-05-21T19:45:46.605329Z","steps":["trace[1235133947] 'agreement among raft nodes before linearized reading' (duration: 170.627677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.667367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.282272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-05-21T19:45:46.667451Z","caller":"traceutil/trace.go:171","msg":"trace[530113580] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:7450; }","duration":"104.395314ms","start":"2026-05-21T19:45:46.563039Z","end":"2026-05-21T19:45:46.667434Z","steps":["trace[530113580] 'agreement among raft nodes before linearized reading' (duration: 104.199279ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:46.667759Z","caller":"traceutil/trace.go:171","msg":"trace[1158669533] transaction","detail":"{read_only:false; response_revision:7450; number_of_response:1; }","duration":"101.387265ms","start":"2026-05-21T19:45:46.56634Z","end":"2026-05-21T19:45:46.667727Z","steps":["trace[1158669533] 'process raft request' (duration: 100.78768ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:46.965997Z","caller":"traceutil/trace.go:171","msg":"trace[2023037178] transaction","detail":"{read_only:false; response_revision:7451; number_of_response:1; }","duration":"356.372328ms","start":"2026-05-21T19:45:46.609599Z","end":"2026-05-21T19:45:46.965972Z","steps":["trace[2023037178] 'process raft request' (duration: 356.212114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.966148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.609546Z","time spent":"356.537343ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:45:46.966248Z","caller":"traceutil/trace.go:171","msg":"trace[1963859743] transaction","detail":"{read_only:false; response_revision:7452; number_of_response:1; }","duration":"356.341486ms","start":"2026-05-21T19:45:46.60989Z","end":"2026-05-21T19:45:46.966232Z","steps":["trace[1963859743] 'process raft request' (duration: 356.037948ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:46.966293Z","caller":"traceutil/trace.go:171","msg":"trace[1193397149] transaction","detail":"{read_only:false; response_revision:7453; number_of_response:1; }","duration":"356.226504ms","start":"2026-05-21T19:45:46.61006Z","end":"2026-05-21T19:45:46.966286Z","steps":["trace[1193397149] 'process raft request' (duration: 356.06839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.966356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.609874Z","time spent":"356.426269ms","remote":"127.0.0.1:54130","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-05-21T19:45:46.966355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.610035Z","time spent":"356.278665ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:45:46.966247Z","caller":"traceutil/trace.go:171","msg":"trace[1731107640] transaction","detail":"{read_only:false; response_revision:7454; number_of_response:1; }","duration":"355.082523ms","start":"2026-05-21T19:45:46.611152Z","end":"2026-05-21T19:45:46.966235Z","steps":["trace[1731107640] 'process raft request' (duration: 355.045172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:46.965977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.606647Z","time spent":"359.317004ms","remote":"127.0.0.1:53918","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T19:45:46.966485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.611138Z","time spent":"355.294739ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:45:47.245353Z","caller":"traceutil/trace.go:171","msg":"trace[1586611286] transaction","detail":"{read_only:false; response_revision:7455; number_of_response:1; }","duration":"565.596907ms","start":"2026-05-21T19:45:46.67972Z","end":"2026-05-21T19:45:47.245317Z","steps":["trace[1586611286] 'process raft request' (duration: 565.29132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:47.245597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.679668Z","time spent":"565.766173ms","remote":"127.0.0.1:54300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:45:47.245652Z","caller":"traceutil/trace.go:171","msg":"trace[1038750473] transaction","detail":"{read_only:false; response_revision:7456; number_of_response:1; }","duration":"507.724974ms","start":"2026-05-21T19:45:46.737854Z","end":"2026-05-21T19:45:47.245579Z","steps":["trace[1038750473] 'process raft request' (duration: 507.377254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:47.245794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.737834Z","time spent":"507.880077ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:45:47.24549Z","caller":"traceutil/trace.go:171","msg":"trace[726876716] linearizableReadLoop","detail":"{readStateIndex:7800; appliedIndex:7794; }","duration":"508.939805ms","start":"2026-05-21T19:45:46.736521Z","end":"2026-05-21T19:45:47.24546Z","steps":["trace[726876716] 'read index received' (duration: 229.133158ms)","trace[726876716] 'applied index is now lower than readState.Index' (duration: 279.804467ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:47.246084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.558911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5nvts\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:45:47.24613Z","caller":"traceutil/trace.go:171","msg":"trace[1621986859] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5nvts; range_end:; response_count:1; response_revision:7456; }","duration":"509.659814ms","start":"2026-05-21T19:45:46.736459Z","end":"2026-05-21T19:45:47.246118Z","steps":["trace[1621986859] 'agreement among raft nodes before linearized reading' (duration: 509.488559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:47.246163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.736428Z","time spent":"509.728516ms","remote":"127.0.0.1:54050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-5nvts\" "} {"level":"warn","ts":"2026-05-21T19:45:47.251684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.621189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:45:47.251689Z","caller":"traceutil/trace.go:171","msg":"trace[1983079738] transaction","detail":"{read_only:false; response_revision:7457; number_of_response:1; }","duration":"282.868146ms","start":"2026-05-21T19:45:46.968797Z","end":"2026-05-21T19:45:47.251665Z","steps":["trace[1983079738] 'process raft request' (duration: 282.559189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:47.2517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.593179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:45:47.251815Z","caller":"traceutil/trace.go:171","msg":"trace[2021933926] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:7457; }","duration":"322.775214ms","start":"2026-05-21T19:45:46.929011Z","end":"2026-05-21T19:45:47.251786Z","steps":["trace[2021933926] 'agreement among raft nodes before linearized reading' (duration: 322.539697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:47.251889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.92899Z","time spent":"322.887706ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:45:47.251748Z","caller":"traceutil/trace.go:171","msg":"trace[1850303008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7457; }","duration":"302.716073ms","start":"2026-05-21T19:45:46.949011Z","end":"2026-05-21T19:45:47.251727Z","steps":["trace[1850303008] 'agreement among raft nodes before linearized reading' (duration: 302.596209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:45:47.252005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:45:46.948997Z","time spent":"302.965198ms","remote":"127.0.0.1:53902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:45:47.399125Z","caller":"traceutil/trace.go:171","msg":"trace[698864128] transaction","detail":"{read_only:false; response_revision:7458; number_of_response:1; }","duration":"136.355866ms","start":"2026-05-21T19:45:47.262722Z","end":"2026-05-21T19:45:47.399077Z","steps":["trace[698864128] 'process raft request' (duration: 102.024983ms)","trace[698864128] 'compare' (duration: 34.083137ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:51.371702Z","caller":"traceutil/trace.go:171","msg":"trace[586549097] linearizableReadLoop","detail":"{readStateIndex:7827; appliedIndex:7826; }","duration":"101.998641ms","start":"2026-05-21T19:45:51.269677Z","end":"2026-05-21T19:45:51.371676Z","steps":["trace[586549097] 'read index received' (duration: 47.260078ms)","trace[586549097] 'applied index is now lower than readState.Index' (duration: 54.737103ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:45:51.371784Z","caller":"traceutil/trace.go:171","msg":"trace[1366588282] transaction","detail":"{read_only:false; response_revision:7482; number_of_response:1; }","duration":"111.087898ms","start":"2026-05-21T19:45:51.260656Z","end":"2026-05-21T19:45:51.371744Z","steps":["trace[1366588282] 'process raft request' (duration: 56.381436ms)","trace[1366588282] 'compare' (duration: 54.515447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:45:51.371926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.225838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5nvts\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:45:51.371967Z","caller":"traceutil/trace.go:171","msg":"trace[1702656713] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5nvts; range_end:; response_count:1; response_revision:7482; }","duration":"102.32738ms","start":"2026-05-21T19:45:51.269628Z","end":"2026-05-21T19:45:51.371956Z","steps":["trace[1702656713] 'agreement among raft nodes before linearized reading' (duration: 102.156916ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:45:51.652971Z","caller":"traceutil/trace.go:171","msg":"trace[498039157] transaction","detail":"{read_only:false; response_revision:7484; number_of_response:1; }","duration":"129.064646ms","start":"2026-05-21T19:45:51.523872Z","end":"2026-05-21T19:45:51.652937Z","steps":["trace[498039157] 'process raft request' (duration: 90.78761ms)","trace[498039157] 'compare' (duration: 38.010369ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:47:20.126724Z","caller":"traceutil/trace.go:171","msg":"trace[1000028200] transaction","detail":"{read_only:false; response_revision:8052; number_of_response:1; }","duration":"160.21876ms","start":"2026-05-21T19:47:19.966487Z","end":"2026-05-21T19:47:20.126706Z","steps":["trace[1000028200] 'process raft request' (duration: 160.085696ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:47:31.461716Z","caller":"traceutil/trace.go:171","msg":"trace[1882035983] transaction","detail":"{read_only:false; response_revision:8123; number_of_response:1; }","duration":"102.437646ms","start":"2026-05-21T19:47:31.359241Z","end":"2026-05-21T19:47:31.461679Z","steps":["trace[1882035983] 'process raft request' (duration: 43.632875ms)","trace[1882035983] 'compare' (duration: 58.566554ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:47:35.704358Z","caller":"traceutil/trace.go:171","msg":"trace[2002186132] transaction","detail":"{read_only:false; response_revision:8151; number_of_response:1; }","duration":"103.170585ms","start":"2026-05-21T19:47:35.601172Z","end":"2026-05-21T19:47:35.704342Z","steps":["trace[2002186132] 'process raft request' (duration: 103.025471ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:47:35.981618Z","caller":"traceutil/trace.go:171","msg":"trace[1907537330] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"224.221194ms","start":"2026-05-21T19:47:35.757357Z","end":"2026-05-21T19:47:35.981578Z","steps":["trace[1907537330] 'process raft request' (duration: 223.941687ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:47:35.999933Z","caller":"traceutil/trace.go:171","msg":"trace[253888896] transaction","detail":"{read_only:false; response_revision:8156; number_of_response:1; }","duration":"165.887937ms","start":"2026-05-21T19:47:35.834008Z","end":"2026-05-21T19:47:35.999896Z","steps":["trace[253888896] 'process raft request' (duration: 165.542528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:01.873391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.528242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:01.873584Z","caller":"traceutil/trace.go:171","msg":"trace[1737008419] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"170.357653ms","start":"2026-05-21T19:48:01.703182Z","end":"2026-05-21T19:48:01.87354Z","steps":["trace[1737008419] 'process raft request' (duration: 53.615956ms)","trace[1737008419] 'compare' (duration: 116.432479ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:01.873673Z","caller":"traceutil/trace.go:171","msg":"trace[2010565245] linearizableReadLoop","detail":"{readStateIndex:8712; appliedIndex:8711; }","duration":"167.629033ms","start":"2026-05-21T19:48:01.705981Z","end":"2026-05-21T19:48:01.87361Z","steps":["trace[2010565245] 'read index received' (duration: 4.278661ms)","trace[2010565245] 'applied index is now lower than readState.Index' (duration: 163.345541ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:01.87371Z","caller":"traceutil/trace.go:171","msg":"trace[1862694051] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"139.46111ms","start":"2026-05-21T19:48:01.734215Z","end":"2026-05-21T19:48:01.873677Z","steps":["trace[1862694051] 'process raft request' (duration: 139.259804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:02.025335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.308339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:48:02.02543Z","caller":"traceutil/trace.go:171","msg":"trace[954138367] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:8317; }","duration":"319.456353ms","start":"2026-05-21T19:48:01.705948Z","end":"2026-05-21T19:48:02.025404Z","steps":["trace[954138367] 'agreement among raft nodes before linearized reading' (duration: 167.813637ms)","trace[954138367] 'range keys from in-memory index tree' (duration: 151.476671ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:02.025417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.874972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-21T19:48:02.025482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:01.705938Z","time spent":"319.529915ms","remote":"127.0.0.1:53962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"info","ts":"2026-05-21T19:48:02.025521Z","caller":"traceutil/trace.go:171","msg":"trace[944539955] linearizableReadLoop","detail":"{readStateIndex:8714; appliedIndex:8713; }","duration":"151.703238ms","start":"2026-05-21T19:48:01.8738Z","end":"2026-05-21T19:48:02.025503Z","steps":["trace[944539955] 'read index received' (duration: 110.353µs)","trace[944539955] 'applied index is now lower than readState.Index' (duration: 151.591175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:02.025571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.347138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:48:02.025655Z","caller":"traceutil/trace.go:171","msg":"trace[1792677858] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:8317; }","duration":"224.450962ms","start":"2026-05-21T19:48:01.801185Z","end":"2026-05-21T19:48:02.025636Z","steps":["trace[1792677858] 'agreement among raft nodes before linearized reading' (duration: 224.331979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:02.025674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.967743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-21T19:48:02.025737Z","caller":"traceutil/trace.go:171","msg":"trace[1168389338] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8317; }","duration":"160.066815ms","start":"2026-05-21T19:48:01.865648Z","end":"2026-05-21T19:48:02.025715Z","steps":["trace[1168389338] 'agreement among raft nodes before linearized reading' (duration: 159.891201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:02.025819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.103748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T19:48:02.025924Z","caller":"traceutil/trace.go:171","msg":"trace[343883959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8317; }","duration":"230.217741ms","start":"2026-05-21T19:48:01.795683Z","end":"2026-05-21T19:48:02.025901Z","steps":["trace[343883959] 'agreement among raft nodes before linearized reading' (duration: 229.894733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:02.025899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.209721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T19:48:02.026094Z","caller":"traceutil/trace.go:171","msg":"trace[264324974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8317; }","duration":"162.477018ms","start":"2026-05-21T19:48:01.863573Z","end":"2026-05-21T19:48:02.02605Z","steps":["trace[264324974] 'agreement among raft nodes before linearized reading' (duration: 161.950595ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:02.156088Z","caller":"traceutil/trace.go:171","msg":"trace[432651975] linearizableReadLoop","detail":"{readStateIndex:8718; appliedIndex:8715; }","duration":"117.298124ms","start":"2026-05-21T19:48:02.038766Z","end":"2026-05-21T19:48:02.156064Z","steps":["trace[432651975] 'read index received' (duration: 117.039367ms)","trace[432651975] 'applied index is now lower than readState.Index' (duration: 257.637µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:02.156164Z","caller":"traceutil/trace.go:171","msg":"trace[1208318949] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"126.091281ms","start":"2026-05-21T19:48:02.030038Z","end":"2026-05-21T19:48:02.156129Z","steps":["trace[1208318949] 'process raft request' (duration: 125.953868ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:02.156164Z","caller":"traceutil/trace.go:171","msg":"trace[127199391] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"126.100552ms","start":"2026-05-21T19:48:02.030016Z","end":"2026-05-21T19:48:02.156116Z","steps":["trace[127199391] 'process raft request' (duration: 125.933308ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:02.156213Z","caller":"traceutil/trace.go:171","msg":"trace[1553435581] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"126.40572ms","start":"2026-05-21T19:48:02.029777Z","end":"2026-05-21T19:48:02.156183Z","steps":["trace[1553435581] 'process raft request' (duration: 126.00477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:02.15651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.737675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-05-21T19:48:02.156566Z","caller":"traceutil/trace.go:171","msg":"trace[1696841434] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:8320; }","duration":"117.806847ms","start":"2026-05-21T19:48:02.038745Z","end":"2026-05-21T19:48:02.156552Z","steps":["trace[1696841434] 'agreement among raft nodes before linearized reading' (duration: 117.443177ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:33.372815Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6565} {"level":"info","ts":"2026-05-21T19:48:33.478351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6565,"took":"102.326973ms","hash":897883757,"current-db-size-bytes":60108800,"current-db-size":"60 MB","current-db-size-in-use-bytes":18726912,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-05-21T19:48:33.478429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":897883757,"revision":6565,"compact-revision":3183} {"level":"info","ts":"2026-05-21T19:48:34.428867Z","caller":"traceutil/trace.go:171","msg":"trace[2031035593] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"119.449009ms","start":"2026-05-21T19:48:34.309379Z","end":"2026-05-21T19:48:34.428828Z","steps":["trace[2031035593] 'process raft request' (duration: 104.833208ms)","trace[2031035593] 'compare' (duration: 14.480757ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:34.429333Z","caller":"traceutil/trace.go:171","msg":"trace[1420173600] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"119.793667ms","start":"2026-05-21T19:48:34.30952Z","end":"2026-05-21T19:48:34.429313Z","steps":["trace[1420173600] 'process raft request' (duration: 119.612553ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:36.61807Z","caller":"traceutil/trace.go:171","msg":"trace[1765347323] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"124.49817ms","start":"2026-05-21T19:48:36.493552Z","end":"2026-05-21T19:48:36.61805Z","steps":["trace[1765347323] 'process raft request' (duration: 124.465749ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:36.618105Z","caller":"traceutil/trace.go:171","msg":"trace[1313527790] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"181.417541ms","start":"2026-05-21T19:48:36.436652Z","end":"2026-05-21T19:48:36.61807Z","steps":["trace[1313527790] 'process raft request' (duration: 112.493237ms)","trace[1313527790] 'compare' (duration: 68.75407ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:36.618146Z","caller":"traceutil/trace.go:171","msg":"trace[1700968962] linearizableReadLoop","detail":"{readStateIndex:8952; appliedIndex:8950; }","duration":"175.140458ms","start":"2026-05-21T19:48:36.442971Z","end":"2026-05-21T19:48:36.618111Z","steps":["trace[1700968962] 'read index received' (duration: 4.540348ms)","trace[1700968962] 'applied index is now lower than readState.Index' (duration: 170.59748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:36.618362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.137822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-21T19:48:36.618373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.383794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.44\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-05-21T19:48:36.618397Z","caller":"traceutil/trace.go:171","msg":"trace[1412415314] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8542; }","duration":"174.214074ms","start":"2026-05-21T19:48:36.444174Z","end":"2026-05-21T19:48:36.618388Z","steps":["trace[1412415314] 'agreement among raft nodes before linearized reading' (duration: 174.100021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:36.618387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.822808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5nvts\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:48:36.618428Z","caller":"traceutil/trace.go:171","msg":"trace[596794870] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5nvts; range_end:; response_count:1; response_revision:8542; }","duration":"172.952431ms","start":"2026-05-21T19:48:36.445468Z","end":"2026-05-21T19:48:36.61842Z","steps":["trace[596794870] 'agreement among raft nodes before linearized reading' (duration: 172.831048ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:36.618427Z","caller":"traceutil/trace.go:171","msg":"trace[345606263] range","detail":"{range_begin:/registry/masterleases/199.19.213.44; range_end:; response_count:1; response_revision:8542; }","duration":"175.469726ms","start":"2026-05-21T19:48:36.442941Z","end":"2026-05-21T19:48:36.61841Z","steps":["trace[345606263] 'agreement among raft nodes before linearized reading' (duration: 175.279201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:37.021612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.395908ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-21T19:48:37.021709Z","caller":"traceutil/trace.go:171","msg":"trace[390003841] linearizableReadLoop","detail":"{readStateIndex:8954; appliedIndex:8953; }","duration":"399.531796ms","start":"2026-05-21T19:48:36.62216Z","end":"2026-05-21T19:48:37.021691Z","steps":["trace[390003841] 'read index received' (duration: 270.076287ms)","trace[390003841] 'applied index is now lower than readState.Index' (duration: 129.453669ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:37.021743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:36.619709Z","time spent":"402.021741ms","remote":"127.0.0.1:53918","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T19:48:37.022055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.895966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:16 size:130234"} {"level":"info","ts":"2026-05-21T19:48:37.022091Z","caller":"traceutil/trace.go:171","msg":"trace[2056369527] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:16; response_revision:8542; }","duration":"399.989509ms","start":"2026-05-21T19:48:36.622094Z","end":"2026-05-21T19:48:37.022084Z","steps":["trace[2056369527] 'agreement among raft nodes before linearized reading' (duration: 399.65961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:37.022116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:36.622064Z","time spent":"400.04623ms","remote":"127.0.0.1:54050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":16,"response size":130257,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-05-21T19:48:37.034677Z","caller":"traceutil/trace.go:171","msg":"trace[1497864557] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"411.569291ms","start":"2026-05-21T19:48:36.62307Z","end":"2026-05-21T19:48:37.034639Z","steps":["trace[1497864557] 'process raft request' (duration: 411.366536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:37.034689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.047034ms","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-05-21T19:48:37.034883Z","caller":"traceutil/trace.go:171","msg":"trace[1191217729] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8543; }","duration":"344.29892ms","start":"2026-05-21T19:48:36.69056Z","end":"2026-05-21T19:48:37.034859Z","steps":["trace[1191217729] 'agreement among raft nodes before linearized reading' (duration: 344.038154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:37.034908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:36.623049Z","time spent":"411.731494ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:37.03499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:36.690516Z","time spent":"344.442424ms","remote":"127.0.0.1:54014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":5,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:48:38.065977Z","caller":"traceutil/trace.go:171","msg":"trace[1950474723] linearizableReadLoop","detail":"{readStateIndex:8959; appliedIndex:8958; }","duration":"151.045121ms","start":"2026-05-21T19:48:37.914904Z","end":"2026-05-21T19:48:38.065949Z","steps":["trace[1950474723] 'read index received' (duration: 64.738955ms)","trace[1950474723] 'applied index is now lower than readState.Index' (duration: 86.304546ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:38.066232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.300137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"warn","ts":"2026-05-21T19:48:38.066247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.722385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:48:38.06632Z","caller":"traceutil/trace.go:171","msg":"trace[977900464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8547; }","duration":"119.816948ms","start":"2026-05-21T19:48:37.946484Z","end":"2026-05-21T19:48:38.066301Z","steps":["trace[977900464] 'agreement among raft nodes before linearized reading' (duration: 119.709806ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:38.066192Z","caller":"traceutil/trace.go:171","msg":"trace[1527148009] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"161.287797ms","start":"2026-05-21T19:48:37.904756Z","end":"2026-05-21T19:48:38.066044Z","steps":["trace[1527148009] 'process raft request' (duration: 74.94974ms)","trace[1527148009] 'compare' (duration: 86.119062ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:38.066277Z","caller":"traceutil/trace.go:171","msg":"trace[203722536] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8547; }","duration":"151.380249ms","start":"2026-05-21T19:48:37.914881Z","end":"2026-05-21T19:48:38.066262Z","steps":["trace[203722536] 'agreement among raft nodes before linearized reading' (duration: 151.223285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:38.345164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.94539ms","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-05-21T19:48:38.345319Z","caller":"traceutil/trace.go:171","msg":"trace[1308788573] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8547; }","duration":"123.125054ms","start":"2026-05-21T19:48:38.222175Z","end":"2026-05-21T19:48:38.3453Z","steps":["trace[1308788573] 'range keys from in-memory index tree' (duration: 122.9277ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:38.366394Z","caller":"traceutil/trace.go:171","msg":"trace[1133414135] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"295.578512ms","start":"2026-05-21T19:48:38.070793Z","end":"2026-05-21T19:48:38.366371Z","steps":["trace[1133414135] 'process raft request' (duration: 295.455559ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:38.380027Z","caller":"traceutil/trace.go:171","msg":"trace[900127582] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"308.831329ms","start":"2026-05-21T19:48:38.071155Z","end":"2026-05-21T19:48:38.379987Z","steps":["trace[900127582] 'process raft request' (duration: 308.602642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:38.380176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:38.07113Z","time spent":"308.964741ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:48:38.380408Z","caller":"traceutil/trace.go:171","msg":"trace[433819159] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"309.077124ms","start":"2026-05-21T19:48:38.071319Z","end":"2026-05-21T19:48:38.380396Z","steps":["trace[433819159] 'process raft request' (duration: 308.625211ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:38.380446Z","caller":"traceutil/trace.go:171","msg":"trace[956986662] linearizableReadLoop","detail":"{readStateIndex:8963; appliedIndex:8959; }","duration":"271.3148ms","start":"2026-05-21T19:48:38.10911Z","end":"2026-05-21T19:48:38.380425Z","steps":["trace[956986662] 'read index received' (duration: 257.210583ms)","trace[956986662] 'applied index is now lower than readState.Index' (duration: 14.103207ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:38.380535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:38.07131Z","time spent":"309.169286ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:48:38.380523Z","caller":"traceutil/trace.go:171","msg":"trace[812045302] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"307.165113ms","start":"2026-05-21T19:48:38.073327Z","end":"2026-05-21T19:48:38.380492Z","steps":["trace[812045302] 'process raft request' (duration: 306.951638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:38.380687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:38.073315Z","time spent":"307.274036ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:38.380712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.239171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:48:38.380751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.629939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-05-21T19:48:38.380825Z","caller":"traceutil/trace.go:171","msg":"trace[1365204881] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8552; }","duration":"271.742792ms","start":"2026-05-21T19:48:38.109061Z","end":"2026-05-21T19:48:38.380804Z","steps":["trace[1365204881] 'agreement among raft nodes before linearized reading' (duration: 271.535856ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:38.380824Z","caller":"traceutil/trace.go:171","msg":"trace[1341898232] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8552; }","duration":"117.374585ms","start":"2026-05-21T19:48:38.263418Z","end":"2026-05-21T19:48:38.380793Z","steps":["trace[1341898232] 'agreement among raft nodes before linearized reading' (duration: 117.174719ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:38.773763Z","caller":"traceutil/trace.go:171","msg":"trace[1924546724] linearizableReadLoop","detail":"{readStateIndex:8965; appliedIndex:8964; }","duration":"319.225187ms","start":"2026-05-21T19:48:38.45452Z","end":"2026-05-21T19:48:38.773745Z","steps":["trace[1924546724] 'read index received' (duration: 284.600296ms)","trace[1924546724] 'applied index is now lower than readState.Index' (duration: 34.624161ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:38.773801Z","caller":"traceutil/trace.go:171","msg":"trace[73928603] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"344.441713ms","start":"2026-05-21T19:48:38.429325Z","end":"2026-05-21T19:48:38.773767Z","steps":["trace[73928603] 'process raft request' (duration: 309.901425ms)","trace[73928603] 'compare' (duration: 34.300132ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:38.77393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.878883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-21T19:48:38.773953Z","caller":"traceutil/trace.go:171","msg":"trace[1442378531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8553; }","duration":"151.915254ms","start":"2026-05-21T19:48:38.622032Z","end":"2026-05-21T19:48:38.773947Z","steps":["trace[1442378531] 'agreement among raft nodes before linearized reading' (duration: 151.806301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:38.77395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:38.429294Z","time spent":"344.575707ms","remote":"127.0.0.1:54300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:38.773983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.446923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T19:48:38.774072Z","caller":"traceutil/trace.go:171","msg":"trace[1884222422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8553; }","duration":"319.583126ms","start":"2026-05-21T19:48:38.454467Z","end":"2026-05-21T19:48:38.77405Z","steps":["trace[1884222422] 'agreement among raft nodes before linearized reading' (duration: 319.34002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:38.774139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:38.454445Z","time spent":"319.679689ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T19:48:38.927361Z","caller":"traceutil/trace.go:171","msg":"trace[1060922141] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"148.446853ms","start":"2026-05-21T19:48:38.778891Z","end":"2026-05-21T19:48:38.927338Z","steps":["trace[1060922141] 'process raft request' (duration: 147.781046ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:39.157628Z","caller":"traceutil/trace.go:171","msg":"trace[727730055] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8555; }","duration":"377.006811ms","start":"2026-05-21T19:48:38.780596Z","end":"2026-05-21T19:48:39.157603Z","steps":["trace[727730055] 'process raft request' (duration: 376.914909ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:39.157696Z","caller":"traceutil/trace.go:171","msg":"trace[1745976791] linearizableReadLoop","detail":"{readStateIndex:8968; appliedIndex:8966; }","duration":"129.668514ms","start":"2026-05-21T19:48:39.027924Z","end":"2026-05-21T19:48:39.157593Z","steps":["trace[1745976791] 'read index received' (duration: 76.768068ms)","trace[1745976791] 'applied index is now lower than readState.Index' (duration: 52.899186ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:39.157785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:38.780587Z","time spent":"377.130174ms","remote":"127.0.0.1:54300","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:48:39.1578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.851599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5nvts\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:48:39.157887Z","caller":"traceutil/trace.go:171","msg":"trace[588181766] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5nvts; range_end:; response_count:1; response_revision:8555; }","duration":"129.974682ms","start":"2026-05-21T19:48:39.027891Z","end":"2026-05-21T19:48:39.157866Z","steps":["trace[588181766] 'agreement among raft nodes before linearized reading' (duration: 129.705315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:39.157977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.543026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-21T19:48:39.158026Z","caller":"traceutil/trace.go:171","msg":"trace[1409424862] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8555; }","duration":"118.654819ms","start":"2026-05-21T19:48:39.039359Z","end":"2026-05-21T19:48:39.158014Z","steps":["trace[1409424862] 'agreement among raft nodes before linearized reading' (duration: 118.446624ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:39.157635Z","caller":"traceutil/trace.go:171","msg":"trace[1738314436] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"378.706806ms","start":"2026-05-21T19:48:38.778885Z","end":"2026-05-21T19:48:39.157592Z","steps":["trace[1738314436] 'process raft request' (duration: 325.906342ms)","trace[1738314436] 'compare' (duration: 52.429704ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:39.158237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:38.778857Z","time spent":"379.272411ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:48:39.287646Z","caller":"traceutil/trace.go:171","msg":"trace[271890916] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"124.655323ms","start":"2026-05-21T19:48:39.162973Z","end":"2026-05-21T19:48:39.287628Z","steps":["trace[271890916] 'process raft request' (duration: 122.872176ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:39.302795Z","caller":"traceutil/trace.go:171","msg":"trace[1311988006] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"132.857398ms","start":"2026-05-21T19:48:39.169912Z","end":"2026-05-21T19:48:39.302769Z","steps":["trace[1311988006] 'process raft request' (duration: 132.725763ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:41.105353Z","caller":"traceutil/trace.go:171","msg":"trace[1212384615] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"160.538747ms","start":"2026-05-21T19:48:40.94479Z","end":"2026-05-21T19:48:41.105328Z","steps":["trace[1212384615] 'process raft request' (duration: 160.391023ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:41.426216Z","caller":"traceutil/trace.go:171","msg":"trace[1002154990] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"130.432654ms","start":"2026-05-21T19:48:41.295748Z","end":"2026-05-21T19:48:41.426181Z","steps":["trace[1002154990] 'process raft request' (duration: 56.127141ms)","trace[1002154990] 'compare' (duration: 73.80898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:41.730357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.312657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:41.730457Z","caller":"traceutil/trace.go:171","msg":"trace[953417514] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"337.37887ms","start":"2026-05-21T19:48:41.393062Z","end":"2026-05-21T19:48:41.730441Z","steps":["trace[953417514] 'process raft request' (duration: 205.895918ms)","trace[953417514] 'compare' (duration: 131.126773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:41.730682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:41.393034Z","time spent":"337.628757ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:42.761459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.940912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:42.761783Z","caller":"traceutil/trace.go:171","msg":"trace[1135936255] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"239.207545ms","start":"2026-05-21T19:48:42.522557Z","end":"2026-05-21T19:48:42.761764Z","steps":["trace[1135936255] 'process raft request' (duration: 239.161664ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:42.7619Z","caller":"traceutil/trace.go:171","msg":"trace[1956034487] transaction","detail":"{read_only:false; response_revision:8573; number_of_response:1; }","duration":"253.906567ms","start":"2026-05-21T19:48:42.507943Z","end":"2026-05-21T19:48:42.76185Z","steps":["trace[1956034487] 'process raft request' (duration: 253.63728ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:42.762057Z","caller":"traceutil/trace.go:171","msg":"trace[689466342] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"250.962672ms","start":"2026-05-21T19:48:42.511067Z","end":"2026-05-21T19:48:42.76203Z","steps":["trace[689466342] 'process raft request' (duration: 250.620113ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:42.762125Z","caller":"traceutil/trace.go:171","msg":"trace[409189979] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"254.889263ms","start":"2026-05-21T19:48:42.507222Z","end":"2026-05-21T19:48:42.762111Z","steps":["trace[409189979] 'process raft request' (duration: 104.175661ms)","trace[409189979] 'compare' (duration: 149.658004ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:42.763112Z","caller":"traceutil/trace.go:171","msg":"trace[213524168] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"252.101801ms","start":"2026-05-21T19:48:42.510963Z","end":"2026-05-21T19:48:42.763064Z","steps":["trace[213524168] 'process raft request' (duration: 250.679484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:43.064432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.515455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:43.064548Z","caller":"traceutil/trace.go:171","msg":"trace[670633865] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"294.965845ms","start":"2026-05-21T19:48:42.769564Z","end":"2026-05-21T19:48:43.06453Z","steps":["trace[670633865] 'process raft request' (duration: 136.258375ms)","trace[670633865] 'compare' (duration: 158.373071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:43.319276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.798238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:43.319446Z","caller":"traceutil/trace.go:171","msg":"trace[594579912] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"205.814966ms","start":"2026-05-21T19:48:43.113597Z","end":"2026-05-21T19:48:43.319412Z","steps":["trace[594579912] 'process raft request' (duration: 70.752701ms)","trace[594579912] 'compare' (duration: 134.489359ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:43.319428Z","caller":"traceutil/trace.go:171","msg":"trace[912897200] linearizableReadLoop","detail":"{readStateIndex:8993; appliedIndex:8992; }","duration":"153.526395ms","start":"2026-05-21T19:48:43.165875Z","end":"2026-05-21T19:48:43.319402Z","steps":["trace[912897200] 'read index received' (duration: 18.495091ms)","trace[912897200] 'applied index is now lower than readState.Index' (duration: 135.029124ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:43.319951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.036028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:48:43.320144Z","caller":"traceutil/trace.go:171","msg":"trace[1178774907] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8579; }","duration":"154.297895ms","start":"2026-05-21T19:48:43.165821Z","end":"2026-05-21T19:48:43.320119Z","steps":["trace[1178774907] 'agreement among raft nodes before linearized reading' (duration: 153.959296ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:44.323424Z","caller":"traceutil/trace.go:171","msg":"trace[2075712273] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"194.515453ms","start":"2026-05-21T19:48:44.128877Z","end":"2026-05-21T19:48:44.323392Z","steps":["trace[2075712273] 'process raft request' (duration: 194.377779ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:44.495255Z","caller":"traceutil/trace.go:171","msg":"trace[1967595680] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"104.687464ms","start":"2026-05-21T19:48:44.390541Z","end":"2026-05-21T19:48:44.495228Z","steps":["trace[1967595680] 'process raft request' (duration: 104.498689ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:44.67599Z","caller":"traceutil/trace.go:171","msg":"trace[416089932] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8583; }","duration":"173.392652ms","start":"2026-05-21T19:48:44.502569Z","end":"2026-05-21T19:48:44.675962Z","steps":["trace[416089932] 'process raft request' (duration: 123.586965ms)","trace[416089932] 'compare' (duration: 49.755625ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:44.841185Z","caller":"traceutil/trace.go:171","msg":"trace[733784478] linearizableReadLoop","detail":"{readStateIndex:8999; appliedIndex:8998; }","duration":"163.998208ms","start":"2026-05-21T19:48:44.67716Z","end":"2026-05-21T19:48:44.841158Z","steps":["trace[733784478] 'read index received' (duration: 163.72089ms)","trace[733784478] 'applied index is now lower than readState.Index' (duration: 276.228µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:44.841216Z","caller":"traceutil/trace.go:171","msg":"trace[544958035] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"332.916373ms","start":"2026-05-21T19:48:44.508277Z","end":"2026-05-21T19:48:44.841193Z","steps":["trace[544958035] 'process raft request' (duration: 332.674986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:44.841693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:44.508255Z","time spent":"333.331234ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:44.841963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.204594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-05-21T19:48:44.842016Z","caller":"traceutil/trace.go:171","msg":"trace[1359476049] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:8584; }","duration":"164.902342ms","start":"2026-05-21T19:48:44.677105Z","end":"2026-05-21T19:48:44.842008Z","steps":["trace[1359476049] 'agreement among raft nodes before linearized reading' (duration: 164.157133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.120768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.151617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:45.121144Z","caller":"traceutil/trace.go:171","msg":"trace[1347932031] linearizableReadLoop","detail":"{readStateIndex:9001; appliedIndex:8999; }","duration":"265.412867ms","start":"2026-05-21T19:48:44.855711Z","end":"2026-05-21T19:48:45.121123Z","steps":["trace[1347932031] 'read index received' (duration: 36.812199ms)","trace[1347932031] 'applied index is now lower than readState.Index' (duration: 228.599568ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:45.121238Z","caller":"traceutil/trace.go:171","msg":"trace[1242568950] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"275.504959ms","start":"2026-05-21T19:48:44.845683Z","end":"2026-05-21T19:48:45.121188Z","steps":["trace[1242568950] 'process raft request' (duration: 275.200401ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:45.121348Z","caller":"traceutil/trace.go:171","msg":"trace[530939067] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"253.009164ms","start":"2026-05-21T19:48:44.868299Z","end":"2026-05-21T19:48:45.121308Z","steps":["trace[530939067] 'process raft request' (duration: 252.655435ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:45.121404Z","caller":"traceutil/trace.go:171","msg":"trace[346591769] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"276.427874ms","start":"2026-05-21T19:48:44.844904Z","end":"2026-05-21T19:48:45.121332Z","steps":["trace[346591769] 'process raft request' (duration: 47.597889ms)","trace[346591769] 'compare' (duration: 228.022454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:45.12142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.819181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:48:45.12153Z","caller":"traceutil/trace.go:171","msg":"trace[1495141121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8587; }","duration":"171.998976ms","start":"2026-05-21T19:48:44.949507Z","end":"2026-05-21T19:48:45.121506Z","steps":["trace[1495141121] 'agreement among raft nodes before linearized reading' (duration: 171.828252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.121526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.787897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-21T19:48:45.121629Z","caller":"traceutil/trace.go:171","msg":"trace[788429023] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8587; }","duration":"265.924491ms","start":"2026-05-21T19:48:44.855674Z","end":"2026-05-21T19:48:45.121598Z","steps":["trace[788429023] 'agreement among raft nodes before linearized reading' (duration: 265.613203ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:45.434459Z","caller":"traceutil/trace.go:171","msg":"trace[637091133] linearizableReadLoop","detail":"{readStateIndex:9004; appliedIndex:9003; }","duration":"292.171323ms","start":"2026-05-21T19:48:45.142248Z","end":"2026-05-21T19:48:45.43442Z","steps":["trace[637091133] 'read index received' (duration: 234.335638ms)","trace[637091133] 'applied index is now lower than readState.Index' (duration: 57.834415ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:45.434547Z","caller":"traceutil/trace.go:171","msg":"trace[355617812] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"303.512118ms","start":"2026-05-21T19:48:45.131003Z","end":"2026-05-21T19:48:45.434515Z","steps":["trace[355617812] 'process raft request' (duration: 245.659362ms)","trace[355617812] 'compare' (duration: 57.537668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:45.434728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:45.130988Z","time spent":"303.615401ms","remote":"127.0.0.1:53988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:45.434742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.46594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-05-21T19:48:45.434825Z","caller":"traceutil/trace.go:171","msg":"trace[109976314] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8589; }","duration":"292.553513ms","start":"2026-05-21T19:48:45.142237Z","end":"2026-05-21T19:48:45.43479Z","steps":["trace[109976314] 'agreement among raft nodes before linearized reading' (duration: 292.319067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.435035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.343788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-05-21T19:48:45.435091Z","caller":"traceutil/trace.go:171","msg":"trace[1784197262] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8589; }","duration":"287.431791ms","start":"2026-05-21T19:48:45.147643Z","end":"2026-05-21T19:48:45.435075Z","steps":["trace[1784197262] 'agreement among raft nodes before linearized reading' (duration: 287.205395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.435147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.882087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5nvts\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:48:45.435234Z","caller":"traceutil/trace.go:171","msg":"trace[238559956] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5nvts; range_end:; response_count:1; response_revision:8589; }","duration":"103.068992ms","start":"2026-05-21T19:48:45.33215Z","end":"2026-05-21T19:48:45.435219Z","steps":["trace[238559956] 'agreement among raft nodes before linearized reading' (duration: 102.763545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.435196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.862662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T19:48:45.435297Z","caller":"traceutil/trace.go:171","msg":"trace[1329781167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8589; }","duration":"112.018216ms","start":"2026-05-21T19:48:45.323255Z","end":"2026-05-21T19:48:45.435273Z","steps":["trace[1329781167] 'agreement among raft nodes before linearized reading' (duration: 111.669147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.859718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.5257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:45.860444Z","caller":"traceutil/trace.go:171","msg":"trace[328406216] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"420.502732ms","start":"2026-05-21T19:48:45.439867Z","end":"2026-05-21T19:48:45.86037Z","steps":["trace[328406216] 'process raft request' (duration: 207.207922ms)","trace[328406216] 'compare' (duration: 212.384207ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:45.86049Z","caller":"traceutil/trace.go:171","msg":"trace[1837516323] linearizableReadLoop","detail":"{readStateIndex:9007; appliedIndex:9005; }","duration":"347.268447ms","start":"2026-05-21T19:48:45.513199Z","end":"2026-05-21T19:48:45.860467Z","steps":["trace[1837516323] 'read index received' (duration: 133.808343ms)","trace[1837516323] 'applied index is now lower than readState.Index' (duration: 213.458274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:45.860629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:45.439847Z","time spent":"420.691827ms","remote":"127.0.0.1:54130","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-05-21T19:48:45.860923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.842606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-21T19:48:45.860965Z","caller":"traceutil/trace.go:171","msg":"trace[1610003799] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8592; }","duration":"273.914538ms","start":"2026-05-21T19:48:45.587039Z","end":"2026-05-21T19:48:45.860954Z","steps":["trace[1610003799] 'agreement among raft nodes before linearized reading' (duration: 273.753264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.860942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.60136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-21T19:48:45.86104Z","caller":"traceutil/trace.go:171","msg":"trace[1800495943] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"420.700128ms","start":"2026-05-21T19:48:45.440307Z","end":"2026-05-21T19:48:45.861007Z","steps":["trace[1800495943] 'process raft request' (duration: 420.039351ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:45.861049Z","caller":"traceutil/trace.go:171","msg":"trace[618052887] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8592; }","duration":"421.760805ms","start":"2026-05-21T19:48:45.439258Z","end":"2026-05-21T19:48:45.861019Z","steps":["trace[618052887] 'agreement among raft nodes before linearized reading' (duration: 421.462068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.861117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:45.439232Z","time spent":"421.871577ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-21T19:48:45.861149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:45.440299Z","time spent":"420.79519ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:45.861196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.850687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-05-21T19:48:45.861292Z","caller":"traceutil/trace.go:171","msg":"trace[1504629218] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8592; }","duration":"412.005502ms","start":"2026-05-21T19:48:45.449265Z","end":"2026-05-21T19:48:45.861271Z","steps":["trace[1504629218] 'agreement among raft nodes before linearized reading' (duration: 411.773386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:45.861333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:45.449235Z","time spent":"412.089084ms","remote":"127.0.0.1:42970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4853,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-21T19:48:45.861386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.276067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:48:45.861564Z","caller":"traceutil/trace.go:171","msg":"trace[1191047761] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:8592; }","duration":"141.551554ms","start":"2026-05-21T19:48:45.719974Z","end":"2026-05-21T19:48:45.861525Z","steps":["trace[1191047761] 'agreement among raft nodes before linearized reading' (duration: 141.02469ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:46.1688Z","caller":"traceutil/trace.go:171","msg":"trace[228506976] linearizableReadLoop","detail":"{readStateIndex:9009; appliedIndex:9008; }","duration":"219.121772ms","start":"2026-05-21T19:48:45.94965Z","end":"2026-05-21T19:48:46.168772Z","steps":["trace[228506976] 'read index received' (duration: 218.901467ms)","trace[228506976] 'applied index is now lower than readState.Index' (duration: 218.445µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:46.168817Z","caller":"traceutil/trace.go:171","msg":"trace[1606963623] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"303.118308ms","start":"2026-05-21T19:48:45.86565Z","end":"2026-05-21T19:48:46.168768Z","steps":["trace[1606963623] 'process raft request' (duration: 302.900523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:46.169043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:45.865626Z","time spent":"303.291502ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:46.168931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.269716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:48:46.169109Z","caller":"traceutil/trace.go:171","msg":"trace[1724551606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8594; }","duration":"219.509321ms","start":"2026-05-21T19:48:45.949587Z","end":"2026-05-21T19:48:46.169096Z","steps":["trace[1724551606] 'agreement among raft nodes before linearized reading' (duration: 219.285676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:46.980678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.043686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:46.980869Z","caller":"traceutil/trace.go:171","msg":"trace[2013167958] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"298.758824ms","start":"2026-05-21T19:48:46.68208Z","end":"2026-05-21T19:48:46.980839Z","steps":["trace[2013167958] 'process raft request' (duration: 88.436401ms)","trace[2013167958] 'compare' (duration: 209.589084ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:46.981006Z","caller":"traceutil/trace.go:171","msg":"trace[2136908259] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"133.505705ms","start":"2026-05-21T19:48:46.847476Z","end":"2026-05-21T19:48:46.980982Z","steps":["trace[2136908259] 'process raft request' (duration: 133.375232ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:46.980993Z","caller":"traceutil/trace.go:171","msg":"trace[112984907] linearizableReadLoop","detail":"{readStateIndex:9013; appliedIndex:9012; }","duration":"263.457305ms","start":"2026-05-21T19:48:46.717493Z","end":"2026-05-21T19:48:46.980951Z","steps":["trace[112984907] 'read index received' (duration: 52.949517ms)","trace[112984907] 'applied index is now lower than readState.Index' (duration: 210.500988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:46.981243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.188546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:48:46.981254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.748593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-21T19:48:46.981287Z","caller":"traceutil/trace.go:171","msg":"trace[171505357] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8598; }","duration":"241.301319ms","start":"2026-05-21T19:48:46.73997Z","end":"2026-05-21T19:48:46.981271Z","steps":["trace[171505357] 'agreement among raft nodes before linearized reading' (duration: 241.174456ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:46.98132Z","caller":"traceutil/trace.go:171","msg":"trace[5507557] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8598; }","duration":"263.877806ms","start":"2026-05-21T19:48:46.717425Z","end":"2026-05-21T19:48:46.981302Z","steps":["trace[5507557] 'agreement among raft nodes before linearized reading' (duration: 263.63121ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:47.48357Z","caller":"traceutil/trace.go:171","msg":"trace[1295919845] linearizableReadLoop","detail":"{readStateIndex:9016; appliedIndex:9015; }","duration":"259.603777ms","start":"2026-05-21T19:48:47.223935Z","end":"2026-05-21T19:48:47.483539Z","steps":["trace[1295919845] 'read index received' (duration: 254.536065ms)","trace[1295919845] 'applied index is now lower than readState.Index' (duration: 5.066242ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:47.483614Z","caller":"traceutil/trace.go:171","msg":"trace[1017223613] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"298.13517ms","start":"2026-05-21T19:48:47.18543Z","end":"2026-05-21T19:48:47.483565Z","steps":["trace[1017223613] 'process raft request' (duration: 293.14642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:47.483788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.829662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-21T19:48:47.483862Z","caller":"traceutil/trace.go:171","msg":"trace[907387447] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8600; }","duration":"259.960855ms","start":"2026-05-21T19:48:47.223878Z","end":"2026-05-21T19:48:47.483839Z","steps":["trace[907387447] 'agreement among raft nodes before linearized reading' (duration: 259.75527ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:47.664056Z","caller":"traceutil/trace.go:171","msg":"trace[1058399789] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"121.265286ms","start":"2026-05-21T19:48:47.542725Z","end":"2026-05-21T19:48:47.66399Z","steps":["trace[1058399789] 'process raft request' (duration: 117.235451ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:47.997458Z","caller":"traceutil/trace.go:171","msg":"trace[1182559795] linearizableReadLoop","detail":"{readStateIndex:9019; appliedIndex:9018; }","duration":"240.54531ms","start":"2026-05-21T19:48:47.756883Z","end":"2026-05-21T19:48:47.997428Z","steps":["trace[1182559795] 'read index received' (duration: 177.458558ms)","trace[1182559795] 'applied index is now lower than readState.Index' (duration: 63.085312ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:47.997571Z","caller":"traceutil/trace.go:171","msg":"trace[1409763315] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"329.512525ms","start":"2026-05-21T19:48:47.668029Z","end":"2026-05-21T19:48:47.997541Z","steps":["trace[1409763315] 'process raft request' (duration: 266.457004ms)","trace[1409763315] 'compare' (duration: 62.728643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:47.997606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.692723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:48:47.997666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.481079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:48:47.997682Z","caller":"traceutil/trace.go:171","msg":"trace[452271204] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8603; }","duration":"240.817497ms","start":"2026-05-21T19:48:47.756844Z","end":"2026-05-21T19:48:47.997662Z","steps":["trace[452271204] 'agreement among raft nodes before linearized reading' (duration: 240.679643ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:47.997749Z","caller":"traceutil/trace.go:171","msg":"trace[2047900013] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8603; }","duration":"202.570041ms","start":"2026-05-21T19:48:47.795162Z","end":"2026-05-21T19:48:47.997732Z","steps":["trace[2047900013] 'agreement among raft nodes before linearized reading' (duration: 202.454989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:47.997754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:48:47.667993Z","time spent":"329.666259ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:48:47.998082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.728455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-21T19:48:47.99814Z","caller":"traceutil/trace.go:171","msg":"trace[207986048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8603; }","duration":"132.788626ms","start":"2026-05-21T19:48:47.865338Z","end":"2026-05-21T19:48:47.998126Z","steps":["trace[207986048] 'agreement among raft nodes before linearized reading' (duration: 132.619762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:47.99836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.480013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-21T19:48:47.998719Z","caller":"traceutil/trace.go:171","msg":"trace[1220886103] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8603; }","duration":"133.863884ms","start":"2026-05-21T19:48:47.864832Z","end":"2026-05-21T19:48:47.998696Z","steps":["trace[1220886103] 'agreement among raft nodes before linearized reading' (duration: 133.238938ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:48.204176Z","caller":"traceutil/trace.go:171","msg":"trace[1019752226] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"199.510692ms","start":"2026-05-21T19:48:48.004634Z","end":"2026-05-21T19:48:48.204145Z","steps":["trace[1019752226] 'process raft request' (duration: 127.192561ms)","trace[1019752226] 'compare' (duration: 71.993953ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:48.252649Z","caller":"traceutil/trace.go:171","msg":"trace[2084923007] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"236.148305ms","start":"2026-05-21T19:48:48.016478Z","end":"2026-05-21T19:48:48.252627Z","steps":["trace[2084923007] 'process raft request' (duration: 236.012212ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:48.252853Z","caller":"traceutil/trace.go:171","msg":"trace[254640230] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"228.957008ms","start":"2026-05-21T19:48:48.023871Z","end":"2026-05-21T19:48:48.252828Z","steps":["trace[254640230] 'process raft request' (duration: 228.715481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:48.442849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.497925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:48.443293Z","caller":"traceutil/trace.go:171","msg":"trace[2107043324] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"186.319059ms","start":"2026-05-21T19:48:48.256932Z","end":"2026-05-21T19:48:48.443251Z","steps":["trace[2107043324] 'process raft request' (duration: 47.303361ms)","trace[2107043324] 'compare' (duration: 138.371801ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:48.443374Z","caller":"traceutil/trace.go:171","msg":"trace[2087153396] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"185.505547ms","start":"2026-05-21T19:48:48.257811Z","end":"2026-05-21T19:48:48.443317Z","steps":["trace[2087153396] 'process raft request' (duration: 185.351723ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:48.443583Z","caller":"traceutil/trace.go:171","msg":"trace[1161686170] transaction","detail":"{read_only:false; response_revision:8609; number_of_response:1; }","duration":"184.430059ms","start":"2026-05-21T19:48:48.259131Z","end":"2026-05-21T19:48:48.443561Z","steps":["trace[1161686170] 'process raft request' (duration: 184.122592ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:49.597057Z","caller":"traceutil/trace.go:171","msg":"trace[1660150241] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"105.934037ms","start":"2026-05-21T19:48:49.4911Z","end":"2026-05-21T19:48:49.597034Z","steps":["trace[1660150241] 'process raft request' (duration: 105.809724ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:51.096569Z","caller":"traceutil/trace.go:171","msg":"trace[1679595433] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"163.494695ms","start":"2026-05-21T19:48:50.933053Z","end":"2026-05-21T19:48:51.096548Z","steps":["trace[1679595433] 'process raft request' (duration: 163.097614ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:51.240207Z","caller":"traceutil/trace.go:171","msg":"trace[1477571391] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8624; }","duration":"135.984269ms","start":"2026-05-21T19:48:51.104129Z","end":"2026-05-21T19:48:51.240113Z","steps":["trace[1477571391] 'process raft request' (duration: 107.298453ms)","trace[1477571391] 'compare' (duration: 28.554692ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:51.367941Z","caller":"traceutil/trace.go:171","msg":"trace[845751979] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"112.895647ms","start":"2026-05-21T19:48:51.254991Z","end":"2026-05-21T19:48:51.367886Z","steps":["trace[845751979] 'process raft request' (duration: 112.643091ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:51.905039Z","caller":"traceutil/trace.go:171","msg":"trace[1819940913] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"108.573995ms","start":"2026-05-21T19:48:51.796408Z","end":"2026-05-21T19:48:51.904982Z","steps":["trace[1819940913] 'process raft request' (duration: 108.345249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:52.635346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.994096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-21T19:48:52.635452Z","caller":"traceutil/trace.go:171","msg":"trace[429329240] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8629; }","duration":"117.139989ms","start":"2026-05-21T19:48:52.518296Z","end":"2026-05-21T19:48:52.635436Z","steps":["trace[429329240] 'range keys from in-memory index tree' (duration: 116.860292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:52.635615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.552979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-21T19:48:52.635745Z","caller":"traceutil/trace.go:171","msg":"trace[1669051800] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8629; }","duration":"102.750514ms","start":"2026-05-21T19:48:52.532966Z","end":"2026-05-21T19:48:52.635716Z","steps":["trace[1669051800] 'range keys from in-memory index tree' (duration: 102.349184ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:52.956938Z","caller":"traceutil/trace.go:171","msg":"trace[1427254149] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"236.054882ms","start":"2026-05-21T19:48:52.720863Z","end":"2026-05-21T19:48:52.956918Z","steps":["trace[1427254149] 'process raft request' (duration: 235.913109ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:53.000035Z","caller":"traceutil/trace.go:171","msg":"trace[1345415395] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"278.243011ms","start":"2026-05-21T19:48:52.721733Z","end":"2026-05-21T19:48:52.999976Z","steps":["trace[1345415395] 'process raft request' (duration: 278.038896ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:53.898747Z","caller":"traceutil/trace.go:171","msg":"trace[1925837077] linearizableReadLoop","detail":"{readStateIndex:9057; appliedIndex:9056; }","duration":"176.02424ms","start":"2026-05-21T19:48:53.722689Z","end":"2026-05-21T19:48:53.898713Z","steps":["trace[1925837077] 'read index received' (duration: 175.777764ms)","trace[1925837077] 'applied index is now lower than readState.Index' (duration: 244.606µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:53.898866Z","caller":"traceutil/trace.go:171","msg":"trace[1862986708] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"247.757807ms","start":"2026-05-21T19:48:53.651079Z","end":"2026-05-21T19:48:53.898837Z","steps":["trace[1862986708] 'process raft request' (duration: 247.405829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:53.898979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.246775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:48:53.899025Z","caller":"traceutil/trace.go:171","msg":"trace[1794478740] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8639; }","duration":"176.37286ms","start":"2026-05-21T19:48:53.72264Z","end":"2026-05-21T19:48:53.899013Z","steps":["trace[1794478740] 'agreement among raft nodes before linearized reading' (duration: 176.241185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:53.899466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.924047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18314"} {"level":"info","ts":"2026-05-21T19:48:53.899545Z","caller":"traceutil/trace.go:171","msg":"trace[2033008868] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:8639; }","duration":"136.033229ms","start":"2026-05-21T19:48:53.763495Z","end":"2026-05-21T19:48:53.899528Z","steps":["trace[2033008868] 'agreement among raft nodes before linearized reading' (duration: 135.811824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:53.899514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.284966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5nvts\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:48:53.899803Z","caller":"traceutil/trace.go:171","msg":"trace[289070800] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5nvts; range_end:; response_count:1; response_revision:8639; }","duration":"156.571905ms","start":"2026-05-21T19:48:53.743172Z","end":"2026-05-21T19:48:53.899743Z","steps":["trace[289070800] 'agreement among raft nodes before linearized reading' (duration: 155.823275ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:55.314469Z","caller":"traceutil/trace.go:171","msg":"trace[1740523661] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"276.046074ms","start":"2026-05-21T19:48:55.038392Z","end":"2026-05-21T19:48:55.314438Z","steps":["trace[1740523661] 'process raft request' (duration: 213.038574ms)","trace[1740523661] 'compare' (duration: 62.634181ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:55.317127Z","caller":"traceutil/trace.go:171","msg":"trace[372419005] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"278.103578ms","start":"2026-05-21T19:48:55.038976Z","end":"2026-05-21T19:48:55.31708Z","steps":["trace[372419005] 'process raft request' (duration: 277.936374ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:55.317251Z","caller":"traceutil/trace.go:171","msg":"trace[1722005526] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"175.926108ms","start":"2026-05-21T19:48:55.141303Z","end":"2026-05-21T19:48:55.317229Z","steps":["trace[1722005526] 'process raft request' (duration: 175.715873ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:56.039976Z","caller":"traceutil/trace.go:171","msg":"trace[890790284] linearizableReadLoop","detail":"{readStateIndex:9068; appliedIndex:9067; }","duration":"126.970384ms","start":"2026-05-21T19:48:55.912979Z","end":"2026-05-21T19:48:56.039949Z","steps":["trace[890790284] 'read index received' (duration: 126.789669ms)","trace[890790284] 'applied index is now lower than readState.Index' (duration: 179.395µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:56.040093Z","caller":"traceutil/trace.go:171","msg":"trace[1403663275] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"130.526297ms","start":"2026-05-21T19:48:55.909531Z","end":"2026-05-21T19:48:56.040058Z","steps":["trace[1403663275] 'process raft request' (duration: 130.199048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:56.040428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.402315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5nvts\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-21T19:48:56.040522Z","caller":"traceutil/trace.go:171","msg":"trace[2145580813] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5nvts; range_end:; response_count:1; response_revision:8650; }","duration":"127.550899ms","start":"2026-05-21T19:48:55.912956Z","end":"2026-05-21T19:48:56.040507Z","steps":["trace[2145580813] 'agreement among raft nodes before linearized reading' (duration: 127.16707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:56.241038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.698526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:48:56.24117Z","caller":"traceutil/trace.go:171","msg":"trace[911476731] linearizableReadLoop","detail":"{readStateIndex:9069; appliedIndex:9068; }","duration":"194.945603ms","start":"2026-05-21T19:48:56.046205Z","end":"2026-05-21T19:48:56.24115Z","steps":["trace[911476731] 'read index received' (duration: 85.059003ms)","trace[911476731] 'applied index is now lower than readState.Index' (duration: 109.88524ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:56.241195Z","caller":"traceutil/trace.go:171","msg":"trace[1056913963] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"195.920158ms","start":"2026-05-21T19:48:56.045243Z","end":"2026-05-21T19:48:56.241163Z","steps":["trace[1056913963] 'process raft request' (duration: 85.961617ms)","trace[1056913963] 'compare' (duration: 109.502549ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:56.241685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.492837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:16 size:130234"} {"level":"info","ts":"2026-05-21T19:48:56.241731Z","caller":"traceutil/trace.go:171","msg":"trace[1045983681] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:16; response_revision:8651; }","duration":"195.608271ms","start":"2026-05-21T19:48:56.046113Z","end":"2026-05-21T19:48:56.241721Z","steps":["trace[1045983681] 'agreement among raft nodes before linearized reading' (duration: 195.085037ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:56.726071Z","caller":"traceutil/trace.go:171","msg":"trace[1223325405] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"107.281113ms","start":"2026-05-21T19:48:56.618772Z","end":"2026-05-21T19:48:56.726053Z","steps":["trace[1223325405] 'process raft request' (duration: 106.619806ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:56.83951Z","caller":"traceutil/trace.go:171","msg":"trace[1294179236] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8655; }","duration":"105.760532ms","start":"2026-05-21T19:48:56.7337Z","end":"2026-05-21T19:48:56.839461Z","steps":["trace[1294179236] 'process raft request' (duration: 105.551496ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:56.984698Z","caller":"traceutil/trace.go:171","msg":"trace[1082224353] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"132.759015ms","start":"2026-05-21T19:48:56.851861Z","end":"2026-05-21T19:48:56.98462Z","steps":["trace[1082224353] 'process raft request' (duration: 95.119926ms)","trace[1082224353] 'compare' (duration: 37.259899ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:48:57.439613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.484809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-21T19:48:57.439755Z","caller":"traceutil/trace.go:171","msg":"trace[276532759] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8659; }","duration":"104.691945ms","start":"2026-05-21T19:48:57.335023Z","end":"2026-05-21T19:48:57.439715Z","steps":["trace[276532759] 'range keys from in-memory index tree' (duration: 104.237663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:48:57.439791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.726872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-21T19:48:57.439866Z","caller":"traceutil/trace.go:171","msg":"trace[306797761] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8659; }","duration":"120.862245ms","start":"2026-05-21T19:48:57.318986Z","end":"2026-05-21T19:48:57.439848Z","steps":["trace[306797761] 'range keys from in-memory index tree' (duration: 120.476495ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:57.612087Z","caller":"traceutil/trace.go:171","msg":"trace[1102272325] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"164.473109ms","start":"2026-05-21T19:48:57.447576Z","end":"2026-05-21T19:48:57.612049Z","steps":["trace[1102272325] 'process raft request' (duration: 142.934869ms)","trace[1102272325] 'compare' (duration: 21.374406ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:48:58.718195Z","caller":"traceutil/trace.go:171","msg":"trace[1256169779] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"174.062164ms","start":"2026-05-21T19:48:58.544099Z","end":"2026-05-21T19:48:58.718161Z","steps":["trace[1256169779] 'process raft request' (duration: 173.867249ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:48:59.64214Z","caller":"traceutil/trace.go:171","msg":"trace[501093677] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"186.56554ms","start":"2026-05-21T19:48:59.45555Z","end":"2026-05-21T19:48:59.642115Z","steps":["trace[501093677] 'process raft request' (duration: 186.429296ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:49:03.114325Z","caller":"traceutil/trace.go:171","msg":"trace[2099824946] linearizableReadLoop","detail":"{readStateIndex:9117; appliedIndex:9116; }","duration":"100.753837ms","start":"2026-05-21T19:49:03.013526Z","end":"2026-05-21T19:49:03.11428Z","steps":["trace[2099824946] 'read index received' (duration: 33.402527ms)","trace[2099824946] 'applied index is now lower than readState.Index' (duration: 67.34942ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:49:03.114365Z","caller":"traceutil/trace.go:171","msg":"trace[468994005] transaction","detail":"{read_only:false; response_revision:8695; number_of_response:1; }","duration":"105.904313ms","start":"2026-05-21T19:49:03.008429Z","end":"2026-05-21T19:49:03.114334Z","steps":["trace[468994005] 'process raft request' (duration: 38.508301ms)","trace[468994005] 'compare' (duration: 67.018792ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:49:03.114568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.021606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-21T19:49:03.114615Z","caller":"traceutil/trace.go:171","msg":"trace[1279969890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8695; }","duration":"101.110737ms","start":"2026-05-21T19:49:03.013497Z","end":"2026-05-21T19:49:03.114608Z","steps":["trace[1279969890] 'agreement among raft nodes before linearized reading' (duration: 100.911852ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:49:07.556992Z","caller":"traceutil/trace.go:171","msg":"trace[1803994207] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8723; }","duration":"174.245759ms","start":"2026-05-21T19:49:07.3827Z","end":"2026-05-21T19:49:07.556946Z","steps":["trace[1803994207] 'process raft request' (duration: 123.603172ms)","trace[1803994207] 'compare' (duration: 50.524064ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:49:07.706058Z","caller":"traceutil/trace.go:171","msg":"trace[1201338451] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"134.379532ms","start":"2026-05-21T19:49:07.57166Z","end":"2026-05-21T19:49:07.70604Z","steps":["trace[1201338451] 'process raft request' (duration: 134.262719ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:49:10.00601Z","caller":"traceutil/trace.go:171","msg":"trace[1268866688] linearizableReadLoop","detail":"{readStateIndex:9160; appliedIndex:9159; }","duration":"169.012622ms","start":"2026-05-21T19:49:09.836969Z","end":"2026-05-21T19:49:10.005981Z","steps":["trace[1268866688] 'read index received' (duration: 168.635543ms)","trace[1268866688] 'applied index is now lower than readState.Index' (duration: 375.259µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:49:10.006171Z","caller":"traceutil/trace.go:171","msg":"trace[1289495124] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"238.131689ms","start":"2026-05-21T19:49:09.767975Z","end":"2026-05-21T19:49:10.006107Z","steps":["trace[1289495124] 'process raft request' (duration: 237.671007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:49:10.006316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.33991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-21T19:49:10.006395Z","caller":"traceutil/trace.go:171","msg":"trace[236891478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8736; }","duration":"169.496965ms","start":"2026-05-21T19:49:09.836874Z","end":"2026-05-21T19:49:10.006371Z","steps":["trace[236891478] 'agreement among raft nodes before linearized reading' (duration: 169.29286ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:49:10.17124Z","caller":"traceutil/trace.go:171","msg":"trace[348953775] linearizableReadLoop","detail":"{readStateIndex:9162; appliedIndex:9160; }","duration":"139.025173ms","start":"2026-05-21T19:49:10.032132Z","end":"2026-05-21T19:49:10.171157Z","steps":["trace[348953775] 'read index received' (duration: 8.260465ms)","trace[348953775] 'applied index is now lower than readState.Index' (duration: 130.763088ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:49:10.171309Z","caller":"traceutil/trace.go:171","msg":"trace[1785180280] transaction","detail":"{read_only:false; response_revision:8738; number_of_response:1; }","duration":"157.091483ms","start":"2026-05-21T19:49:10.014184Z","end":"2026-05-21T19:49:10.171276Z","steps":["trace[1785180280] 'process raft request' (duration: 116.358024ms)","trace[1785180280] 'compare' (duration: 40.166074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:49:10.171578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.431214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-21T19:49:10.171635Z","caller":"traceutil/trace.go:171","msg":"trace[109322307] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8738; }","duration":"139.568118ms","start":"2026-05-21T19:49:10.032052Z","end":"2026-05-21T19:49:10.17162Z","steps":["trace[109322307] 'agreement among raft nodes before linearized reading' (duration: 139.376003ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:49:10.476417Z","caller":"traceutil/trace.go:171","msg":"trace[2001085727] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"296.143236ms","start":"2026-05-21T19:49:10.180246Z","end":"2026-05-21T19:49:10.476389Z","steps":["trace[2001085727] 'process raft request' (duration: 296.007432ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:49:10.476417Z","caller":"traceutil/trace.go:171","msg":"trace[2090463405] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"296.718541ms","start":"2026-05-21T19:49:10.179646Z","end":"2026-05-21T19:49:10.476365Z","steps":["trace[2090463405] 'process raft request' (duration: 275.908571ms)","trace[2090463405] 'compare' (duration: 20.552924ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:49:11.412944Z","caller":"traceutil/trace.go:171","msg":"trace[1051377284] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"118.89849ms","start":"2026-05-21T19:49:11.294006Z","end":"2026-05-21T19:49:11.412904Z","steps":["trace[1051377284] 'process raft request' (duration: 52.912306ms)","trace[1051377284] 'compare' (duration: 65.82337ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:49:12.614532Z","caller":"traceutil/trace.go:171","msg":"trace[1695130161] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"125.917881ms","start":"2026-05-21T19:49:12.488576Z","end":"2026-05-21T19:49:12.614494Z","steps":["trace[1695130161] 'process raft request' (duration: 125.733057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:50:29.930068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.816491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-21T19:50:29.930174Z","caller":"traceutil/trace.go:171","msg":"trace[1605616953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9248; }","duration":"132.962255ms","start":"2026-05-21T19:50:29.797192Z","end":"2026-05-21T19:50:29.930154Z","steps":["trace[1605616953] 'range keys from in-memory index tree' (duration: 132.675327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:50:29.930052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.61167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-21T19:50:29.930257Z","caller":"traceutil/trace.go:171","msg":"trace[225308344] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:9248; }","duration":"181.869217ms","start":"2026-05-21T19:50:29.748366Z","end":"2026-05-21T19:50:29.930235Z","steps":["trace[225308344] 'range keys from in-memory index tree' (duration: 181.394694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:50:29.930481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.972863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-05-21T19:50:29.930715Z","caller":"traceutil/trace.go:171","msg":"trace[1493591188] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9248; }","duration":"201.25138ms","start":"2026-05-21T19:50:29.729441Z","end":"2026-05-21T19:50:29.930693Z","steps":["trace[1493591188] 'range keys from in-memory index tree' (duration: 200.460489ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:50:30.108469Z","caller":"traceutil/trace.go:171","msg":"trace[15539715] linearizableReadLoop","detail":"{readStateIndex:9705; appliedIndex:9703; }","duration":"173.369706ms","start":"2026-05-21T19:50:29.935068Z","end":"2026-05-21T19:50:30.108438Z","steps":["trace[15539715] 'read index received' (duration: 76.445427ms)","trace[15539715] 'applied index is now lower than readState.Index' (duration: 96.922948ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:50:30.108565Z","caller":"traceutil/trace.go:171","msg":"trace[1304510552] transaction","detail":"{read_only:false; response_revision:9250; number_of_response:1; }","duration":"174.982737ms","start":"2026-05-21T19:50:29.933556Z","end":"2026-05-21T19:50:30.108539Z","steps":["trace[1304510552] 'process raft request' (duration: 155.284155ms)","trace[1304510552] 'compare' (duration: 19.405575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:50:30.10869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.331223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:50:30.108737Z","caller":"traceutil/trace.go:171","msg":"trace[84936241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9250; }","duration":"161.437925ms","start":"2026-05-21T19:50:29.947285Z","end":"2026-05-21T19:50:30.108723Z","steps":["trace[84936241] 'agreement among raft nodes before linearized reading' (duration: 161.329012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:50:30.108749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.660603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-05-21T19:50:30.108814Z","caller":"traceutil/trace.go:171","msg":"trace[1129587144] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9250; }","duration":"173.764906ms","start":"2026-05-21T19:50:29.935034Z","end":"2026-05-21T19:50:30.108798Z","steps":["trace[1129587144] 'agreement among raft nodes before linearized reading' (duration: 173.503419ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:50:30.554065Z","caller":"traceutil/trace.go:171","msg":"trace[1782726110] linearizableReadLoop","detail":"{readStateIndex:9709; appliedIndex:9708; }","duration":"129.62626ms","start":"2026-05-21T19:50:30.424402Z","end":"2026-05-21T19:50:30.554028Z","steps":["trace[1782726110] 'read index received' (duration: 119.591728ms)","trace[1782726110] 'applied index is now lower than readState.Index' (duration: 10.028501ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:50:30.554152Z","caller":"traceutil/trace.go:171","msg":"trace[528790331] transaction","detail":"{read_only:false; response_revision:9253; number_of_response:1; }","duration":"236.513877ms","start":"2026-05-21T19:50:30.317595Z","end":"2026-05-21T19:50:30.554109Z","steps":["trace[528790331] 'process raft request' (duration: 226.519126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:50:30.554342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.915056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-05-21T19:50:30.554398Z","caller":"traceutil/trace.go:171","msg":"trace[1617739904] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9253; }","duration":"129.991658ms","start":"2026-05-21T19:50:30.424395Z","end":"2026-05-21T19:50:30.554387Z","steps":["trace[1617739904] 'agreement among raft nodes before linearized reading' (duration: 129.795584ms)"],"step_count":1}