{"level":"warn","ts":"2026-07-23T23:42:14.956017Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:42:14.956132Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.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.204.45.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.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-07-23T23:42:14.956198Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:42:14.956208Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-07-23T23:42:14.956236Z","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-07-23T23:42:14.957898Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-07-23T23:42:14.957977Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.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-07-23T23:42:14.963135Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.957131ms"} {"level":"info","ts":"2026-07-23T23:42:14.971816Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-07-23T23:42:14.971879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:42:14.971894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-07-23T23:42:14.971902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:42:14.971912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-07-23T23:42:14.971939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-07-23T23:42:14.97907Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:42:14.982023Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:42:14.983312Z","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-07-23T23:42:14.985213Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:42:14.986179Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:42:14.985413Z","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-07-23T23:42:14.986612Z","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-07-23T23:42:14.986631Z","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-07-23T23:42:14.988088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-07-23T23:42:14.988419Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-07-23T23:42:14.989458Z","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-07-23T23:42:14.989595Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-07-23T23:42:14.989651Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-07-23T23:42:14.989753Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:42:14.989796Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:42:15.872134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:42:15.872206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:42:15.872257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-07-23T23:42:15.872278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:42:15.872295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-07-23T23:42:15.872311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-07-23T23:42:15.87235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-07-23T23:42:15.873585Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:42:15.873723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:42:15.873793Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:42:15.87385Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:42:15.873869Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:42:15.8739Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:42:15.874623Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:42:15.874715Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:42:15.874738Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:42:15.876522Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-07-23T23:42:15.87688Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T23:42:21.553493Z","caller":"traceutil/trace.go:171","msg":"trace[1242737380] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"166.586617ms","start":"2026-07-23T23:42:21.386854Z","end":"2026-07-23T23:42:21.55344Z","steps":["trace[1242737380] 'process raft request' (duration: 165.643228ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.554316Z","caller":"traceutil/trace.go:171","msg":"trace[186988458] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"167.470654ms","start":"2026-07-23T23:42:21.386814Z","end":"2026-07-23T23:42:21.554284Z","steps":["trace[186988458] 'process raft request' (duration: 165.557279ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.554334Z","caller":"traceutil/trace.go:171","msg":"trace[1061889742] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"166.794315ms","start":"2026-07-23T23:42:21.387499Z","end":"2026-07-23T23:42:21.554293Z","steps":["trace[1061889742] 'process raft request' (duration: 165.05843ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.554395Z","caller":"traceutil/trace.go:171","msg":"trace[1723372000] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"145.768137ms","start":"2026-07-23T23:42:21.408607Z","end":"2026-07-23T23:42:21.554375Z","steps":["trace[1723372000] 'process raft request' (duration: 144.222111ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.5544Z","caller":"traceutil/trace.go:171","msg":"trace[17865333] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"145.507598ms","start":"2026-07-23T23:42:21.40887Z","end":"2026-07-23T23:42:21.554377Z","steps":["trace[17865333] 'process raft request' (duration: 144.572791ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.554387Z","caller":"traceutil/trace.go:171","msg":"trace[379577174] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"167.578574ms","start":"2026-07-23T23:42:21.386778Z","end":"2026-07-23T23:42:21.554357Z","steps":["trace[379577174] 'process raft request' (duration: 147.793873ms)","trace[379577174] 'compare' (duration: 15.808381ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:42:21.554747Z","caller":"traceutil/trace.go:171","msg":"trace[433162683] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"145.704497ms","start":"2026-07-23T23:42:21.408846Z","end":"2026-07-23T23:42:21.55455Z","steps":["trace[433162683] 'process raft request' (duration: 144.506491ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.554801Z","caller":"traceutil/trace.go:171","msg":"trace[144903581] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"146.212576ms","start":"2026-07-23T23:42:21.40837Z","end":"2026-07-23T23:42:21.554583Z","steps":["trace[144903581] 'process raft request' (duration: 144.384231ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.555249Z","caller":"traceutil/trace.go:171","msg":"trace[1341375483] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"164.997699ms","start":"2026-07-23T23:42:21.389565Z","end":"2026-07-23T23:42:21.554563Z","steps":["trace[1341375483] 'process raft request' (duration: 163.090745ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.555283Z","caller":"traceutil/trace.go:171","msg":"trace[1381399626] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:300; }","duration":"167.915283ms","start":"2026-07-23T23:42:21.386847Z","end":"2026-07-23T23:42:21.554762Z","steps":["trace[1381399626] 'read index received' (duration: 26.728434ms)","trace[1381399626] 'applied index is now lower than readState.Index' (duration: 140.981239ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:42:21.556815Z","caller":"traceutil/trace.go:171","msg":"trace[1034925862] transaction","detail":"{read_only:false; number_of_response:0; response_revision:299; }","duration":"165.392189ms","start":"2026-07-23T23:42:21.38936Z","end":"2026-07-23T23:42:21.554752Z","steps":["trace[1034925862] 'process raft request' (duration: 163.255745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:21.55694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.050458ms","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-07-23T23:42:21.556995Z","caller":"traceutil/trace.go:171","msg":"trace[305308926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:305; }","duration":"170.165767ms","start":"2026-07-23T23:42:21.386819Z","end":"2026-07-23T23:42:21.556985Z","steps":["trace[305308926] 'agreement among raft nodes before linearized reading' (duration: 167.740883ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.557042Z","caller":"traceutil/trace.go:171","msg":"trace[1153782985] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"135.987512ms","start":"2026-07-23T23:42:21.418775Z","end":"2026-07-23T23:42:21.554763Z","steps":["trace[1153782985] 'process raft request' (duration: 134.910965ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:21.557143Z","caller":"traceutil/trace.go:171","msg":"trace[1841852702] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"145.898227ms","start":"2026-07-23T23:42:21.408643Z","end":"2026-07-23T23:42:21.554541Z","steps":["trace[1841852702] 'process raft request' (duration: 144.232152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:57.057196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.390428ms","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-07-23T23:42:57.057329Z","caller":"traceutil/trace.go:171","msg":"trace[1639851213] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:611; }","duration":"203.551561ms","start":"2026-07-23T23:42:56.853761Z","end":"2026-07-23T23:42:57.057312Z","steps":["trace[1639851213] 'range keys from in-memory index tree' (duration: 203.372848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:57.057643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.015085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-07-23T23:42:57.057737Z","caller":"traceutil/trace.go:171","msg":"trace[729218647] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:611; }","duration":"247.140237ms","start":"2026-07-23T23:42:56.810572Z","end":"2026-07-23T23:42:57.057712Z","steps":["trace[729218647] 'range keys from in-memory index tree' (duration: 246.797181ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:01.033503Z","caller":"traceutil/trace.go:171","msg":"trace[1458400034] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"115.05316ms","start":"2026-07-23T23:43:00.91842Z","end":"2026-07-23T23:43:01.033473Z","steps":["trace[1458400034] 'process raft request' (duration: 114.414788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:01.672504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.996646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:01.672597Z","caller":"traceutil/trace.go:171","msg":"trace[1819812943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"597.104728ms","start":"2026-07-23T23:43:01.075468Z","end":"2026-07-23T23:43:01.672572Z","steps":["trace[1819812943] 'range keys from in-memory index tree' (duration: 596.931115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:01.672667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:01.075432Z","time spent":"597.225601ms","remote":"127.0.0.1:47326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:43:01.67321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.116328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:43:01.673286Z","caller":"traceutil/trace.go:171","msg":"trace[458369643] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"243.501944ms","start":"2026-07-23T23:43:01.429773Z","end":"2026-07-23T23:43:01.673274Z","steps":["trace[458369643] 'read index received' (duration: 35.801695ms)","trace[458369643] 'applied index is now lower than readState.Index' (duration: 207.699149ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:01.673449Z","caller":"traceutil/trace.go:171","msg":"trace[554664254] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"575.637808ms","start":"2026-07-23T23:43:01.097769Z","end":"2026-07-23T23:43:01.673407Z","steps":["trace[554664254] 'process raft request' (duration: 367.881727ms)","trace[554664254] 'compare' (duration: 206.836763ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:43:01.673573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:01.097753Z","time spent":"575.75821ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:43:01.67356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.751639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bfw99\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-23T23:43:01.673807Z","caller":"traceutil/trace.go:171","msg":"trace[1128068628] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bfw99; range_end:; response_count:1; response_revision:638; }","duration":"243.974804ms","start":"2026-07-23T23:43:01.429753Z","end":"2026-07-23T23:43:01.673728Z","steps":["trace[1128068628] 'agreement among raft nodes before linearized reading' (duration: 243.562136ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:14.658849Z","caller":"traceutil/trace.go:171","msg":"trace[772439994] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"111.996147ms","start":"2026-07-23T23:43:14.546821Z","end":"2026-07-23T23:43:14.658818Z","steps":["trace[772439994] 'process raft request' (duration: 68.157067ms)","trace[772439994] 'compare' (duration: 43.604704ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:14.661467Z","caller":"traceutil/trace.go:171","msg":"trace[90879293] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"113.77342ms","start":"2026-07-23T23:43:14.547667Z","end":"2026-07-23T23:43:14.661441Z","steps":["trace[90879293] 'process raft request' (duration: 113.415782ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:14.661513Z","caller":"traceutil/trace.go:171","msg":"trace[791299149] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"112.358295ms","start":"2026-07-23T23:43:14.549138Z","end":"2026-07-23T23:43:14.661496Z","steps":["trace[791299149] 'process raft request' (duration: 112.048768ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:17.382847Z","caller":"traceutil/trace.go:171","msg":"trace[1625261787] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"327.454802ms","start":"2026-07-23T23:43:17.055366Z","end":"2026-07-23T23:43:17.382821Z","steps":["trace[1625261787] 'process raft request' (duration: 327.282118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:17.383496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:17.055348Z","time spent":"327.622646ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:43:52.53583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257187801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:43:53.002339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.200644972s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T23:43:53.002935Z","caller":"traceutil/trace.go:171","msg":"trace[1349386574] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"1.201316494s","start":"2026-07-23T23:43:51.801581Z","end":"2026-07-23T23:43:53.002897Z","steps":["trace[1349386574] 'process raft request' (duration: 1.201118798s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:53.003Z","caller":"traceutil/trace.go:171","msg":"trace[1221919699] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1276; }","duration":"967.823037ms","start":"2026-07-23T23:43:52.035132Z","end":"2026-07-23T23:43:53.002955Z","steps":["trace[1221919699] 'read index received' (duration: 967.784476ms)","trace[1221919699] 'applied index is now lower than readState.Index' (duration: 35.861µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:43:53.003169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:51.801561Z","time spent":"1.201476729s","remote":"127.0.0.1:52732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":916,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:43:53.003352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.18334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:53.003462Z","caller":"traceutil/trace.go:171","msg":"trace[550334939] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1241; }","duration":"968.360526ms","start":"2026-07-23T23:43:52.035076Z","end":"2026-07-23T23:43:53.003437Z","steps":["trace[550334939] 'agreement among raft nodes before linearized reading' (duration: 968.108027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.003533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:52.035056Z","time spent":"968.457699ms","remote":"127.0.0.1:47602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-23T23:43:53.010685Z","caller":"traceutil/trace.go:171","msg":"trace[1484916010] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"306.985981ms","start":"2026-07-23T23:43:52.70368Z","end":"2026-07-23T23:43:53.010666Z","steps":["trace[1484916010] 'process raft request' (duration: 306.673351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.01085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:52.703632Z","time spent":"307.125416ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:43:53.011106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.223794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-23T23:43:53.011513Z","caller":"traceutil/trace.go:171","msg":"trace[1416425449] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1242; }","duration":"327.67452ms","start":"2026-07-23T23:43:52.683826Z","end":"2026-07-23T23:43:53.0115Z","steps":["trace[1416425449] 'agreement among raft nodes before linearized reading' (duration: 327.164663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.011547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:52.683789Z","time spent":"327.749493ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-23T23:43:53.011175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.502003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T23:43:53.01119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.387202ms","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-07-23T23:43:53.011657Z","caller":"traceutil/trace.go:171","msg":"trace[229072802] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1242; }","duration":"157.987999ms","start":"2026-07-23T23:43:52.853624Z","end":"2026-07-23T23:43:53.011612Z","steps":["trace[229072802] 'agreement among raft nodes before linearized reading' (duration: 157.506442ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:53.011759Z","caller":"traceutil/trace.go:171","msg":"trace[699551904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1242; }","duration":"948.992703ms","start":"2026-07-23T23:43:52.062746Z","end":"2026-07-23T23:43:53.011739Z","steps":["trace[699551904] 'agreement among raft nodes before linearized reading' (duration: 948.220087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.011819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:52.062737Z","time spent":"949.066565ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T23:43:53.011189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.777862ms","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-07-23T23:43:53.011965Z","caller":"traceutil/trace.go:171","msg":"trace[264535875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1242; }","duration":"573.61309ms","start":"2026-07-23T23:43:52.438339Z","end":"2026-07-23T23:43:53.011952Z","steps":["trace[264535875] 'agreement among raft nodes before linearized reading' (duration: 572.674519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.012002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:52.438316Z","time spent":"573.675842ms","remote":"127.0.0.1:47582","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-07-23T23:43:53.011211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.440967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:53.012064Z","caller":"traceutil/trace.go:171","msg":"trace[1740100415] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1242; }","duration":"160.300336ms","start":"2026-07-23T23:43:52.851741Z","end":"2026-07-23T23:43:53.012042Z","steps":["trace[1740100415] 'agreement among raft nodes before linearized reading' (duration: 159.450707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.011252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.894735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:53.01219Z","caller":"traceutil/trace.go:171","msg":"trace[219605317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"937.823736ms","start":"2026-07-23T23:43:52.074353Z","end":"2026-07-23T23:43:53.012176Z","steps":["trace[219605317] 'agreement among raft nodes before linearized reading' (duration: 936.877925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.012229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:52.074324Z","time spent":"937.896119ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:43:53.011288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.294708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-23T23:43:53.012369Z","caller":"traceutil/trace.go:171","msg":"trace[1023247990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"967.329103ms","start":"2026-07-23T23:43:52.044975Z","end":"2026-07-23T23:43:53.012304Z","steps":["trace[1023247990] 'agreement among raft nodes before linearized reading' (duration: 966.263397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.012493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:52.044966Z","time spent":"967.430107ms","remote":"127.0.0.1:47582","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":"warn","ts":"2026-07-23T23:43:53.011308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.347641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-23T23:43:53.012623Z","caller":"traceutil/trace.go:171","msg":"trace[1371098968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"967.681906ms","start":"2026-07-23T23:43:52.044933Z","end":"2026-07-23T23:43:53.012615Z","steps":["trace[1371098968] 'agreement among raft nodes before linearized reading' (duration: 966.31656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:53.012695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:52.04492Z","time spent":"967.766158ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T23:43:53.883083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257187858,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:43:54.272102Z","caller":"traceutil/trace.go:171","msg":"trace[884016535] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"968.246125ms","start":"2026-07-23T23:43:53.303833Z","end":"2026-07-23T23:43:54.27208Z","steps":["trace[884016535] 'process raft request' (duration: 968.13212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:54.272232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:53.303806Z","time spent":"968.363139ms","remote":"127.0.0.1:47482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6384,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:43:54.276917Z","caller":"traceutil/trace.go:171","msg":"trace[744110944] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1315; }","duration":"894.33244ms","start":"2026-07-23T23:43:53.382567Z","end":"2026-07-23T23:43:54.276899Z","steps":["trace[744110944] 'read index received' (duration: 889.497019ms)","trace[744110944] 'applied index is now lower than readState.Index' (duration: 4.832601ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:54.276951Z","caller":"traceutil/trace.go:171","msg":"trace[58704367] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"908.445925ms","start":"2026-07-23T23:43:53.368476Z","end":"2026-07-23T23:43:54.276922Z","steps":["trace[58704367] 'process raft request' (duration: 908.336441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:54.277117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.536866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" ","response":"range_response_count:1 size:2122"} {"level":"info","ts":"2026-07-23T23:43:54.277184Z","caller":"traceutil/trace.go:171","msg":"trace[615994640] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/self-signed-ca; range_end:; response_count:1; response_revision:1278; }","duration":"894.64335ms","start":"2026-07-23T23:43:53.382526Z","end":"2026-07-23T23:43:54.277169Z","steps":["trace[615994640] 'agreement among raft nodes before linearized reading' (duration: 894.467904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:54.277206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:53.382513Z","time spent":"894.687622ms","remote":"127.0.0.1:52732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":2146,"request content":"key:\"/registry/cert-manager.io/certificates/cert-manager/self-signed-ca\" "} {"level":"warn","ts":"2026-07-23T23:43:54.277185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.849619ms","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":"warn","ts":"2026-07-23T23:43:54.277196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.280392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/cert-manager-selfsigned-ca\" ","response":"range_response_count:1 size:2479"} {"level":"info","ts":"2026-07-23T23:43:54.277244Z","caller":"traceutil/trace.go:171","msg":"trace[882460204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"681.958653ms","start":"2026-07-23T23:43:53.595271Z","end":"2026-07-23T23:43:54.27723Z","steps":["trace[882460204] 'agreement among raft nodes before linearized reading' (duration: 681.760116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:54.277274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:53.595247Z","time spent":"682.020615ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:43:54.277342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.652007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:54.27727Z","caller":"traceutil/trace.go:171","msg":"trace[1393261540] range","detail":"{range_begin:/registry/secrets/cert-manager/cert-manager-selfsigned-ca; range_end:; response_count:1; response_revision:1278; }","duration":"403.390516ms","start":"2026-07-23T23:43:53.873866Z","end":"2026-07-23T23:43:54.277257Z","steps":["trace[1393261540] 'agreement among raft nodes before linearized reading' (duration: 403.172818ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:54.277377Z","caller":"traceutil/trace.go:171","msg":"trace[1764963286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"204.722239ms","start":"2026-07-23T23:43:54.072646Z","end":"2026-07-23T23:43:54.277368Z","steps":["trace[1764963286] 'agreement among raft nodes before linearized reading' (duration: 204.645427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:54.277428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.178086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:54.277465Z","caller":"traceutil/trace.go:171","msg":"trace[997991252] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1278; }","duration":"514.217767ms","start":"2026-07-23T23:43:53.763237Z","end":"2026-07-23T23:43:54.277455Z","steps":["trace[997991252] 'agreement among raft nodes before linearized reading' (duration: 514.142555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:54.277472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:53.873853Z","time spent":"403.585342ms","remote":"127.0.0.1:47398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":2503,"request content":"key:\"/registry/secrets/cert-manager/cert-manager-selfsigned-ca\" "} {"level":"warn","ts":"2026-07-23T23:43:54.277498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:53.763177Z","time spent":"514.3134ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:43:54.277404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:53.368463Z","time spent":"908.654802ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:02.869294Z","caller":"traceutil/trace.go:171","msg":"trace[1968446406] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"135.25505ms","start":"2026-07-23T23:44:02.734014Z","end":"2026-07-23T23:44:02.869269Z","steps":["trace[1968446406] 'process raft request' (duration: 135.055743ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:10.194015Z","caller":"traceutil/trace.go:171","msg":"trace[869173829] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"179.397909ms","start":"2026-07-23T23:44:10.014566Z","end":"2026-07-23T23:44:10.193964Z","steps":["trace[869173829] 'process raft request' (duration: 179.104968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:10.194222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.312834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:10.194628Z","caller":"traceutil/trace.go:171","msg":"trace[1672898184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1466; }","duration":"117.565213ms","start":"2026-07-23T23:44:10.076812Z","end":"2026-07-23T23:44:10.194377Z","steps":["trace[1672898184] 'agreement among raft nodes before linearized reading' (duration: 117.292814ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:10.193958Z","caller":"traceutil/trace.go:171","msg":"trace[847213473] linearizableReadLoop","detail":"{readStateIndex:1509; appliedIndex:1508; }","duration":"117.027515ms","start":"2026-07-23T23:44:10.076889Z","end":"2026-07-23T23:44:10.193917Z","steps":["trace[847213473] 'read index received' (duration: 116.719754ms)","trace[847213473] 'applied index is now lower than readState.Index' (duration: 301.811µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:44.792492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.024427ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-23T23:44:44.792694Z","caller":"traceutil/trace.go:171","msg":"trace[1096084781] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"206.898957ms","start":"2026-07-23T23:44:44.585769Z","end":"2026-07-23T23:44:44.792668Z","steps":["trace[1096084781] 'read index received' (duration: 54.902µs)","trace[1096084781] 'applied index is now lower than readState.Index' (duration: 206.841645ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:44:44.793053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.242812ms","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-07-23T23:44:44.793178Z","caller":"traceutil/trace.go:171","msg":"trace[1315708410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1912; }","duration":"207.45818ms","start":"2026-07-23T23:44:44.585694Z","end":"2026-07-23T23:44:44.793152Z","steps":["trace[1315708410] 'agreement among raft nodes before linearized reading' (duration: 207.061285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:44.793245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.005731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a00af5cb-9163-4dae-88a8-5c5c7b59309f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-23T23:44:44.793369Z","caller":"traceutil/trace.go:171","msg":"trace[1455153983] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a00af5cb-9163-4dae-88a8-5c5c7b59309f; range_end:; response_count:1; response_revision:1912; }","duration":"189.2268ms","start":"2026-07-23T23:44:44.604126Z","end":"2026-07-23T23:44:44.793353Z","steps":["trace[1455153983] 'agreement among raft nodes before linearized reading' (duration: 188.839645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:45.327266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.618548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a00af5cb-9163-4dae-88a8-5c5c7b59309f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-23T23:44:45.327388Z","caller":"traceutil/trace.go:171","msg":"trace[1121541892] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a00af5cb-9163-4dae-88a8-5c5c7b59309f; range_end:; response_count:1; response_revision:1915; }","duration":"139.820447ms","start":"2026-07-23T23:44:45.187538Z","end":"2026-07-23T23:44:45.327358Z","steps":["trace[1121541892] 'range keys from in-memory index tree' (duration: 139.375389ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:45.327517Z","caller":"traceutil/trace.go:171","msg":"trace[234125945] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"120.586646ms","start":"2026-07-23T23:44:45.206843Z","end":"2026-07-23T23:44:45.32743Z","steps":["trace[234125945] 'process raft request' (duration: 103.887484ms)","trace[234125945] 'compare' (duration: 16.329147ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:52.43161Z","caller":"traceutil/trace.go:171","msg":"trace[1145013912] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2065; }","duration":"147.99353ms","start":"2026-07-23T23:44:52.283584Z","end":"2026-07-23T23:44:52.431578Z","steps":["trace[1145013912] 'read index received' (duration: 147.924788ms)","trace[1145013912] 'applied index is now lower than readState.Index' (duration: 68.242µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:52.43165Z","caller":"traceutil/trace.go:171","msg":"trace[1692245419] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"409.024117ms","start":"2026-07-23T23:44:52.022595Z","end":"2026-07-23T23:44:52.431619Z","steps":["trace[1692245419] 'process raft request' (duration: 408.892242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:52.43247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:52.022579Z","time spent":"409.83426ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:52.431849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.24479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-07-23T23:44:52.432532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.558975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-23T23:44:52.432571Z","caller":"traceutil/trace.go:171","msg":"trace[245734988] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1992; }","duration":"142.603617ms","start":"2026-07-23T23:44:52.289956Z","end":"2026-07-23T23:44:52.43256Z","steps":["trace[245734988] 'agreement among raft nodes before linearized reading' (duration: 142.506173ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:52.432566Z","caller":"traceutil/trace.go:171","msg":"trace[894854711] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1992; }","duration":"148.970629ms","start":"2026-07-23T23:44:52.283553Z","end":"2026-07-23T23:44:52.432524Z","steps":["trace[894854711] 'agreement among raft nodes before linearized reading' (duration: 148.153456ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:53.132845Z","caller":"traceutil/trace.go:171","msg":"trace[492498862] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"126.855713ms","start":"2026-07-23T23:44:53.005966Z","end":"2026-07-23T23:44:53.132822Z","steps":["trace[492498862] 'process raft request' (duration: 126.439886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:58.979123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257189434,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:44:59.447846Z","caller":"traceutil/trace.go:171","msg":"trace[286247011] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"996.73144ms","start":"2026-07-23T23:44:58.451092Z","end":"2026-07-23T23:44:59.447823Z","steps":["trace[286247011] 'process raft request' (duration: 996.554643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:58.45107Z","time spent":"996.846464ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:44:59.448445Z","caller":"traceutil/trace.go:171","msg":"trace[694155103] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2104; }","duration":"970.024013ms","start":"2026-07-23T23:44:58.478406Z","end":"2026-07-23T23:44:59.44843Z","steps":["trace[694155103] 'read index received' (duration: 969.413169ms)","trace[694155103] 'applied index is now lower than readState.Index' (duration: 610.084µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:59.448687Z","caller":"traceutil/trace.go:171","msg":"trace[1183310388] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"835.744134ms","start":"2026-07-23T23:44:58.612929Z","end":"2026-07-23T23:44:59.448673Z","steps":["trace[1183310388] 'process raft request' (duration: 835.376819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.448759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:58.612914Z","time spent":"835.805396ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:44:59.449083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.683699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-23T23:44:59.449126Z","caller":"traceutil/trace.go:171","msg":"trace[254080389] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2031; }","duration":"970.766793ms","start":"2026-07-23T23:44:58.478346Z","end":"2026-07-23T23:44:59.449113Z","steps":["trace[254080389] 'agreement among raft nodes before linearized reading' (duration: 970.653869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.449154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:58.478325Z","time spent":"970.823114ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-23T23:44:59.449225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.555475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:59.449277Z","caller":"traceutil/trace.go:171","msg":"trace[27981469] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2031; }","duration":"727.635308ms","start":"2026-07-23T23:44:58.721629Z","end":"2026-07-23T23:44:59.449264Z","steps":["trace[27981469] 'agreement among raft nodes before linearized reading' (duration: 727.554965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.449313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:58.721615Z","time spent":"727.689571ms","remote":"127.0.0.1:50014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:44:59.449637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.370888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-23T23:44:59.44968Z","caller":"traceutil/trace.go:171","msg":"trace[1605296242] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2031; }","duration":"206.446392ms","start":"2026-07-23T23:44:59.243224Z","end":"2026-07-23T23:44:59.44967Z","steps":["trace[1605296242] 'agreement among raft nodes before linearized reading' (duration: 206.347178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.449697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.888865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:59.449731Z","caller":"traceutil/trace.go:171","msg":"trace[1272949227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2031; }","duration":"373.952978ms","start":"2026-07-23T23:44:59.075768Z","end":"2026-07-23T23:44:59.449721Z","steps":["trace[1272949227] 'agreement among raft nodes before linearized reading' (duration: 373.900176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.449782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:59.075758Z","time spent":"374.017081ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:44:59.449931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.951948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:44:59.449979Z","caller":"traceutil/trace.go:171","msg":"trace[2033429402] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2031; }","duration":"418.022911ms","start":"2026-07-23T23:44:59.031937Z","end":"2026-07-23T23:44:59.44996Z","steps":["trace[2033429402] 'agreement among raft nodes before linearized reading' (duration: 417.966488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.450008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:59.031923Z","time spent":"418.077343ms","remote":"127.0.0.1:47844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:44:59.450195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.864589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-23T23:44:59.450231Z","caller":"traceutil/trace.go:171","msg":"trace[899352653] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2031; }","duration":"507.90561ms","start":"2026-07-23T23:44:58.942316Z","end":"2026-07-23T23:44:59.450222Z","steps":["trace[899352653] 'agreement among raft nodes before linearized reading' (duration: 507.813886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.450258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:58.942261Z","time spent":"507.989523ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-23T23:44:59.450467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.685349ms","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-07-23T23:44:59.450503Z","caller":"traceutil/trace.go:171","msg":"trace[1131282486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2031; }","duration":"523.721241ms","start":"2026-07-23T23:44:58.926774Z","end":"2026-07-23T23:44:59.450495Z","steps":["trace[1131282486] 'agreement among raft nodes before linearized reading' (duration: 523.614646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:44:59.450534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:44:58.92671Z","time spent":"523.817914ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:44:59.450196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.57727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T23:44:59.450739Z","caller":"traceutil/trace.go:171","msg":"trace[431288410] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:2031; }","duration":"157.120271ms","start":"2026-07-23T23:44:59.293609Z","end":"2026-07-23T23:44:59.45073Z","steps":["trace[431288410] 'agreement among raft nodes before linearized reading' (duration: 156.533998ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:01.32797Z","caller":"traceutil/trace.go:171","msg":"trace[1730336487] linearizableReadLoop","detail":"{readStateIndex:2116; appliedIndex:2115; }","duration":"252.903569ms","start":"2026-07-23T23:45:01.075041Z","end":"2026-07-23T23:45:01.327944Z","steps":["trace[1730336487] 'read index received' (duration: 252.723582ms)","trace[1730336487] 'applied index is now lower than readState.Index' (duration: 178.237µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:01.328076Z","caller":"traceutil/trace.go:171","msg":"trace[1253032470] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"503.301369ms","start":"2026-07-23T23:45:00.824741Z","end":"2026-07-23T23:45:01.328042Z","steps":["trace[1253032470] 'process raft request' (duration: 502.984226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:01.328144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.088377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:01.328218Z","caller":"traceutil/trace.go:171","msg":"trace[1395330264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2040; }","duration":"253.215321ms","start":"2026-07-23T23:45:01.074987Z","end":"2026-07-23T23:45:01.328203Z","steps":["trace[1395330264] 'agreement among raft nodes before linearized reading' (duration: 253.058035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:01.328246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:00.82472Z","time spent":"503.438444ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:45:19.821927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.724883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:45:19.822029Z","caller":"traceutil/trace.go:171","msg":"trace[995278432] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2154; }","duration":"111.90273ms","start":"2026-07-23T23:45:19.710109Z","end":"2026-07-23T23:45:19.822012Z","steps":["trace[995278432] 'count revisions from in-memory index tree' (duration: 111.547585ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:23.348575Z","caller":"traceutil/trace.go:171","msg":"trace[152800864] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2684; }","duration":"124.645622ms","start":"2026-07-23T23:46:23.223905Z","end":"2026-07-23T23:46:23.348551Z","steps":["trace[152800864] 'read index received' (duration: 124.451853ms)","trace[152800864] 'applied index is now lower than readState.Index' (duration: 193.049µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:46:23.348992Z","caller":"traceutil/trace.go:171","msg":"trace[218287317] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"146.483615ms","start":"2026-07-23T23:46:23.202482Z","end":"2026-07-23T23:46:23.348965Z","steps":["trace[218287317] 'process raft request' (duration: 145.944946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:23.348827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.880392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-23T23:46:23.349527Z","caller":"traceutil/trace.go:171","msg":"trace[1175847270] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2591; }","duration":"125.639001ms","start":"2026-07-23T23:46:23.223876Z","end":"2026-07-23T23:46:23.349516Z","steps":["trace[1175847270] 'agreement among raft nodes before linearized reading' (duration: 124.772052ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:56.264227Z","caller":"traceutil/trace.go:171","msg":"trace[555476215] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"160.052494ms","start":"2026-07-23T23:46:56.10415Z","end":"2026-07-23T23:46:56.264202Z","steps":["trace[555476215] 'process raft request' (duration: 159.845803ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:56.434239Z","caller":"traceutil/trace.go:171","msg":"trace[1469399523] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"330.061424ms","start":"2026-07-23T23:46:56.104147Z","end":"2026-07-23T23:46:56.434208Z","steps":["trace[1469399523] 'process raft request' (duration: 329.031566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:56.434431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:56.104125Z","time spent":"330.209405ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:46:56.434579Z","caller":"traceutil/trace.go:171","msg":"trace[498581668] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"330.404136ms","start":"2026-07-23T23:46:56.104165Z","end":"2026-07-23T23:46:56.434569Z","steps":["trace[498581668] 'process raft request' (duration: 329.188518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:56.434677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:56.10415Z","time spent":"330.460437ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:46:56.434812Z","caller":"traceutil/trace.go:171","msg":"trace[5394731] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"330.618698ms","start":"2026-07-23T23:46:56.104185Z","end":"2026-07-23T23:46:56.434803Z","steps":["trace[5394731] 'process raft request' (duration: 329.210238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:56.434863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:56.104162Z","time spent":"330.671998ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:46:56.434976Z","caller":"traceutil/trace.go:171","msg":"trace[706975456] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"330.554777ms","start":"2026-07-23T23:46:56.104414Z","end":"2026-07-23T23:46:56.434968Z","steps":["trace[706975456] 'process raft request' (duration: 329.022176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:56.435575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:56.104405Z","time spent":"331.142971ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:46:56.435755Z","caller":"traceutil/trace.go:171","msg":"trace[270615375] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"330.932559ms","start":"2026-07-23T23:46:56.104778Z","end":"2026-07-23T23:46:56.435711Z","steps":["trace[270615375] 'process raft request' (duration: 328.725484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:56.435922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:56.104774Z","time spent":"331.04519ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:46:56.436232Z","caller":"traceutil/trace.go:171","msg":"trace[233982895] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"331.737597ms","start":"2026-07-23T23:46:56.104466Z","end":"2026-07-23T23:46:56.436203Z","steps":["trace[233982895] 'process raft request' (duration: 329.011226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:56.437544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:56.104462Z","time spent":"331.815517ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:47:51.063871Z","caller":"traceutil/trace.go:171","msg":"trace[645000663] linearizableReadLoop","detail":"{readStateIndex:3172; appliedIndex:3171; }","duration":"324.253157ms","start":"2026-07-23T23:47:50.739595Z","end":"2026-07-23T23:47:51.063848Z","steps":["trace[645000663] 'read index received' (duration: 324.070414ms)","trace[645000663] 'applied index is now lower than readState.Index' (duration: 181.883µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:47:51.063976Z","caller":"traceutil/trace.go:171","msg":"trace[1132143426] transaction","detail":"{read_only:false; response_revision:3058; number_of_response:1; }","duration":"325.352857ms","start":"2026-07-23T23:47:50.738587Z","end":"2026-07-23T23:47:51.06394Z","steps":["trace[1132143426] 'process raft request' (duration: 325.119013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:51.064131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.462501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-23T23:47:51.064149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:50.73857Z","time spent":"325.492519ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:47:51.064211Z","caller":"traceutil/trace.go:171","msg":"trace[1457835945] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3058; }","duration":"324.609914ms","start":"2026-07-23T23:47:50.739554Z","end":"2026-07-23T23:47:51.064164Z","steps":["trace[1457835945] 'agreement among raft nodes before linearized reading' (duration: 324.4105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:51.0644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:50.739537Z","time spent":"324.825937ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-23T23:47:51.564371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257192179,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:47:52.065568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257192179,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:47:52.565709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257192179,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:47:52.601978Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.538420628s","expected-duration":"1s"} {"level":"info","ts":"2026-07-23T23:47:52.605382Z","caller":"traceutil/trace.go:171","msg":"trace[585024776] transaction","detail":"{read_only:false; response_revision:3059; number_of_response:1; }","duration":"1.86457778s","start":"2026-07-23T23:47:50.740772Z","end":"2026-07-23T23:47:52.60535Z","steps":["trace[585024776] 'process raft request' (duration: 1.861398001s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:52.605477Z","caller":"traceutil/trace.go:171","msg":"trace[1292345133] transaction","detail":"{read_only:false; response_revision:3062; number_of_response:1; }","duration":"1.861068907s","start":"2026-07-23T23:47:50.744394Z","end":"2026-07-23T23:47:52.605463Z","steps":["trace[1292345133] 'process raft request' (duration: 1.861023806s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.605499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:50.740756Z","time spent":"1.864680752s","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:47:52.605537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:50.744379Z","time spent":"1.861129018s","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:47:52.60559Z","caller":"traceutil/trace.go:171","msg":"trace[1030554257] transaction","detail":"{read_only:false; response_revision:3060; number_of_response:1; }","duration":"1.863543412s","start":"2026-07-23T23:47:50.742037Z","end":"2026-07-23T23:47:52.605581Z","steps":["trace[1030554257] 'process raft request' (duration: 1.863280108s)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:47:52.605611Z","caller":"traceutil/trace.go:171","msg":"trace[229591553] transaction","detail":"{read_only:false; response_revision:3061; number_of_response:1; }","duration":"1.863559352s","start":"2026-07-23T23:47:50.742044Z","end":"2026-07-23T23:47:52.605603Z","steps":["trace[229591553] 'process raft request' (duration: 1.863350648s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.605623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:50.742026Z","time spent":"1.863577403s","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:47:52.605665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:50.742026Z","time spent":"1.863611064s","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:47:52.606739Z","caller":"traceutil/trace.go:171","msg":"trace[724117848] linearizableReadLoop","detail":"{readStateIndex:3180; appliedIndex:3172; }","duration":"1.542778259s","start":"2026-07-23T23:47:51.063944Z","end":"2026-07-23T23:47:52.606723Z","steps":["trace[724117848] 'read index received' (duration: 1.538241205s)","trace[724117848] 'applied index is now lower than readState.Index' (duration: 4.536304ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:47:52.606903Z","caller":"traceutil/trace.go:171","msg":"trace[1568759855] transaction","detail":"{read_only:false; response_revision:3063; number_of_response:1; }","duration":"1.536613256s","start":"2026-07-23T23:47:51.07028Z","end":"2026-07-23T23:47:52.606893Z","steps":["trace[1568759855] 'process raft request' (duration: 1.536314561s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.606975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:51.070243Z","time spent":"1.536680217s","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:47:52.60709Z","caller":"traceutil/trace.go:171","msg":"trace[493699993] transaction","detail":"{read_only:false; response_revision:3064; number_of_response:1; }","duration":"476.962731ms","start":"2026-07-23T23:47:52.130121Z","end":"2026-07-23T23:47:52.607083Z","steps":["trace[493699993] 'process raft request' (duration: 476.530703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.607124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:52.130098Z","time spent":"477.006722ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:47:52.6072Z","caller":"traceutil/trace.go:171","msg":"trace[650857821] transaction","detail":"{read_only:false; response_revision:3065; number_of_response:1; }","duration":"471.885666ms","start":"2026-07-23T23:47:52.135307Z","end":"2026-07-23T23:47:52.607192Z","steps":["trace[650857821] 'process raft request' (duration: 471.371297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.607235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:52.135297Z","time spent":"471.918607ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:47:52.607322Z","caller":"traceutil/trace.go:171","msg":"trace[1198229089] transaction","detail":"{read_only:false; response_revision:3066; number_of_response:1; }","duration":"452.253263ms","start":"2026-07-23T23:47:52.155063Z","end":"2026-07-23T23:47:52.607316Z","steps":["trace[1198229089] 'process raft request' (duration: 451.638132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.607352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:52.155051Z","time spent":"452.285523ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:47:52.607439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68172813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:47:52.607459Z","caller":"traceutil/trace.go:171","msg":"trace[850744072] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:3066; }","duration":"1.681779161s","start":"2026-07-23T23:47:50.925675Z","end":"2026-07-23T23:47:52.607454Z","steps":["trace[850744072] 'agreement among raft nodes before linearized reading' (duration: 1.681743201s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.607478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:50.92566Z","time spent":"1.681813822s","remote":"127.0.0.1:47526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:47:52.607574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532974228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:47:52.607591Z","caller":"traceutil/trace.go:171","msg":"trace[1690511908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3066; }","duration":"1.53302826s","start":"2026-07-23T23:47:51.074558Z","end":"2026-07-23T23:47:52.607586Z","steps":["trace[1690511908] 'agreement among raft nodes before linearized reading' (duration: 1.533001079s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.607607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:51.074543Z","time spent":"1.53306047s","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:47:52.608097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089959917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c50ffaa5841802\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-07-23T23:47:52.608125Z","caller":"traceutil/trace.go:171","msg":"trace[775551603] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c50ffaa5841802; range_end:; response_count:1; response_revision:3066; }","duration":"1.090038898s","start":"2026-07-23T23:47:51.51808Z","end":"2026-07-23T23:47:52.608119Z","steps":["trace[775551603] 'agreement among raft nodes before linearized reading' (duration: 1.089939936s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.608155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:51.518053Z","time spent":"1.090097399s","remote":"127.0.0.1:47388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c50ffaa5841802\" "} {"level":"warn","ts":"2026-07-23T23:47:52.608281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139547257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-23T23:47:52.608299Z","caller":"traceutil/trace.go:171","msg":"trace[1688549685] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3066; }","duration":"1.139620918s","start":"2026-07-23T23:47:51.468672Z","end":"2026-07-23T23:47:52.608293Z","steps":["trace[1688549685] 'agreement among raft nodes before linearized reading' (duration: 1.139562067s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.608331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:51.468638Z","time spent":"1.13968851s","remote":"127.0.0.1:47498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-23T23:47:52.608451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384665644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-23T23:47:52.608472Z","caller":"traceutil/trace.go:171","msg":"trace[1082982043] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3066; }","duration":"1.384733505s","start":"2026-07-23T23:47:51.223734Z","end":"2026-07-23T23:47:52.608467Z","steps":["trace[1082982043] 'agreement among raft nodes before linearized reading' (duration: 1.384674423s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.608489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:51.223705Z","time spent":"1.384780716s","remote":"127.0.0.1:47502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-23T23:47:52.608593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525536373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:47:52.608613Z","caller":"traceutil/trace.go:171","msg":"trace[827954745] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3066; }","duration":"1.525555913s","start":"2026-07-23T23:47:51.083051Z","end":"2026-07-23T23:47:52.608607Z","steps":["trace[827954745] 'agreement among raft nodes before linearized reading' (duration: 1.525524793s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:47:52.608637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:47:51.083044Z","time spent":"1.525588774s","remote":"127.0.0.1:47642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":2,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:48:09.167839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257192436,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:48:09.587853Z","caller":"traceutil/trace.go:171","msg":"trace[2013114969] transaction","detail":"{read_only:false; response_revision:3145; number_of_response:1; }","duration":"930.153145ms","start":"2026-07-23T23:48:08.657672Z","end":"2026-07-23T23:48:09.587825Z","steps":["trace[2013114969] 'process raft request' (duration: 929.88538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.588139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.657648Z","time spent":"930.345309ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:48:09.643695Z","caller":"traceutil/trace.go:171","msg":"trace[1622296329] transaction","detail":"{read_only:false; response_revision:3146; number_of_response:1; }","duration":"983.471778ms","start":"2026-07-23T23:48:08.660195Z","end":"2026-07-23T23:48:09.643667Z","steps":["trace[1622296329] 'process raft request' (duration: 983.291414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.64383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.660183Z","time spent":"983.57674ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:48:09.643893Z","caller":"traceutil/trace.go:171","msg":"trace[1945463665] linearizableReadLoop","detail":"{readStateIndex:3264; appliedIndex:3261; }","duration":"976.89588ms","start":"2026-07-23T23:48:08.666949Z","end":"2026-07-23T23:48:09.643845Z","steps":["trace[1945463665] 'read index received' (duration: 920.682357ms)","trace[1945463665] 'applied index is now lower than readState.Index' (duration: 56.211363ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:48:09.64394Z","caller":"traceutil/trace.go:171","msg":"trace[955134874] transaction","detail":"{read_only:false; response_revision:3147; number_of_response:1; }","duration":"920.678025ms","start":"2026-07-23T23:48:08.723254Z","end":"2026-07-23T23:48:09.643932Z","steps":["trace[955134874] 'process raft request' (duration: 920.368618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.643981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.723232Z","time spent":"920.728196ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:48:09.644169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.177046ms","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-07-23T23:48:09.644174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.245598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:48:09.644196Z","caller":"traceutil/trace.go:171","msg":"trace[1110198417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3147; }","duration":"977.278198ms","start":"2026-07-23T23:48:08.666911Z","end":"2026-07-23T23:48:09.644189Z","steps":["trace[1110198417] 'agreement among raft nodes before linearized reading' (duration: 977.078774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.644355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.131805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-23T23:48:09.64441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.666894Z","time spent":"977.507563ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T23:48:09.644423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.584953ms","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-07-23T23:48:09.644446Z","caller":"traceutil/trace.go:171","msg":"trace[2073086988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3147; }","duration":"508.647473ms","start":"2026-07-23T23:48:09.135792Z","end":"2026-07-23T23:48:09.644439Z","steps":["trace[2073086988] 'agreement among raft nodes before linearized reading' (duration: 508.592382ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:09.644438Z","caller":"traceutil/trace.go:171","msg":"trace[1666006093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3147; }","duration":"967.264318ms","start":"2026-07-23T23:48:08.677157Z","end":"2026-07-23T23:48:09.644421Z","steps":["trace[1666006093] 'agreement among raft nodes before linearized reading' (duration: 967.006383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.644508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.677152Z","time spent":"967.341299ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:48:09.644482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:09.135769Z","time spent":"508.705155ms","remote":"127.0.0.1:47582","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-07-23T23:48:09.644595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.827736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-23T23:48:09.644621Z","caller":"traceutil/trace.go:171","msg":"trace[195059354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3147; }","duration":"974.880448ms","start":"2026-07-23T23:48:08.669734Z","end":"2026-07-23T23:48:09.644614Z","steps":["trace[195059354] 'agreement among raft nodes before linearized reading' (duration: 974.316156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.644642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.669721Z","time spent":"974.915218ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:48:09.644859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.249218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-23T23:48:09.644206Z","caller":"traceutil/trace.go:171","msg":"trace[2039786674] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:3147; }","duration":"587.314929ms","start":"2026-07-23T23:48:09.056882Z","end":"2026-07-23T23:48:09.644197Z","steps":["trace[2039786674] 'agreement among raft nodes before linearized reading' (duration: 587.256638ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:09.644915Z","caller":"traceutil/trace.go:171","msg":"trace[2041606888] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3147; }","duration":"966.320349ms","start":"2026-07-23T23:48:08.678581Z","end":"2026-07-23T23:48:09.644901Z","steps":["trace[2041606888] 'agreement among raft nodes before linearized reading' (duration: 965.767977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.644953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.447284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-23T23:48:09.644977Z","caller":"traceutil/trace.go:171","msg":"trace[493044172] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3147; }","duration":"969.487925ms","start":"2026-07-23T23:48:08.675482Z","end":"2026-07-23T23:48:09.64497Z","steps":["trace[493044172] 'agreement among raft nodes before linearized reading' (duration: 969.418864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.644988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.98499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T23:48:09.644995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.675474Z","time spent":"969.515286ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-23T23:48:09.644949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.678572Z","time spent":"966.36995ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-23T23:48:09.64502Z","caller":"traceutil/trace.go:171","msg":"trace[1657959374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3147; }","duration":"975.033931ms","start":"2026-07-23T23:48:08.669978Z","end":"2026-07-23T23:48:09.645012Z","steps":["trace[1657959374] 'agreement among raft nodes before linearized reading' (duration: 974.96036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.645047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:08.66997Z","time spent":"975.069842ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T23:48:09.645015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:09.056864Z","time spent":"588.101555ms","remote":"127.0.0.1:47392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:48:09.644264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.631199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T23:48:09.645131Z","caller":"traceutil/trace.go:171","msg":"trace[1186263086] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:3147; }","duration":"342.508157ms","start":"2026-07-23T23:48:09.302615Z","end":"2026-07-23T23:48:09.645123Z","steps":["trace[1186263086] 'agreement among raft nodes before linearized reading' (duration: 341.604319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.645155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:09.30261Z","time spent":"342.538098ms","remote":"127.0.0.1:47354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"warn","ts":"2026-07-23T23:48:09.644296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.703389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:48:09.645209Z","caller":"traceutil/trace.go:171","msg":"trace[871120466] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:3147; }","duration":"398.634028ms","start":"2026-07-23T23:48:09.246565Z","end":"2026-07-23T23:48:09.645199Z","steps":["trace[871120466] 'agreement among raft nodes before linearized reading' (duration: 397.711579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.645237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:09.246553Z","time spent":"398.675559ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:48:09.644371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.55634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-23T23:48:09.645303Z","caller":"traceutil/trace.go:171","msg":"trace[1222097037] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3147; }","duration":"421.501969ms","start":"2026-07-23T23:48:09.223772Z","end":"2026-07-23T23:48:09.645274Z","steps":["trace[1222097037] 'agreement among raft nodes before linearized reading' (duration: 420.535079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:09.645403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:09.223754Z","time spent":"421.620602ms","remote":"127.0.0.1:47502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-23T23:48:19.927974Z","caller":"traceutil/trace.go:171","msg":"trace[539650001] transaction","detail":"{read_only:false; response_revision:3198; number_of_response:1; }","duration":"303.883558ms","start":"2026-07-23T23:48:19.624069Z","end":"2026-07-23T23:48:19.927952Z","steps":["trace[539650001] 'process raft request' (duration: 303.733344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:19.928127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:19.624045Z","time spent":"304.003771ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:48:19.928658Z","caller":"traceutil/trace.go:171","msg":"trace[1151942717] linearizableReadLoop","detail":"{readStateIndex:3319; appliedIndex:3317; }","duration":"239.364744ms","start":"2026-07-23T23:48:19.689273Z","end":"2026-07-23T23:48:19.928638Z","steps":["trace[1151942717] 'read index received' (duration: 238.503875ms)","trace[1151942717] 'applied index is now lower than readState.Index' (duration: 860.069µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:48:19.928861Z","caller":"traceutil/trace.go:171","msg":"trace[1266450773] transaction","detail":"{read_only:false; response_revision:3199; number_of_response:1; }","duration":"247.353012ms","start":"2026-07-23T23:48:19.681492Z","end":"2026-07-23T23:48:19.928845Z","steps":["trace[1266450773] 'process raft request' (duration: 247.052866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:19.929048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.35824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-23T23:48:19.929106Z","caller":"traceutil/trace.go:171","msg":"trace[2032954756] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3199; }","duration":"233.449012ms","start":"2026-07-23T23:48:19.695642Z","end":"2026-07-23T23:48:19.929091Z","steps":["trace[2032954756] 'agreement among raft nodes before linearized reading' (duration: 233.318649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:19.929131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.792103ms","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-07-23T23:48:19.929293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.200666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-23T23:48:19.929327Z","caller":"traceutil/trace.go:171","msg":"trace[396798976] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3199; }","duration":"233.262348ms","start":"2026-07-23T23:48:19.696056Z","end":"2026-07-23T23:48:19.929318Z","steps":["trace[396798976] 'agreement among raft nodes before linearized reading' (duration: 233.194096ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:19.929312Z","caller":"traceutil/trace.go:171","msg":"trace[301761638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3199; }","duration":"240.0605ms","start":"2026-07-23T23:48:19.689238Z","end":"2026-07-23T23:48:19.929299Z","steps":["trace[301761638] 'agreement among raft nodes before linearized reading' (duration: 239.627021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:19.929368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.8994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-23T23:48:19.929481Z","caller":"traceutil/trace.go:171","msg":"trace[1975381563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3199; }","duration":"229.030453ms","start":"2026-07-23T23:48:19.700412Z","end":"2026-07-23T23:48:19.929443Z","steps":["trace[1975381563] 'agreement among raft nodes before linearized reading' (duration: 228.746147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:19.929234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.002877ms","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-07-23T23:48:19.929643Z","caller":"traceutil/trace.go:171","msg":"trace[472327805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3199; }","duration":"231.417635ms","start":"2026-07-23T23:48:19.698202Z","end":"2026-07-23T23:48:19.929619Z","steps":["trace[472327805] 'agreement among raft nodes before linearized reading' (duration: 230.978516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:19.929861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.221485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T23:48:19.929925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.679235ms","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-07-23T23:48:19.929929Z","caller":"traceutil/trace.go:171","msg":"trace[620602735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3199; }","duration":"224.338938ms","start":"2026-07-23T23:48:19.705576Z","end":"2026-07-23T23:48:19.929915Z","steps":["trace[620602735] 'agreement among raft nodes before linearized reading' (duration: 223.743295ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:48:19.930043Z","caller":"traceutil/trace.go:171","msg":"trace[242388207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3199; }","duration":"228.822127ms","start":"2026-07-23T23:48:19.701202Z","end":"2026-07-23T23:48:19.930024Z","steps":["trace[242388207] 'agreement among raft nodes before linearized reading' (duration: 228.377557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.438243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257192627,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:48:22.461288Z","caller":"traceutil/trace.go:171","msg":"trace[268969177] transaction","detail":"{read_only:false; response_revision:3208; number_of_response:1; }","duration":"527.506559ms","start":"2026-07-23T23:48:21.933758Z","end":"2026-07-23T23:48:22.461264Z","steps":["trace[268969177] 'process raft request' (duration: 527.364566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.461439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.933739Z","time spent":"527.622732ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:48:22.46264Z","caller":"traceutil/trace.go:171","msg":"trace[363838559] linearizableReadLoop","detail":"{readStateIndex:3329; appliedIndex:3327; }","duration":"525.026893ms","start":"2026-07-23T23:48:21.93754Z","end":"2026-07-23T23:48:22.462567Z","steps":["trace[363838559] 'read index received' (duration: 523.55434ms)","trace[363838559] 'applied index is now lower than readState.Index' (duration: 1.471273ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:48:22.462716Z","caller":"traceutil/trace.go:171","msg":"trace[429447552] transaction","detail":"{read_only:false; response_revision:3209; number_of_response:1; }","duration":"528.082233ms","start":"2026-07-23T23:48:21.934616Z","end":"2026-07-23T23:48:22.462698Z","steps":["trace[429447552] 'process raft request' (duration: 527.754235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.462804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.934584Z","time spent":"528.175956ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:48:22.462982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.582958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:48:22.463024Z","caller":"traceutil/trace.go:171","msg":"trace[1476593247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3209; }","duration":"388.66679ms","start":"2026-07-23T23:48:22.074346Z","end":"2026-07-23T23:48:22.463012Z","steps":["trace[1476593247] 'agreement among raft nodes before linearized reading' (duration: 388.604339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.463066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:22.074324Z","time spent":"388.735142ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-23T23:48:22.463146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.462715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-23T23:48:22.46311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.534246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-23T23:48:22.463954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.053056ms","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-07-23T23:48:22.464018Z","caller":"traceutil/trace.go:171","msg":"trace[1529374466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3209; }","duration":"522.136279ms","start":"2026-07-23T23:48:21.941867Z","end":"2026-07-23T23:48:22.464003Z","steps":["trace[1529374466] 'agreement among raft nodes before linearized reading' (duration: 521.118636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.464077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.94186Z","time spent":"522.20465ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-23T23:48:22.464099Z","caller":"traceutil/trace.go:171","msg":"trace[1590672351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3209; }","duration":"526.583419ms","start":"2026-07-23T23:48:21.937493Z","end":"2026-07-23T23:48:22.464076Z","steps":["trace[1590672351] 'agreement among raft nodes before linearized reading' (duration: 525.211218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.464204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.937482Z","time spent":"526.704872ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:48:22.463019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.206568ms","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-07-23T23:48:22.464557Z","caller":"traceutil/trace.go:171","msg":"trace[260162644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3209; }","duration":"522.787103ms","start":"2026-07-23T23:48:21.941755Z","end":"2026-07-23T23:48:22.464542Z","steps":["trace[260162644] 'agreement among raft nodes before linearized reading' (duration: 521.022183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.46461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.941742Z","time spent":"522.852774ms","remote":"127.0.0.1:47582","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-07-23T23:48:22.463224Z","caller":"traceutil/trace.go:171","msg":"trace[124172002] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3209; }","duration":"518.554378ms","start":"2026-07-23T23:48:21.944651Z","end":"2026-07-23T23:48:22.463205Z","steps":["trace[124172002] 'agreement among raft nodes before linearized reading' (duration: 518.376584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.464812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.944646Z","time spent":"520.113282ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-23T23:48:22.463207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.34204ms","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-07-23T23:48:22.464936Z","caller":"traceutil/trace.go:171","msg":"trace[2023519970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3209; }","duration":"523.091229ms","start":"2026-07-23T23:48:21.941831Z","end":"2026-07-23T23:48:22.464922Z","steps":["trace[2023519970] 'agreement among raft nodes before linearized reading' (duration: 521.220537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.464982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.94182Z","time spent":"523.148851ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T23:48:22.463359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.085965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-23T23:48:22.46505Z","caller":"traceutil/trace.go:171","msg":"trace[1950825134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3209; }","duration":"522.786452ms","start":"2026-07-23T23:48:21.942239Z","end":"2026-07-23T23:48:22.465026Z","steps":["trace[1950825134] 'agreement among raft nodes before linearized reading' (duration: 521.025253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.463686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.041698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"warn","ts":"2026-07-23T23:48:22.465131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.942229Z","time spent":"522.885715ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T23:48:22.465167Z","caller":"traceutil/trace.go:171","msg":"trace[1597698197] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3209; }","duration":"520.547342ms","start":"2026-07-23T23:48:21.944608Z","end":"2026-07-23T23:48:22.465155Z","steps":["trace[1597698197] 'agreement among raft nodes before linearized reading' (duration: 518.302452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:48:22.465211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:48:21.944597Z","time spent":"520.602643ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-23T23:50:11.38762Z","caller":"traceutil/trace.go:171","msg":"trace[480555865] transaction","detail":"{read_only:false; response_revision:3945; number_of_response:1; }","duration":"348.932519ms","start":"2026-07-23T23:50:11.038665Z","end":"2026-07-23T23:50:11.387598Z","steps":["trace[480555865] 'process raft request' (duration: 347.133762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:11.387768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:11.038654Z","time spent":"349.046573ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:50:11.38781Z","caller":"traceutil/trace.go:171","msg":"trace[1672861795] linearizableReadLoop","detail":"{readStateIndex:4094; appliedIndex:4093; }","duration":"343.62203ms","start":"2026-07-23T23:50:11.04417Z","end":"2026-07-23T23:50:11.387792Z","steps":["trace[1672861795] 'read index received' (duration: 341.643046ms)","trace[1672861795] 'applied index is now lower than readState.Index' (duration: 1.977844ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:50:11.388014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.840577ms","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-07-23T23:50:11.388067Z","caller":"traceutil/trace.go:171","msg":"trace[2018850242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3946; }","duration":"343.9295ms","start":"2026-07-23T23:50:11.044123Z","end":"2026-07-23T23:50:11.388052Z","steps":["trace[2018850242] 'agreement among raft nodes before linearized reading' (duration: 343.773035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:11.388114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:11.044104Z","time spent":"344.001703ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:50:11.388305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.038033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-23T23:50:11.388376Z","caller":"traceutil/trace.go:171","msg":"trace[1654729594] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3946; }","duration":"312.146177ms","start":"2026-07-23T23:50:11.076215Z","end":"2026-07-23T23:50:11.388361Z","steps":["trace[1654729594] 'agreement among raft nodes before linearized reading' (duration: 311.959881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:11.388446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:11.076206Z","time spent":"312.230619ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-23T23:50:11.388476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.53965ms","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-07-23T23:50:11.388529Z","caller":"traceutil/trace.go:171","msg":"trace[1954973777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3946; }","duration":"280.633213ms","start":"2026-07-23T23:50:11.107884Z","end":"2026-07-23T23:50:11.388517Z","steps":["trace[1954973777] 'agreement among raft nodes before linearized reading' (duration: 280.487758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:11.388721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.385025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-23T23:50:11.388729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.306346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-23T23:50:11.388759Z","caller":"traceutil/trace.go:171","msg":"trace[236847139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3946; }","duration":"307.444386ms","start":"2026-07-23T23:50:11.081305Z","end":"2026-07-23T23:50:11.388749Z","steps":["trace[236847139] 'agreement among raft nodes before linearized reading' (duration: 307.317192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:11.388823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:11.081294Z","time spent":"307.520009ms","remote":"127.0.0.1:47582","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-07-23T23:50:11.388912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.183248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:50:11.388961Z","caller":"traceutil/trace.go:171","msg":"trace[1025115710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3946; }","duration":"312.230109ms","start":"2026-07-23T23:50:11.07672Z","end":"2026-07-23T23:50:11.38895Z","steps":["trace[1025115710] 'agreement among raft nodes before linearized reading' (duration: 312.164317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:11.389002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:11.076701Z","time spent":"312.292482ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T23:50:11.388769Z","caller":"traceutil/trace.go:171","msg":"trace[1148590721] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3946; }","duration":"239.373248ms","start":"2026-07-23T23:50:11.149383Z","end":"2026-07-23T23:50:11.388756Z","steps":["trace[1148590721] 'agreement among raft nodes before linearized reading' (duration: 239.273565ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:13.126644Z","caller":"traceutil/trace.go:171","msg":"trace[473313429] transaction","detail":"{read_only:false; response_revision:3953; number_of_response:1; }","duration":"766.770531ms","start":"2026-07-23T23:50:12.359851Z","end":"2026-07-23T23:50:13.126622Z","steps":["trace[473313429] 'process raft request' (duration: 766.645667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:13.126895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:50:12.359825Z","time spent":"766.888744ms","remote":"127.0.0.1:47402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":27708,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-23T23:50:13.12732Z","caller":"traceutil/trace.go:171","msg":"trace[657772854] transaction","detail":"{read_only:false; response_revision:3954; number_of_response:1; }","duration":"285.149309ms","start":"2026-07-23T23:50:12.842143Z","end":"2026-07-23T23:50:13.127292Z","steps":["trace[657772854] 'process raft request' (duration: 284.959203ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:13.127426Z","caller":"traceutil/trace.go:171","msg":"trace[312560268] linearizableReadLoop","detail":"{readStateIndex:4104; appliedIndex:4101; }","duration":"122.517728ms","start":"2026-07-23T23:50:13.004895Z","end":"2026-07-23T23:50:13.127413Z","steps":["trace[312560268] 'read index received' (duration: 121.63568ms)","trace[312560268] 'applied index is now lower than readState.Index' (duration: 880.858µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:50:13.127739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.813156ms","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-07-23T23:50:13.127803Z","caller":"traceutil/trace.go:171","msg":"trace[2052584865] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:3955; }","duration":"123.055024ms","start":"2026-07-23T23:50:13.004734Z","end":"2026-07-23T23:50:13.127789Z","steps":["trace[2052584865] 'agreement among raft nodes before linearized reading' (duration: 122.90986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:13.127843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.344272ms","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-07-23T23:50:13.127888Z","caller":"traceutil/trace.go:171","msg":"trace[1560988441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3955; }","duration":"112.431154ms","start":"2026-07-23T23:50:13.015446Z","end":"2026-07-23T23:50:13.127877Z","steps":["trace[1560988441] 'agreement among raft nodes before linearized reading' (duration: 112.29092ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:13.127874Z","caller":"traceutil/trace.go:171","msg":"trace[892513431] transaction","detail":"{read_only:false; response_revision:3955; number_of_response:1; }","duration":"255.256494ms","start":"2026-07-23T23:50:12.872593Z","end":"2026-07-23T23:50:13.12785Z","steps":["trace[892513431] 'process raft request' (duration: 254.662536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:47.095408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.530393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:50:47.095544Z","caller":"traceutil/trace.go:171","msg":"trace[1614819612] linearizableReadLoop","detail":"{readStateIndex:4728; appliedIndex:4727; }","duration":"204.675208ms","start":"2026-07-23T23:50:46.890846Z","end":"2026-07-23T23:50:47.095522Z","steps":["trace[1614819612] 'read index received' (duration: 62.888477ms)","trace[1614819612] 'applied index is now lower than readState.Index' (duration: 141.785581ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:50:47.095613Z","caller":"traceutil/trace.go:171","msg":"trace[641854838] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"212.868161ms","start":"2026-07-23T23:50:46.882705Z","end":"2026-07-23T23:50:47.095573Z","steps":["trace[641854838] 'process raft request' (duration: 71.08065ms)","trace[641854838] 'compare' (duration: 141.400558ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:50:47.095747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.895126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:11981"} {"level":"info","ts":"2026-07-23T23:50:47.09579Z","caller":"traceutil/trace.go:171","msg":"trace[1098710395] range","detail":"{range_begin:/registry/statefulsets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4556; }","duration":"204.956018ms","start":"2026-07-23T23:50:46.890821Z","end":"2026-07-23T23:50:47.095777Z","steps":["trace[1098710395] 'agreement among raft nodes before linearized reading' (duration: 204.800563ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:52.213311Z","caller":"traceutil/trace.go:171","msg":"trace[807989504] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4869; }","duration":"135.941783ms","start":"2026-07-23T23:50:52.077347Z","end":"2026-07-23T23:50:52.213289Z","steps":["trace[807989504] 'read index received' (duration: 135.626092ms)","trace[807989504] 'applied index is now lower than readState.Index' (duration: 314.851µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:50:52.213484Z","caller":"traceutil/trace.go:171","msg":"trace[1411390124] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"177.005078ms","start":"2026-07-23T23:50:52.036447Z","end":"2026-07-23T23:50:52.213452Z","steps":["trace[1411390124] 'process raft request' (duration: 176.786601ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:52.213638Z","caller":"traceutil/trace.go:171","msg":"trace[1875005955] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"181.855402ms","start":"2026-07-23T23:50:52.031751Z","end":"2026-07-23T23:50:52.213606Z","steps":["trace[1875005955] 'process raft request' (duration: 181.314774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:52.213885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.514404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:50:52.213938Z","caller":"traceutil/trace.go:171","msg":"trace[129906016] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4695; }","duration":"136.611507ms","start":"2026-07-23T23:50:52.077314Z","end":"2026-07-23T23:50:52.213925Z","steps":["trace[129906016] 'agreement among raft nodes before linearized reading' (duration: 136.514954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:52.214038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.519282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:50:52.214074Z","caller":"traceutil/trace.go:171","msg":"trace[256078106] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4695; }","duration":"130.586814ms","start":"2026-07-23T23:50:52.083479Z","end":"2026-07-23T23:50:52.214065Z","steps":["trace[256078106] 'agreement among raft nodes before linearized reading' (duration: 130.494831ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:53.723957Z","caller":"traceutil/trace.go:171","msg":"trace[69420533] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"195.151058ms","start":"2026-07-23T23:50:53.528789Z","end":"2026-07-23T23:50:53.72394Z","steps":["trace[69420533] 'process raft request' (duration: 194.578918ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:59.17391Z","caller":"traceutil/trace.go:171","msg":"trace[1904983516] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"157.45694ms","start":"2026-07-23T23:50:59.016428Z","end":"2026-07-23T23:50:59.173885Z","steps":["trace[1904983516] 'process raft request' (duration: 157.089988ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:50:59.422112Z","caller":"traceutil/trace.go:171","msg":"trace[551456403] linearizableReadLoop","detail":"{readStateIndex:4998; appliedIndex:4996; }","duration":"114.987068ms","start":"2026-07-23T23:50:59.307096Z","end":"2026-07-23T23:50:59.422083Z","steps":["trace[551456403] 'read index received' (duration: 23.077035ms)","trace[551456403] 'applied index is now lower than readState.Index' (duration: 91.909173ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:50:59.422152Z","caller":"traceutil/trace.go:171","msg":"trace[1158734645] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"136.81907ms","start":"2026-07-23T23:50:59.285306Z","end":"2026-07-23T23:50:59.422125Z","steps":["trace[1158734645] 'process raft request' (duration: 129.839912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:59.422314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.986013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-23T23:50:59.422342Z","caller":"traceutil/trace.go:171","msg":"trace[1678752681] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:4819; }","duration":"109.047436ms","start":"2026-07-23T23:50:59.313287Z","end":"2026-07-23T23:50:59.422335Z","steps":["trace[1678752681] 'agreement among raft nodes before linearized reading' (duration: 108.930672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:50:59.422349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.226746ms","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-07-23T23:50:59.422451Z","caller":"traceutil/trace.go:171","msg":"trace[549877420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4819; }","duration":"115.34567ms","start":"2026-07-23T23:50:59.307062Z","end":"2026-07-23T23:50:59.422407Z","steps":["trace[549877420] 'agreement among raft nodes before linearized reading' (duration: 115.144743ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:07.69477Z","caller":"traceutil/trace.go:171","msg":"trace[327678764] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"344.462783ms","start":"2026-07-23T23:51:07.350282Z","end":"2026-07-23T23:51:07.694745Z","steps":["trace[327678764] 'process raft request' (duration: 344.300287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:07.694982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:07.350256Z","time spent":"344.612678ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:07.701903Z","caller":"traceutil/trace.go:171","msg":"trace[1920081349] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5141; }","duration":"237.099443ms","start":"2026-07-23T23:51:07.464781Z","end":"2026-07-23T23:51:07.70188Z","steps":["trace[1920081349] 'read index received' (duration: 229.775793ms)","trace[1920081349] 'applied index is now lower than readState.Index' (duration: 7.32253ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:07.701979Z","caller":"traceutil/trace.go:171","msg":"trace[999211322] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"257.500493ms","start":"2026-07-23T23:51:07.444463Z","end":"2026-07-23T23:51:07.701963Z","steps":["trace[999211322] 'process raft request' (duration: 257.271926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:07.702155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.349142ms","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-07-23T23:51:07.702249Z","caller":"traceutil/trace.go:171","msg":"trace[65679872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4960; }","duration":"237.486696ms","start":"2026-07-23T23:51:07.464744Z","end":"2026-07-23T23:51:07.702231Z","steps":["trace[65679872] 'agreement among raft nodes before linearized reading' (duration: 237.243268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:12.644184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.360036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-07-23T23:51:12.644234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.918366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:51:12.64428Z","caller":"traceutil/trace.go:171","msg":"trace[2093625476] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4993; }","duration":"547.511531ms","start":"2026-07-23T23:51:12.096751Z","end":"2026-07-23T23:51:12.644263Z","steps":["trace[2093625476] 'range keys from in-memory index tree' (duration: 547.17896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:12.644359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:12.096729Z","time spent":"547.616995ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-23T23:51:12.644196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.083968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-07-23T23:51:12.644556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.68837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-07-23T23:51:12.644569Z","caller":"traceutil/trace.go:171","msg":"trace[1001578429] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4993; }","duration":"378.508173ms","start":"2026-07-23T23:51:12.26605Z","end":"2026-07-23T23:51:12.644558Z","steps":["trace[1001578429] 'range keys from in-memory index tree' (duration: 377.909402ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:12.644603Z","caller":"traceutil/trace.go:171","msg":"trace[356516488] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4993; }","duration":"570.765392ms","start":"2026-07-23T23:51:12.073824Z","end":"2026-07-23T23:51:12.644589Z","steps":["trace[356516488] 'range keys from in-memory index tree' (duration: 570.574196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:12.644606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:12.26604Z","time spent":"378.556395ms","remote":"127.0.0.1:57280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3268,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-07-23T23:51:12.644634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:12.073809Z","time spent":"570.818075ms","remote":"127.0.0.1:47498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-23T23:51:12.644356Z","caller":"traceutil/trace.go:171","msg":"trace[1229065965] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:4993; }","duration":"521.062221ms","start":"2026-07-23T23:51:12.123242Z","end":"2026-07-23T23:51:12.644304Z","steps":["trace[1229065965] 'count revisions from in-memory index tree' (duration: 520.844364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:12.64469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:12.123229Z","time spent":"521.446435ms","remote":"127.0.0.1:47482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:51:12.644787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.39575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:12.644815Z","caller":"traceutil/trace.go:171","msg":"trace[1881754115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4993; }","duration":"570.431521ms","start":"2026-07-23T23:51:12.074375Z","end":"2026-07-23T23:51:12.644806Z","steps":["trace[1881754115] 'range keys from in-memory index tree' (duration: 570.329298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:12.644839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:12.074371Z","time spent":"570.463052ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-23T23:51:13.16518Z","caller":"traceutil/trace.go:171","msg":"trace[1307419806] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"508.261575ms","start":"2026-07-23T23:51:12.656895Z","end":"2026-07-23T23:51:13.165157Z","steps":["trace[1307419806] 'process raft request' (duration: 508.10175ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:13.16539Z","caller":"traceutil/trace.go:171","msg":"trace[1291202215] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5178; }","duration":"449.187761ms","start":"2026-07-23T23:51:12.716175Z","end":"2026-07-23T23:51:13.165362Z","steps":["trace[1291202215] 'read index received' (duration: 449.176101ms)","trace[1291202215] 'applied index is now lower than readState.Index' (duration: 9.71µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:13.165431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:12.656877Z","time spent":"508.460002ms","remote":"127.0.0.1:47498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10663,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:51:13.165575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.365508ms","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-07-23T23:51:13.165617Z","caller":"traceutil/trace.go:171","msg":"trace[823031724] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4994; }","duration":"449.481343ms","start":"2026-07-23T23:51:12.716125Z","end":"2026-07-23T23:51:13.165607Z","steps":["trace[823031724] 'agreement among raft nodes before linearized reading' (duration: 449.362769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:13.165677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:12.71611Z","time spent":"449.556095ms","remote":"127.0.0.1:49892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-23T23:51:13.182562Z","caller":"traceutil/trace.go:171","msg":"trace[1186439833] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"261.211301ms","start":"2026-07-23T23:51:12.92132Z","end":"2026-07-23T23:51:13.182532Z","steps":["trace[1186439833] 'process raft request' (duration: 261.101937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:13.182596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.322765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:13.182713Z","caller":"traceutil/trace.go:171","msg":"trace[859996659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4995; }","duration":"106.563413ms","start":"2026-07-23T23:51:13.076137Z","end":"2026-07-23T23:51:13.1827Z","steps":["trace[859996659] 'agreement among raft nodes before linearized reading' (duration: 106.418478ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:13.407165Z","caller":"traceutil/trace.go:171","msg":"trace[536531059] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"235.786976ms","start":"2026-07-23T23:51:13.171353Z","end":"2026-07-23T23:51:13.40714Z","steps":["trace[536531059] 'process raft request' (duration: 235.573109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:13.407431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.653735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-6679897d6d-gqxl5\" ","response":"range_response_count:1 size:11847"} {"level":"info","ts":"2026-07-23T23:51:13.407223Z","caller":"traceutil/trace.go:171","msg":"trace[95529136] linearizableReadLoop","detail":"{readStateIndex:5183; appliedIndex:5179; }","duration":"224.696914ms","start":"2026-07-23T23:51:13.182504Z","end":"2026-07-23T23:51:13.407201Z","steps":["trace[95529136] 'read index received' (duration: 224.364473ms)","trace[95529136] 'applied index is now lower than readState.Index' (duration: 331.571µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:13.407534Z","caller":"traceutil/trace.go:171","msg":"trace[1659180797] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"235.149255ms","start":"2026-07-23T23:51:13.172365Z","end":"2026-07-23T23:51:13.407514Z","steps":["trace[1659180797] 'process raft request' (duration: 234.697799ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:13.407564Z","caller":"traceutil/trace.go:171","msg":"trace[2054247666] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-6679897d6d-gqxl5; range_end:; response_count:1; response_revision:4999; }","duration":"238.831851ms","start":"2026-07-23T23:51:13.168718Z","end":"2026-07-23T23:51:13.40755Z","steps":["trace[2054247666] 'agreement among raft nodes before linearized reading' (duration: 238.560872ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:13.407766Z","caller":"traceutil/trace.go:171","msg":"trace[1489658788] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"234.091058ms","start":"2026-07-23T23:51:13.173664Z","end":"2026-07-23T23:51:13.407755Z","steps":["trace[1489658788] 'process raft request' (duration: 233.448746ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:13.407866Z","caller":"traceutil/trace.go:171","msg":"trace[945830117] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"233.976093ms","start":"2026-07-23T23:51:13.173882Z","end":"2026-07-23T23:51:13.407858Z","steps":["trace[945830117] 'process raft request' (duration: 233.27946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:13.408164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.609417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a98ac6b1-5909-43d8-933b-d29822f67f28\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-23T23:51:13.408239Z","caller":"traceutil/trace.go:171","msg":"trace[2144663769] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a98ac6b1-5909-43d8-933b-d29822f67f28; range_end:; response_count:1; response_revision:4999; }","duration":"223.709181ms","start":"2026-07-23T23:51:13.184509Z","end":"2026-07-23T23:51:13.408218Z","steps":["trace[2144663769] 'agreement among raft nodes before linearized reading' (duration: 223.307657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:13.893651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.22313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:51:13.894036Z","caller":"traceutil/trace.go:171","msg":"trace[1179362441] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"219.916789ms","start":"2026-07-23T23:51:13.674088Z","end":"2026-07-23T23:51:13.894004Z","steps":["trace[1179362441] 'process raft request' (duration: 28.246773ms)","trace[1179362441] 'compare' (duration: 191.108226ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:13.894333Z","caller":"traceutil/trace.go:171","msg":"trace[1745494221] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5191; }","duration":"167.659228ms","start":"2026-07-23T23:51:13.726658Z","end":"2026-07-23T23:51:13.894317Z","steps":["trace[1745494221] 'read index received' (duration: 123.280888ms)","trace[1745494221] 'applied index is now lower than readState.Index' (duration: 44.37729ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:13.894359Z","caller":"traceutil/trace.go:171","msg":"trace[1596923624] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"182.686147ms","start":"2026-07-23T23:51:13.711647Z","end":"2026-07-23T23:51:13.894333Z","steps":["trace[1596923624] 'process raft request' (duration: 182.103556ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:13.894393Z","caller":"traceutil/trace.go:171","msg":"trace[1612933926] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"180.1603ms","start":"2026-07-23T23:51:13.714218Z","end":"2026-07-23T23:51:13.894378Z","steps":["trace[1612933926] 'process raft request' (duration: 180.064037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:13.894687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.998471ms","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-07-23T23:51:13.894774Z","caller":"traceutil/trace.go:171","msg":"trace[1227333701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5010; }","duration":"168.132095ms","start":"2026-07-23T23:51:13.726623Z","end":"2026-07-23T23:51:13.894755Z","steps":["trace[1227333701] 'agreement among raft nodes before linearized reading' (duration: 167.769693ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:34.099331Z","caller":"traceutil/trace.go:171","msg":"trace[1383560664] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"312.670647ms","start":"2026-07-23T23:51:33.786617Z","end":"2026-07-23T23:51:34.099288Z","steps":["trace[1383560664] 'process raft request' (duration: 312.491471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:34.099716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:33.786595Z","time spent":"312.818142ms","remote":"127.0.0.1:47788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9563,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:34.101774Z","caller":"traceutil/trace.go:171","msg":"trace[320984161] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5329; }","duration":"102.094472ms","start":"2026-07-23T23:51:33.999657Z","end":"2026-07-23T23:51:34.101752Z","steps":["trace[320984161] 'read index received' (duration: 99.510961ms)","trace[320984161] 'applied index is now lower than readState.Index' (duration: 2.580801ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:51:34.1021Z","caller":"traceutil/trace.go:171","msg":"trace[378730418] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"156.647766ms","start":"2026-07-23T23:51:33.945436Z","end":"2026-07-23T23:51:34.102084Z","steps":["trace[378730418] 'process raft request' (duration: 156.112767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:34.102377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.701324ms","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-07-23T23:51:34.103272Z","caller":"traceutil/trace.go:171","msg":"trace[340890038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5145; }","duration":"103.649557ms","start":"2026-07-23T23:51:33.9996Z","end":"2026-07-23T23:51:34.10325Z","steps":["trace[340890038] 'agreement among raft nodes before linearized reading' (duration: 102.59754ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:34.102623Z","caller":"traceutil/trace.go:171","msg":"trace[717017132] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"141.982871ms","start":"2026-07-23T23:51:33.960618Z","end":"2026-07-23T23:51:34.1026Z","steps":["trace[717017132] 'process raft request' (duration: 141.059569ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:34.102668Z","caller":"traceutil/trace.go:171","msg":"trace[947824714] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"141.931529ms","start":"2026-07-23T23:51:33.960732Z","end":"2026-07-23T23:51:34.102663Z","steps":["trace[947824714] 'process raft request' (duration: 140.984836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:34.988502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257197256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:51:35.488932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257197256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:51:35.645642Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.204162467s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-23T23:51:35.646445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505665907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-07-23T23:51:35.646506Z","caller":"traceutil/trace.go:171","msg":"trace[1481481100] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5153; }","duration":"1.505766961s","start":"2026-07-23T23:51:34.140721Z","end":"2026-07-23T23:51:35.646488Z","steps":["trace[1481481100] 'range keys from in-memory index tree' (duration: 1.505540463s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:35.646546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:34.140697Z","time spent":"1.505841044s","remote":"127.0.0.1:47502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1289,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" "} {"level":"info","ts":"2026-07-23T23:51:35.649007Z","caller":"traceutil/trace.go:171","msg":"trace[473282166] linearizableReadLoop","detail":"{readStateIndex:5342; appliedIndex:5341; }","duration":"1.160768444s","start":"2026-07-23T23:51:34.488202Z","end":"2026-07-23T23:51:35.64897Z","steps":["trace[473282166] 'read index received' (duration: 1.157584543s)","trace[473282166] 'applied index is now lower than readState.Index' (duration: 3.182571ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:35.649167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16094277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:35.649208Z","caller":"traceutil/trace.go:171","msg":"trace[1953629722] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:5153; }","duration":"1.161060474s","start":"2026-07-23T23:51:34.488136Z","end":"2026-07-23T23:51:35.649197Z","steps":["trace[1953629722] 'agreement among raft nodes before linearized reading' (duration: 1.160970971s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:35.64925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:34.488116Z","time spent":"1.161119106s","remote":"127.0.0.1:35538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:51:35.649496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.937357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:51:35.649537Z","caller":"traceutil/trace.go:171","msg":"trace[356638564] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5153; }","duration":"123.007789ms","start":"2026-07-23T23:51:35.526519Z","end":"2026-07-23T23:51:35.649526Z","steps":["trace[356638564] 'agreement among raft nodes before linearized reading' (duration: 122.928967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.70838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257197275,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:51:36.854822Z","caller":"traceutil/trace.go:171","msg":"trace[1488804062] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"711.24621ms","start":"2026-07-23T23:51:36.143545Z","end":"2026-07-23T23:51:36.854791Z","steps":["trace[1488804062] 'process raft request' (duration: 709.477457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.854978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.143531Z","time spent":"711.372584ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:36.855452Z","caller":"traceutil/trace.go:171","msg":"trace[1917740379] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"710.264675ms","start":"2026-07-23T23:51:36.145158Z","end":"2026-07-23T23:51:36.855422Z","steps":["trace[1917740379] 'process raft request' (duration: 710.159902ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:36.855576Z","caller":"traceutil/trace.go:171","msg":"trace[948524549] linearizableReadLoop","detail":"{readStateIndex:5350; appliedIndex:5346; }","duration":"647.583192ms","start":"2026-07-23T23:51:36.207975Z","end":"2026-07-23T23:51:36.855558Z","steps":["trace[948524549] 'read index received' (duration: 645.060513ms)","trace[948524549] 'applied index is now lower than readState.Index' (duration: 2.521419ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:51:36.855629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.145146Z","time spent":"710.392421ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:36.855795Z","caller":"traceutil/trace.go:171","msg":"trace[608461505] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"711.793239ms","start":"2026-07-23T23:51:36.14398Z","end":"2026-07-23T23:51:36.855773Z","steps":["trace[608461505] 'process raft request' (duration: 711.226119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.855882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.143969Z","time spent":"711.869232ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:51:36.855931Z","caller":"traceutil/trace.go:171","msg":"trace[2119178] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"710.737423ms","start":"2026-07-23T23:51:36.145176Z","end":"2026-07-23T23:51:36.855914Z","steps":["trace[2119178] 'process raft request' (duration: 710.201364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.856029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.145168Z","time spent":"710.803715ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:51:36.856303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.320888ms","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-07-23T23:51:36.856446Z","caller":"traceutil/trace.go:171","msg":"trace[308823752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5161; }","duration":"648.460453ms","start":"2026-07-23T23:51:36.207968Z","end":"2026-07-23T23:51:36.856429Z","steps":["trace[308823752] 'agreement among raft nodes before linearized reading' (duration: 648.212224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.856501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.207881Z","time spent":"648.607928ms","remote":"127.0.0.1:47582","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-07-23T23:51:36.856713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.433544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-23T23:51:36.856804Z","caller":"traceutil/trace.go:171","msg":"trace[2027720307] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5161; }","duration":"330.554589ms","start":"2026-07-23T23:51:36.52622Z","end":"2026-07-23T23:51:36.856775Z","steps":["trace[2027720307] 'agreement among raft nodes before linearized reading' (duration: 330.325071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:51:36.856845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:51:36.526205Z","time spent":"330.632012ms","remote":"127.0.0.1:47498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-23T23:51:38.218489Z","caller":"traceutil/trace.go:171","msg":"trace[959634229] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"101.358039ms","start":"2026-07-23T23:51:38.117101Z","end":"2026-07-23T23:51:38.218459Z","steps":["trace[959634229] 'process raft request' (duration: 101.081969ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:56.387546Z","caller":"traceutil/trace.go:171","msg":"trace[1650200995] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"110.420847ms","start":"2026-07-23T23:51:56.277098Z","end":"2026-07-23T23:51:56.387519Z","steps":["trace[1650200995] 'process raft request' (duration: 110.067384ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:51:59.762018Z","caller":"traceutil/trace.go:171","msg":"trace[724379859] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"154.192937ms","start":"2026-07-23T23:51:59.607799Z","end":"2026-07-23T23:51:59.761992Z","steps":["trace[724379859] 'process raft request' (duration: 114.524431ms)","trace[724379859] 'compare' (duration: 39.19023ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:52:04.679526Z","caller":"traceutil/trace.go:171","msg":"trace[1420981970] linearizableReadLoop","detail":"{readStateIndex:5623; appliedIndex:5621; }","duration":"170.382056ms","start":"2026-07-23T23:52:04.509127Z","end":"2026-07-23T23:52:04.679509Z","steps":["trace[1420981970] 'read index received' (duration: 166.357922ms)","trace[1420981970] 'applied index is now lower than readState.Index' (duration: 4.023274ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:52:04.679629Z","caller":"traceutil/trace.go:171","msg":"trace[82269813] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"176.538046ms","start":"2026-07-23T23:52:04.503084Z","end":"2026-07-23T23:52:04.679622Z","steps":["trace[82269813] 'process raft request' (duration: 176.301347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:52:04.679852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.697837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-23T23:52:04.679871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.091862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-23T23:52:04.679938Z","caller":"traceutil/trace.go:171","msg":"trace[1051915004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5426; }","duration":"170.869533ms","start":"2026-07-23T23:52:04.509049Z","end":"2026-07-23T23:52:04.679918Z","steps":["trace[1051915004] 'agreement among raft nodes before linearized reading' (duration: 170.637605ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:04.679951Z","caller":"traceutil/trace.go:171","msg":"trace[978177675] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5426; }","duration":"154.232637ms","start":"2026-07-23T23:52:04.525699Z","end":"2026-07-23T23:52:04.679932Z","steps":["trace[978177675] 'agreement among raft nodes before linearized reading' (duration: 153.989548ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:52:15.90026Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2875} {"level":"info","ts":"2026-07-23T23:52:15.993306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2875,"took":"91.658246ms","hash":2039364888,"current-db-size-bytes":52785152,"current-db-size":"53 MB","current-db-size-in-use-bytes":29331456,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-23T23:52:15.993369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2039364888,"revision":2875,"compact-revision":-1} {"level":"info","ts":"2026-07-23T23:54:59.646819Z","caller":"traceutil/trace.go:171","msg":"trace[49953178] linearizableReadLoop","detail":"{readStateIndex:6922; appliedIndex:6920; }","duration":"101.089849ms","start":"2026-07-23T23:54:59.545708Z","end":"2026-07-23T23:54:59.646797Z","steps":["trace[49953178] 'read index received' (duration: 100.803395ms)","trace[49953178] 'applied index is now lower than readState.Index' (duration: 285.684µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:54:59.64693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:59.325832Z","time spent":"321.09542ms","remote":"127.0.0.1:47354","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-23T23:54:59.647295Z","caller":"traceutil/trace.go:171","msg":"trace[766014210] transaction","detail":"{read_only:false; response_revision:6680; number_of_response:1; }","duration":"302.78272ms","start":"2026-07-23T23:54:59.3445Z","end":"2026-07-23T23:54:59.647283Z","steps":["trace[766014210] 'process raft request' (duration: 302.162503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:54:59.647393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:54:59.344489Z","time spent":"302.834211ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:54:59.648295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.581717ms","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-07-23T23:54:59.648396Z","caller":"traceutil/trace.go:171","msg":"trace[855352472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6680; }","duration":"102.716508ms","start":"2026-07-23T23:54:59.545671Z","end":"2026-07-23T23:54:59.648387Z","steps":["trace[855352472] 'agreement among raft nodes before linearized reading' (duration: 101.859847ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:15.909595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5519} {"level":"info","ts":"2026-07-23T23:57:15.980351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5519,"took":"69.516979ms","hash":2602286539,"current-db-size-bytes":52785152,"current-db-size":"53 MB","current-db-size-in-use-bytes":18354176,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-23T23:57:15.980419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2602286539,"revision":5519,"compact-revision":2875} {"level":"warn","ts":"2026-07-23T23:57:32.685303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540087257205165,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:57:32.903513Z","caller":"traceutil/trace.go:171","msg":"trace[1509391139] transaction","detail":"{read_only:false; response_revision:7482; number_of_response:1; }","duration":"735.946489ms","start":"2026-07-23T23:57:32.16754Z","end":"2026-07-23T23:57:32.903487Z","steps":["trace[1509391139] 'process raft request' (duration: 735.805056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.903662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:32.16753Z","time spent":"736.058722ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:32.905962Z","caller":"traceutil/trace.go:171","msg":"trace[812641975] linearizableReadLoop","detail":"{readStateIndex:7756; appliedIndex:7754; }","duration":"721.688908ms","start":"2026-07-23T23:57:32.184233Z","end":"2026-07-23T23:57:32.905922Z","steps":["trace[812641975] 'read index received' (duration: 719.17036ms)","trace[812641975] 'applied index is now lower than readState.Index' (duration: 2.517868ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:57:32.906001Z","caller":"traceutil/trace.go:171","msg":"trace[1171526757] transaction","detail":"{read_only:false; response_revision:7483; number_of_response:1; }","duration":"605.547753ms","start":"2026-07-23T23:57:32.300438Z","end":"2026-07-23T23:57:32.905986Z","steps":["trace[1171526757] 'process raft request' (duration: 605.40397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.9061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:32.300421Z","time spent":"605.613784ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:32.906234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.20443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-23T23:57:32.906299Z","caller":"traceutil/trace.go:171","msg":"trace[1042191193] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:7483; }","duration":"715.283302ms","start":"2026-07-23T23:57:32.191002Z","end":"2026-07-23T23:57:32.906285Z","steps":["trace[1042191193] 'agreement among raft nodes before linearized reading' (duration: 715.092877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.906333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:32.190983Z","time spent":"715.345044ms","remote":"127.0.0.1:47510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-07-23T23:57:32.906395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.674463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-23T23:57:32.906388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.10717ms","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-07-23T23:57:32.90644Z","caller":"traceutil/trace.go:171","msg":"trace[1688235239] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7483; }","duration":"577.735864ms","start":"2026-07-23T23:57:32.328691Z","end":"2026-07-23T23:57:32.906426Z","steps":["trace[1688235239] 'agreement among raft nodes before linearized reading' (duration: 577.643142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.906476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:32.32868Z","time spent":"577.785056ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-23T23:57:32.906473Z","caller":"traceutil/trace.go:171","msg":"trace[778063725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7483; }","duration":"373.194342ms","start":"2026-07-23T23:57:32.533257Z","end":"2026-07-23T23:57:32.906451Z","steps":["trace[778063725] 'agreement among raft nodes before linearized reading' (duration: 372.982838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.906505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.27106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:85 size:63140"} {"level":"warn","ts":"2026-07-23T23:57:32.90653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.103947ms","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-07-23T23:57:32.906562Z","caller":"traceutil/trace.go:171","msg":"trace[307892249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7483; }","duration":"587.136648ms","start":"2026-07-23T23:57:32.319419Z","end":"2026-07-23T23:57:32.906556Z","steps":["trace[307892249] 'agreement among raft nodes before linearized reading' (duration: 587.054366ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:32.906558Z","caller":"traceutil/trace.go:171","msg":"trace[940212335] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:85; response_revision:7483; }","duration":"722.349552ms","start":"2026-07-23T23:57:32.184195Z","end":"2026-07-23T23:57:32.906544Z","steps":["trace[940212335] 'agreement among raft nodes before linearized reading' (duration: 721.854821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.906545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.295128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-23T23:57:32.906584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:32.319381Z","time spent":"587.198808ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T23:57:32.906599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:32.18418Z","time spent":"722.405543ms","remote":"127.0.0.1:47388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":85,"response size":63164,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"info","ts":"2026-07-23T23:57:32.906657Z","caller":"traceutil/trace.go:171","msg":"trace[200109862] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7483; }","duration":"193.436631ms","start":"2026-07-23T23:57:32.713158Z","end":"2026-07-23T23:57:32.906594Z","steps":["trace[200109862] 'agreement among raft nodes before linearized reading' (duration: 193.102083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.90654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.459702ms","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-07-23T23:57:32.906797Z","caller":"traceutil/trace.go:171","msg":"trace[1860877768] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7483; }","duration":"193.751678ms","start":"2026-07-23T23:57:32.713025Z","end":"2026-07-23T23:57:32.906777Z","steps":["trace[1860877768] 'agreement among raft nodes before linearized reading' (duration: 193.254506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.906622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.415042ms","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-07-23T23:57:32.906919Z","caller":"traceutil/trace.go:171","msg":"trace[2094269483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7483; }","duration":"371.71412ms","start":"2026-07-23T23:57:32.535165Z","end":"2026-07-23T23:57:32.906879Z","steps":["trace[2094269483] 'agreement among raft nodes before linearized reading' (duration: 371.377342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:32.906993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:32.535147Z","time spent":"371.802281ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T23:57:32.906518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:32.533189Z","time spent":"373.317996ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T23:57:32.907861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.593139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-23T23:57:32.908104Z","caller":"traceutil/trace.go:171","msg":"trace[1316891280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7483; }","duration":"283.869005ms","start":"2026-07-23T23:57:32.624222Z","end":"2026-07-23T23:57:32.908091Z","steps":["trace[1316891280] 'agreement among raft nodes before linearized reading' (duration: 281.958341ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:45.429141Z","caller":"traceutil/trace.go:171","msg":"trace[632322114] transaction","detail":"{read_only:false; response_revision:7545; number_of_response:1; }","duration":"463.075676ms","start":"2026-07-23T23:57:44.966027Z","end":"2026-07-23T23:57:45.429103Z","steps":["trace[632322114] 'process raft request' (duration: 462.534364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:45.429315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:44.965959Z","time spent":"463.27369ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:57:45.430187Z","caller":"traceutil/trace.go:171","msg":"trace[1604353522] linearizableReadLoop","detail":"{readStateIndex:7822; appliedIndex:7820; }","duration":"460.878115ms","start":"2026-07-23T23:57:44.969284Z","end":"2026-07-23T23:57:45.430163Z","steps":["trace[1604353522] 'read index received' (duration: 459.293948ms)","trace[1604353522] 'applied index is now lower than readState.Index' (duration: 1.583297ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:57:45.430546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.425926ms","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-07-23T23:57:45.430641Z","caller":"traceutil/trace.go:171","msg":"trace[293210505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7546; }","duration":"460.542218ms","start":"2026-07-23T23:57:44.970084Z","end":"2026-07-23T23:57:45.430626Z","steps":["trace[293210505] 'agreement among raft nodes before linearized reading' (duration: 460.333013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:45.430609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.256575ms","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-07-23T23:57:45.431011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.713494ms","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-07-23T23:57:45.431044Z","caller":"traceutil/trace.go:171","msg":"trace[1077041717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7546; }","duration":"455.711366ms","start":"2026-07-23T23:57:44.97531Z","end":"2026-07-23T23:57:45.431021Z","steps":["trace[1077041717] 'agreement among raft nodes before linearized reading' (duration: 455.176824ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:45.43107Z","caller":"traceutil/trace.go:171","msg":"trace[565244145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7546; }","duration":"461.786807ms","start":"2026-07-23T23:57:44.969269Z","end":"2026-07-23T23:57:45.431056Z","steps":["trace[565244145] 'agreement among raft nodes before linearized reading' (duration: 461.288845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:45.43111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:44.969261Z","time spent":"461.841968ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-23T23:57:45.431093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:44.975301Z","time spent":"455.782377ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:57:45.43075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:44.97008Z","time spent":"460.62021ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:57:45.430553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.893142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-23T23:57:45.430817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.356664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:6167"} {"level":"info","ts":"2026-07-23T23:57:45.431648Z","caller":"traceutil/trace.go:171","msg":"trace[1220622905] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:7546; }","duration":"299.227794ms","start":"2026-07-23T23:57:45.132408Z","end":"2026-07-23T23:57:45.431636Z","steps":["trace[1220622905] 'agreement among raft nodes before linearized reading' (duration: 298.18512ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:45.431511Z","caller":"traceutil/trace.go:171","msg":"trace[569122728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7546; }","duration":"333.899695ms","start":"2026-07-23T23:57:45.097594Z","end":"2026-07-23T23:57:45.431494Z","steps":["trace[569122728] 'agreement among raft nodes before linearized reading' (duration: 332.913022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:45.430841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.527162ms","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-07-23T23:57:45.431819Z","caller":"traceutil/trace.go:171","msg":"trace[1851983723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7546; }","duration":"456.518234ms","start":"2026-07-23T23:57:44.975284Z","end":"2026-07-23T23:57:45.431802Z","steps":["trace[1851983723] 'agreement among raft nodes before linearized reading' (duration: 455.443069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:45.431871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:44.975277Z","time spent":"456.580595ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T23:57:45.430885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.984909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-23T23:57:45.431987Z","caller":"traceutil/trace.go:171","msg":"trace[1721341203] range","detail":"{range_begin:/registry/roles/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:7546; }","duration":"316.121455ms","start":"2026-07-23T23:57:45.115847Z","end":"2026-07-23T23:57:45.431969Z","steps":["trace[1721341203] 'agreement among raft nodes before linearized reading' (duration: 314.578979ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:57:45.430938Z","caller":"traceutil/trace.go:171","msg":"trace[962352411] transaction","detail":"{read_only:false; response_revision:7546; number_of_response:1; }","duration":"461.700375ms","start":"2026-07-23T23:57:44.969221Z","end":"2026-07-23T23:57:45.430922Z","steps":["trace[962352411] 'process raft request' (duration: 460.835875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:57:45.43207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:45.115823Z","time spent":"316.237617ms","remote":"127.0.0.1:47654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":922,"request content":"key:\"/registry/roles/monitoring/kube-prometheus-stack-grafana\" "} {"level":"warn","ts":"2026-07-23T23:57:45.432182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:44.969208Z","time spent":"462.866191ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:57:45.432034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:57:45.097575Z","time spent":"334.373256ms","remote":"127.0.0.1:47318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}