{"level":"warn","ts":"2026-10-13T15:01:49.53563Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T15:01:49.53573Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.104: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://162.253.55.104:2380","--initial-cluster=instance=https://162.253.55.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.104: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-10-13T15:01:49.535788Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-13T15:01:49.535802Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-10-13T15:01:49.535822Z","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-10-13T15:01:49.536377Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"]} {"level":"info","ts":"2026-10-13T15:01:49.536446Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.104: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-10-13T15:01:49.542217Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.558551ms"} {"level":"info","ts":"2026-10-13T15:01:49.557436Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8572cc3a5351216d","cluster-id":"c1a156b8f69e6a79"} {"level":"info","ts":"2026-10-13T15:01:49.557557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=()"} {"level":"info","ts":"2026-10-13T15:01:49.557586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 0"} {"level":"info","ts":"2026-10-13T15:01:49.557606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8572cc3a5351216d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-13T15:01:49.557617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became follower at term 1"} {"level":"info","ts":"2026-10-13T15:01:49.557667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"warn","ts":"2026-10-13T15:01:49.565582Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-13T15:01:49.576022Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-13T15:01:49.577712Z","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-10-13T15:01:49.581165Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8572cc3a5351216d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-13T15:01:49.582467Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8572cc3a5351216d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-13T15:01:49.582721Z","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-10-13T15:01:49.582805Z","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-10-13T15:01:49.58284Z","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-10-13T15:01:49.583274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d switched to configuration voters=(9615972705267425645)"} {"level":"info","ts":"2026-10-13T15:01:49.583442Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","added-peer-id":"8572cc3a5351216d","added-peer-peer-urls":["https://162.253.55.104:2380"]} {"level":"info","ts":"2026-10-13T15:01:49.585839Z","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-10-13T15:01:49.585907Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-10-13T15:01:49.586052Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.104:2380"} {"level":"info","ts":"2026-10-13T15:01:49.587263Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-13T15:01:49.587201Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8572cc3a5351216d","initial-advertise-peer-urls":["https://162.253.55.104:2380"],"listen-peer-urls":["https://162.253.55.104:2380"],"advertise-client-urls":["https://162.253.55.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-13T15:01:50.458802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d is starting a new election at term 1"} {"level":"info","ts":"2026-10-13T15:01:50.458929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-13T15:01:50.458997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgPreVoteResp from 8572cc3a5351216d at term 1"} {"level":"info","ts":"2026-10-13T15:01:50.459019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became candidate at term 2"} {"level":"info","ts":"2026-10-13T15:01:50.45903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d received MsgVoteResp from 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-10-13T15:01:50.459046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8572cc3a5351216d became leader at term 2"} {"level":"info","ts":"2026-10-13T15:01:50.459064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8572cc3a5351216d elected leader 8572cc3a5351216d at term 2"} {"level":"info","ts":"2026-10-13T15:01:50.460064Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8572cc3a5351216d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.104:2379]}","request-path":"/0/members/8572cc3a5351216d/attributes","cluster-id":"c1a156b8f69e6a79","publish-timeout":"7s"} {"level":"info","ts":"2026-10-13T15:01:50.460152Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T15:01:50.460172Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T15:01:50.460106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-13T15:01:50.460827Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-13T15:01:50.460918Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-13T15:01:50.461283Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c1a156b8f69e6a79","local-member-id":"8572cc3a5351216d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T15:01:50.461403Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T15:01:50.461432Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-13T15:01:50.462601Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.104:2379"} {"level":"info","ts":"2026-10-13T15:01:50.463197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-13T15:02:42.595978Z","caller":"traceutil/trace.go:171","msg":"trace[330696969] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"162.210628ms","start":"2026-10-13T15:02:42.433713Z","end":"2026-10-13T15:02:42.595923Z","steps":["trace[330696969] 'process raft request' (duration: 111.067513ms)","trace[330696969] 'compare' (duration: 50.900139ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:02:42.596215Z","caller":"traceutil/trace.go:171","msg":"trace[637465600] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"162.251979ms","start":"2026-10-13T15:02:42.433918Z","end":"2026-10-13T15:02:42.59617Z","steps":["trace[637465600] 'process raft request' (duration: 161.965412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:02:42.97072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.704104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T15:02:42.97088Z","caller":"traceutil/trace.go:171","msg":"trace[1471831330] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"323.638555ms","start":"2026-10-13T15:02:42.647221Z","end":"2026-10-13T15:02:42.970859Z","steps":["trace[1471831330] 'process raft request' (duration: 92.094669ms)","trace[1471831330] 'compare' (duration: 230.473188ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:02:42.970973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:02:42.647174Z","time spent":"323.737798ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:02:42.973831Z","caller":"traceutil/trace.go:171","msg":"trace[130465953] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"122.121735ms","start":"2026-10-13T15:02:42.851671Z","end":"2026-10-13T15:02:42.973793Z","steps":["trace[130465953] 'read index received' (duration: 121.347355ms)","trace[130465953] 'applied index is now lower than readState.Index' (duration: 773.63µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:02:42.97393Z","caller":"traceutil/trace.go:171","msg":"trace[1750109809] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"234.017669ms","start":"2026-10-13T15:02:42.739866Z","end":"2026-10-13T15:02:42.973884Z","steps":["trace[1750109809] 'process raft request' (duration: 233.430875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:02:42.974222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.520926ms","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-10-13T15:02:42.97434Z","caller":"traceutil/trace.go:171","msg":"trace[1041124987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"122.689029ms","start":"2026-10-13T15:02:42.851621Z","end":"2026-10-13T15:02:42.97431Z","steps":["trace[1041124987] 'agreement among raft nodes before linearized reading' (duration: 122.30751ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:02:43.562435Z","caller":"traceutil/trace.go:171","msg":"trace[58920882] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"134.643894ms","start":"2026-10-13T15:02:43.427757Z","end":"2026-10-13T15:02:43.562401Z","steps":["trace[58920882] 'read index received' (duration: 95.832454ms)","trace[58920882] 'applied index is now lower than readState.Index' (duration: 38.81022ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:02:43.562479Z","caller":"traceutil/trace.go:171","msg":"trace[1642707061] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"446.455808ms","start":"2026-10-13T15:02:43.115986Z","end":"2026-10-13T15:02:43.562442Z","steps":["trace[1642707061] 'process raft request' (duration: 446.325844ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:02:43.562558Z","caller":"traceutil/trace.go:171","msg":"trace[1871482896] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"513.702893ms","start":"2026-10-13T15:02:43.048838Z","end":"2026-10-13T15:02:43.56254Z","steps":["trace[1871482896] 'process raft request' (duration: 474.849752ms)","trace[1871482896] 'compare' (duration: 38.296837ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:02:43.562673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:02:43.115955Z","time spent":"446.606252ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:02:43.562683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:02:43.048814Z","time spent":"513.779386ms","remote":"127.0.0.1:48860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T15:02:43.563014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.015824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:02:43.563216Z","caller":"traceutil/trace.go:171","msg":"trace[1280580694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"135.491546ms","start":"2026-10-13T15:02:43.427704Z","end":"2026-10-13T15:02:43.563196Z","steps":["trace[1280580694] 'agreement among raft nodes before linearized reading' (duration: 134.832839ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:04:16.36568Z","caller":"traceutil/trace.go:171","msg":"trace[1268884226] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"102.371532ms","start":"2026-10-13T15:04:16.263262Z","end":"2026-10-13T15:04:16.365634Z","steps":["trace[1268884226] 'process raft request' (duration: 69.457722ms)","trace[1268884226] 'compare' (duration: 32.720755ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:04:16.580795Z","caller":"traceutil/trace.go:171","msg":"trace[949449954] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"207.135826ms","start":"2026-10-13T15:04:16.37362Z","end":"2026-10-13T15:04:16.580756Z","steps":["trace[949449954] 'process raft request' (duration: 203.421082ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:04:16.732784Z","caller":"traceutil/trace.go:171","msg":"trace[117782974] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"358.810379ms","start":"2026-10-13T15:04:16.373929Z","end":"2026-10-13T15:04:16.73274Z","steps":["trace[117782974] 'process raft request' (duration: 314.833705ms)","trace[117782974] 'compare' (duration: 43.682855ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:04:16.732811Z","caller":"traceutil/trace.go:171","msg":"trace[1677553416] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1920; }","duration":"307.16281ms","start":"2026-10-13T15:04:16.425607Z","end":"2026-10-13T15:04:16.73277Z","steps":["trace[1677553416] 'read index received' (duration: 151.504387ms)","trace[1677553416] 'applied index is now lower than readState.Index' (duration: 155.657263ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:04:16.733754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:04:16.373891Z","time spent":"359.783413ms","remote":"127.0.0.1:48648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:04:16.732851Z","caller":"traceutil/trace.go:171","msg":"trace[365058976] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"357.976227ms","start":"2026-10-13T15:04:16.374824Z","end":"2026-10-13T15:04:16.7328Z","steps":["trace[365058976] 'process raft request' (duration: 357.825522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:04:16.732999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.272923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-13T15:04:16.734165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:04:16.374809Z","time spent":"359.188387ms","remote":"127.0.0.1:48534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:04:16.734196Z","caller":"traceutil/trace.go:171","msg":"trace[437018588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"308.572185ms","start":"2026-10-13T15:04:16.425594Z","end":"2026-10-13T15:04:16.734166Z","steps":["trace[437018588] 'agreement among raft nodes before linearized reading' (duration: 307.246372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:04:16.734332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:04:16.42552Z","time spent":"308.799101ms","remote":"127.0.0.1:48352","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-10-13T15:04:16.732912Z","caller":"traceutil/trace.go:171","msg":"trace[401442197] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"358.053909ms","start":"2026-10-13T15:04:16.37483Z","end":"2026-10-13T15:04:16.732884Z","steps":["trace[401442197] 'process raft request' (duration: 357.893685ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:04:16.734602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:04:16.374799Z","time spent":"359.647699ms","remote":"127.0.0.1:48648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:04:17.136677Z","caller":"traceutil/trace.go:171","msg":"trace[1920100403] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"178.587268ms","start":"2026-10-13T15:04:16.958056Z","end":"2026-10-13T15:04:17.136643Z","steps":["trace[1920100403] 'process raft request' (duration: 178.384853ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:04:17.138891Z","caller":"traceutil/trace.go:171","msg":"trace[1658886042] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"109.918406ms","start":"2026-10-13T15:04:17.028934Z","end":"2026-10-13T15:04:17.138852Z","steps":["trace[1658886042] 'process raft request' (duration: 109.68273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:05:06.264278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.308872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-10-13T15:05:06.264423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.408337ms","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-10-13T15:05:06.264425Z","caller":"traceutil/trace.go:171","msg":"trace[2050123234] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2171; }","duration":"194.515407ms","start":"2026-10-13T15:05:06.069881Z","end":"2026-10-13T15:05:06.264396Z","steps":["trace[2050123234] 'range keys from in-memory index tree' (duration: 194.099697ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:05:06.264481Z","caller":"traceutil/trace.go:171","msg":"trace[1096266557] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2171; }","duration":"126.545021ms","start":"2026-10-13T15:05:06.137916Z","end":"2026-10-13T15:05:06.264461Z","steps":["trace[1096266557] 'range keys from in-memory index tree' (duration: 126.229734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:05:06.265236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.285103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-13T15:05:06.265323Z","caller":"traceutil/trace.go:171","msg":"trace[1651043634] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2171; }","duration":"207.397296ms","start":"2026-10-13T15:05:06.057907Z","end":"2026-10-13T15:05:06.265305Z","steps":["trace[1651043634] 'range keys from in-memory index tree' (duration: 206.333529ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:05:06.3883Z","caller":"traceutil/trace.go:171","msg":"trace[714632721] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"117.703335ms","start":"2026-10-13T15:05:06.270564Z","end":"2026-10-13T15:05:06.388267Z","steps":["trace[714632721] 'process raft request' (duration: 117.49531ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:05:06.554766Z","caller":"traceutil/trace.go:171","msg":"trace[1425635762] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2254; }","duration":"102.732183ms","start":"2026-10-13T15:05:06.452003Z","end":"2026-10-13T15:05:06.554735Z","steps":["trace[1425635762] 'read index received' (duration: 102.397364ms)","trace[1425635762] 'applied index is now lower than readState.Index' (duration: 333.519µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:05:06.554818Z","caller":"traceutil/trace.go:171","msg":"trace[1243741589] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"284.157326ms","start":"2026-10-13T15:05:06.270623Z","end":"2026-10-13T15:05:06.55478Z","steps":["trace[1243741589] 'process raft request' (duration: 283.875839ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:05:06.554852Z","caller":"traceutil/trace.go:171","msg":"trace[2117918309] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"242.669556ms","start":"2026-10-13T15:05:06.312158Z","end":"2026-10-13T15:05:06.554828Z","steps":["trace[2117918309] 'process raft request' (duration: 242.510382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:05:06.554937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.894147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:05:06.554995Z","caller":"traceutil/trace.go:171","msg":"trace[1327812468] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2174; }","duration":"103.04302ms","start":"2026-10-13T15:05:06.451942Z","end":"2026-10-13T15:05:06.554985Z","steps":["trace[1327812468] 'agreement among raft nodes before linearized reading' (duration: 102.903746ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:06:20.774388Z","caller":"traceutil/trace.go:171","msg":"trace[181282718] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"314.326258ms","start":"2026-10-13T15:06:20.460035Z","end":"2026-10-13T15:06:20.774361Z","steps":["trace[181282718] 'process raft request' (duration: 314.131103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:06:20.77458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:06:20.459999Z","time spent":"314.479162ms","remote":"127.0.0.1:48636","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-10-13T15:06:20.821651Z","caller":"traceutil/trace.go:171","msg":"trace[1720808253] linearizableReadLoop","detail":"{readStateIndex:2876; appliedIndex:2874; }","duration":"161.185122ms","start":"2026-10-13T15:06:20.660436Z","end":"2026-10-13T15:06:20.821621Z","steps":["trace[1720808253] 'read index received' (duration: 113.801827ms)","trace[1720808253] 'applied index is now lower than readState.Index' (duration: 47.382005ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:06:20.821689Z","caller":"traceutil/trace.go:171","msg":"trace[1631346039] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"360.110082ms","start":"2026-10-13T15:06:20.461558Z","end":"2026-10-13T15:06:20.821668Z","steps":["trace[1631346039] 'process raft request' (duration: 334.615739ms)","trace[1631346039] 'compare' (duration: 25.327949ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:06:20.821855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:06:20.461528Z","time spent":"360.228305ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:06:20.821864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.423218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-13T15:06:20.821948Z","caller":"traceutil/trace.go:171","msg":"trace[1425688000] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2772; }","duration":"161.51874ms","start":"2026-10-13T15:06:20.660379Z","end":"2026-10-13T15:06:20.821898Z","steps":["trace[1425688000] 'agreement among raft nodes before linearized reading' (duration: 161.425718ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:10:56.559029Z","caller":"traceutil/trace.go:171","msg":"trace[111893765] linearizableReadLoop","detail":"{readStateIndex:5353; appliedIndex:5352; }","duration":"198.286014ms","start":"2026-10-13T15:10:56.360699Z","end":"2026-10-13T15:10:56.558985Z","steps":["trace[111893765] 'read index received' (duration: 198.051998ms)","trace[111893765] 'applied index is now lower than readState.Index' (duration: 231.516µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:10:56.559263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.553891ms","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-10-13T15:10:56.5593Z","caller":"traceutil/trace.go:171","msg":"trace[458395409] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5163; }","duration":"198.624493ms","start":"2026-10-13T15:10:56.360666Z","end":"2026-10-13T15:10:56.559291Z","steps":["trace[458395409] 'agreement among raft nodes before linearized reading' (duration: 198.483009ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:10:56.559098Z","caller":"traceutil/trace.go:171","msg":"trace[1401878207] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"200.196952ms","start":"2026-10-13T15:10:56.358873Z","end":"2026-10-13T15:10:56.55907Z","steps":["trace[1401878207] 'process raft request' (duration: 199.805312ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:10:56.786913Z","caller":"traceutil/trace.go:171","msg":"trace[662651851] linearizableReadLoop","detail":"{readStateIndex:5354; appliedIndex:5353; }","duration":"227.596466ms","start":"2026-10-13T15:10:56.559273Z","end":"2026-10-13T15:10:56.78687Z","steps":["trace[662651851] 'read index received' (duration: 227.256126ms)","trace[662651851] 'applied index is now lower than readState.Index' (duration: 338.65µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:10:56.787036Z","caller":"traceutil/trace.go:171","msg":"trace[725743976] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"416.504608ms","start":"2026-10-13T15:10:56.370491Z","end":"2026-10-13T15:10:56.786996Z","steps":["trace[725743976] 'process raft request' (duration: 416.093967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:10:56.78723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:10:56.370457Z","time spent":"416.664352ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18238,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:10:56.787256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.802801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c3ee2c46-61e1-4d1b-9a69-0392e9153178\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-13T15:10:56.787345Z","caller":"traceutil/trace.go:171","msg":"trace[1292019188] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c3ee2c46-61e1-4d1b-9a69-0392e9153178; range_end:; response_count:1; response_revision:5164; }","duration":"291.934384ms","start":"2026-10-13T15:10:56.495381Z","end":"2026-10-13T15:10:56.787316Z","steps":["trace[1292019188] 'agreement among raft nodes before linearized reading' (duration: 291.647927ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:10:56.794497Z","caller":"traceutil/trace.go:171","msg":"trace[1038546225] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"231.489484ms","start":"2026-10-13T15:10:56.56295Z","end":"2026-10-13T15:10:56.79444Z","steps":["trace[1038546225] 'process raft request' (duration: 231.249768ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:10:58.901058Z","caller":"traceutil/trace.go:171","msg":"trace[823864610] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"100.323662ms","start":"2026-10-13T15:10:58.800704Z","end":"2026-10-13T15:10:58.901028Z","steps":["trace[823864610] 'process raft request' (duration: 59.815244ms)","trace[823864610] 'compare' (duration: 40.332774ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:11:11.706251Z","caller":"traceutil/trace.go:171","msg":"trace[1030629649] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"140.967154ms","start":"2026-10-13T15:11:11.565255Z","end":"2026-10-13T15:11:11.706222Z","steps":["trace[1030629649] 'process raft request' (duration: 60.262095ms)","trace[1030629649] 'compare' (duration: 80.514545ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:11:11.979565Z","caller":"traceutil/trace.go:171","msg":"trace[280828525] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"261.784832ms","start":"2026-10-13T15:11:11.717752Z","end":"2026-10-13T15:11:11.979537Z","steps":["trace[280828525] 'process raft request' (duration: 234.39311ms)","trace[280828525] 'compare' (duration: 27.257959ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:11:19.432074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.746827ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-13T15:11:19.432223Z","caller":"traceutil/trace.go:171","msg":"trace[750037246] linearizableReadLoop","detail":"{readStateIndex:5801; appliedIndex:5800; }","duration":"138.45851ms","start":"2026-10-13T15:11:19.293724Z","end":"2026-10-13T15:11:19.432182Z","steps":["trace[750037246] 'read index received' (duration: 22.534818ms)","trace[750037246] 'applied index is now lower than readState.Index' (duration: 115.922002ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:11:19.432497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.770848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a9f5d9f-da59-4fe6-bb44-cc0d15aa1782\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-13T15:11:19.432585Z","caller":"traceutil/trace.go:171","msg":"trace[1134998275] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5a9f5d9f-da59-4fe6-bb44-cc0d15aa1782; range_end:; response_count:1; response_revision:5597; }","duration":"138.91108ms","start":"2026-10-13T15:11:19.293655Z","end":"2026-10-13T15:11:19.432566Z","steps":["trace[1134998275] 'agreement among raft nodes before linearized reading' (duration: 138.637384ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:11:21.958035Z","caller":"traceutil/trace.go:171","msg":"trace[1557773530] linearizableReadLoop","detail":"{readStateIndex:5824; appliedIndex:5823; }","duration":"117.172404ms","start":"2026-10-13T15:11:21.840827Z","end":"2026-10-13T15:11:21.958Z","steps":["trace[1557773530] 'read index received' (duration: 105.028462ms)","trace[1557773530] 'applied index is now lower than readState.Index' (duration: 12.142452ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:11:21.958203Z","caller":"traceutil/trace.go:171","msg":"trace[1322668337] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"117.415941ms","start":"2026-10-13T15:11:21.840771Z","end":"2026-10-13T15:11:21.958187Z","steps":["trace[1322668337] 'process raft request' (duration: 105.150777ms)","trace[1322668337] 'compare' (duration: 11.799952ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:11:21.959024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.76325ms","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-10-13T15:11:21.959092Z","caller":"traceutil/trace.go:171","msg":"trace[362293153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5620; }","duration":"118.308483ms","start":"2026-10-13T15:11:21.840766Z","end":"2026-10-13T15:11:21.959074Z","steps":["trace[362293153] 'agreement among raft nodes before linearized reading' (duration: 117.688087ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:11:50.489238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2935} {"level":"info","ts":"2026-10-13T15:11:50.572346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2935,"took":"81.831808ms","hash":2271015251,"current-db-size-bytes":54931456,"current-db-size":"55 MB","current-db-size-in-use-bytes":31604736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-13T15:11:50.5724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2271015251,"revision":2935,"compact-revision":-1} {"level":"info","ts":"2026-10-13T15:16:50.495973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5885} {"level":"info","ts":"2026-10-13T15:16:50.58236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5885,"took":"84.59907ms","hash":2761230676,"current-db-size-bytes":54931456,"current-db-size":"55 MB","current-db-size-in-use-bytes":25825280,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-13T15:16:50.582461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2761230676,"revision":5885,"compact-revision":2935} {"level":"info","ts":"2026-10-13T15:17:22.132077Z","caller":"traceutil/trace.go:171","msg":"trace[508165526] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"262.904121ms","start":"2026-10-13T15:17:21.869138Z","end":"2026-10-13T15:17:22.132043Z","steps":["trace[508165526] 'process raft request' (duration: 262.48281ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:17:34.442893Z","caller":"traceutil/trace.go:171","msg":"trace[1014115726] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"232.249375ms","start":"2026-10-13T15:17:34.210618Z","end":"2026-10-13T15:17:34.442867Z","steps":["trace[1014115726] 'process raft request' (duration: 232.064269ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:17:34.46057Z","caller":"traceutil/trace.go:171","msg":"trace[585596987] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"121.821304ms","start":"2026-10-13T15:17:34.338724Z","end":"2026-10-13T15:17:34.460546Z","steps":["trace[585596987] 'process raft request' (duration: 121.498806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:18:03.832419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.580062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:18:03.832527Z","caller":"traceutil/trace.go:171","msg":"trace[1737528440] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:8687; }","duration":"244.754057ms","start":"2026-10-13T15:18:03.587747Z","end":"2026-10-13T15:18:03.832501Z","steps":["trace[1737528440] 'count revisions from in-memory index tree' (duration: 244.48169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:18:03.832525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.560818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:18:03.83264Z","caller":"traceutil/trace.go:171","msg":"trace[1858726781] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8687; }","duration":"302.735263ms","start":"2026-10-13T15:18:03.529884Z","end":"2026-10-13T15:18:03.83262Z","steps":["trace[1858726781] 'count revisions from in-memory index tree' (duration: 302.480476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:18:03.832682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:18:03.529864Z","time spent":"302.809794ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-10-13T15:18:03.832741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.298003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-13T15:18:03.832863Z","caller":"traceutil/trace.go:171","msg":"trace[1617213073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8687; }","duration":"405.483377ms","start":"2026-10-13T15:18:03.427355Z","end":"2026-10-13T15:18:03.832838Z","steps":["trace[1617213073] 'range keys from in-memory index tree' (duration: 405.20709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:18:03.832894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.203384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pfztb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-13T15:18:03.83292Z","caller":"traceutil/trace.go:171","msg":"trace[1322693334] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pfztb; range_end:; response_count:1; response_revision:8687; }","duration":"349.271656ms","start":"2026-10-13T15:18:03.483641Z","end":"2026-10-13T15:18:03.832913Z","steps":["trace[1322693334] 'range keys from in-memory index tree' (duration: 349.06321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:18:03.832916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:18:03.427333Z","time spent":"405.572919ms","remote":"127.0.0.1:48352","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-10-13T15:18:03.832936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:18:03.48362Z","time spent":"349.311927ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-pfztb\" "} {"level":"info","ts":"2026-10-13T15:18:10.910293Z","caller":"traceutil/trace.go:171","msg":"trace[735090852] linearizableReadLoop","detail":"{readStateIndex:9030; appliedIndex:9028; }","duration":"161.951362ms","start":"2026-10-13T15:18:10.748314Z","end":"2026-10-13T15:18:10.910265Z","steps":["trace[735090852] 'read index received' (duration: 16.685347ms)","trace[735090852] 'applied index is now lower than readState.Index' (duration: 145.264585ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:18:10.91041Z","caller":"traceutil/trace.go:171","msg":"trace[1359171516] transaction","detail":"{read_only:false; response_revision:8725; number_of_response:1; }","duration":"165.775061ms","start":"2026-10-13T15:18:10.744624Z","end":"2026-10-13T15:18:10.910399Z","steps":["trace[1359171516] 'process raft request' (duration: 165.535855ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:18:10.910332Z","caller":"traceutil/trace.go:171","msg":"trace[1099723294] transaction","detail":"{read_only:false; response_revision:8724; number_of_response:1; }","duration":"174.113355ms","start":"2026-10-13T15:18:10.736171Z","end":"2026-10-13T15:18:10.910284Z","steps":["trace[1099723294] 'process raft request' (duration: 166.164051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:18:10.910666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.038963ms","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-10-13T15:18:10.910752Z","caller":"traceutil/trace.go:171","msg":"trace[2013366688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8725; }","duration":"135.195106ms","start":"2026-10-13T15:18:10.775536Z","end":"2026-10-13T15:18:10.910732Z","steps":["trace[2013366688] 'agreement among raft nodes before linearized reading' (duration: 134.9698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:18:10.910842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.520257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-13T15:18:10.910911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.158919ms","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-10-13T15:18:10.910925Z","caller":"traceutil/trace.go:171","msg":"trace[2048930537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8725; }","duration":"162.647711ms","start":"2026-10-13T15:18:10.748259Z","end":"2026-10-13T15:18:10.910906Z","steps":["trace[2048930537] 'agreement among raft nodes before linearized reading' (duration: 162.387454ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:18:10.910956Z","caller":"traceutil/trace.go:171","msg":"trace[231332478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8725; }","duration":"134.221041ms","start":"2026-10-13T15:18:10.776724Z","end":"2026-10-13T15:18:10.910945Z","steps":["trace[231332478] 'agreement among raft nodes before linearized reading' (duration: 134.044666ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:18:11.077111Z","caller":"traceutil/trace.go:171","msg":"trace[152112750] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"160.65315ms","start":"2026-10-13T15:18:10.916435Z","end":"2026-10-13T15:18:11.077088Z","steps":["trace[152112750] 'process raft request' (duration: 110.631018ms)","trace[152112750] 'compare' (duration: 49.727185ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:18:11.077539Z","caller":"traceutil/trace.go:171","msg":"trace[1704944598] linearizableReadLoop","detail":"{readStateIndex:9033; appliedIndex:9030; }","duration":"149.575035ms","start":"2026-10-13T15:18:10.927954Z","end":"2026-10-13T15:18:11.077529Z","steps":["trace[1704944598] 'read index received' (duration: 76.345048ms)","trace[1704944598] 'applied index is now lower than readState.Index' (duration: 73.229027ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:18:11.077525Z","caller":"traceutil/trace.go:171","msg":"trace[547106031] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"161.015349ms","start":"2026-10-13T15:18:10.916478Z","end":"2026-10-13T15:18:11.077493Z","steps":["trace[547106031] 'process raft request' (duration: 160.550888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:18:11.077664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.703878ms","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-10-13T15:18:11.077686Z","caller":"traceutil/trace.go:171","msg":"trace[1088208118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8728; }","duration":"149.77417ms","start":"2026-10-13T15:18:10.927906Z","end":"2026-10-13T15:18:11.077681Z","steps":["trace[1088208118] 'agreement among raft nodes before linearized reading' (duration: 149.652647ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:18:18.42841Z","caller":"traceutil/trace.go:171","msg":"trace[670106800] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"100.558938ms","start":"2026-10-13T15:18:18.327809Z","end":"2026-10-13T15:18:18.428368Z","steps":["trace[670106800] 'process raft request' (duration: 100.297282ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:19:12.797521Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000259f.wal"} {"level":"info","ts":"2026-10-13T15:19:51.293153Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T15:19:51.300114Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-13T15:19:51.300555Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-13T15:21:50.502054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8068} {"level":"info","ts":"2026-10-13T15:21:50.573362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8068,"took":"69.465364ms","hash":625093677,"current-db-size-bytes":54931456,"current-db-size":"55 MB","current-db-size-in-use-bytes":28229632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-13T15:21:50.57344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":625093677,"revision":8068,"compact-revision":5885} {"level":"info","ts":"2026-10-13T15:26:50.51314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10499} {"level":"info","ts":"2026-10-13T15:26:50.604131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10499,"took":"88.398707ms","hash":2124578637,"current-db-size-bytes":54931456,"current-db-size":"55 MB","current-db-size-in-use-bytes":27041792,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-13T15:26:50.604215Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2124578637,"revision":10499,"compact-revision":8068} {"level":"info","ts":"2026-10-13T15:29:14.624287Z","caller":"traceutil/trace.go:171","msg":"trace[1529235034] transaction","detail":"{read_only:false; response_revision:13811; number_of_response:1; }","duration":"127.320838ms","start":"2026-10-13T15:29:14.496913Z","end":"2026-10-13T15:29:14.624233Z","steps":["trace[1529235034] 'process raft request' (duration: 127.134533ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:29:35.271366Z","caller":"traceutil/trace.go:171","msg":"trace[199515640] transaction","detail":"{read_only:false; response_revision:13923; number_of_response:1; }","duration":"205.424284ms","start":"2026-10-13T15:29:35.065907Z","end":"2026-10-13T15:29:35.271331Z","steps":["trace[199515640] 'process raft request' (duration: 205.177107ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:29:53.532393Z","caller":"traceutil/trace.go:171","msg":"trace[1212476635] transaction","detail":"{read_only:false; response_revision:14037; number_of_response:1; }","duration":"108.856055ms","start":"2026-10-13T15:29:53.423509Z","end":"2026-10-13T15:29:53.532365Z","steps":["trace[1212476635] 'process raft request' (duration: 108.221419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:29:53.909526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.02899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-13T15:29:53.909762Z","caller":"traceutil/trace.go:171","msg":"trace[847250014] transaction","detail":"{read_only:false; response_revision:14038; number_of_response:1; }","duration":"239.748378ms","start":"2026-10-13T15:29:53.669995Z","end":"2026-10-13T15:29:53.909744Z","steps":["trace[847250014] 'process raft request' (duration: 73.38989ms)","trace[847250014] 'compare' (duration: 165.713341ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:29:53.910008Z","caller":"traceutil/trace.go:171","msg":"trace[1900077864] transaction","detail":"{read_only:false; response_revision:14039; number_of_response:1; }","duration":"222.409672ms","start":"2026-10-13T15:29:53.687564Z","end":"2026-10-13T15:29:53.909974Z","steps":["trace[1900077864] 'process raft request' (duration: 222.100524ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:29:53.918028Z","caller":"traceutil/trace.go:171","msg":"trace[1271549195] transaction","detail":"{read_only:false; response_revision:14040; number_of_response:1; }","duration":"164.568601ms","start":"2026-10-13T15:29:53.753431Z","end":"2026-10-13T15:29:53.917999Z","steps":["trace[1271549195] 'process raft request' (duration: 164.388786ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:31:50.519733Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12755} {"level":"info","ts":"2026-10-13T15:31:50.611937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12755,"took":"89.389272ms","hash":224467162,"current-db-size-bytes":54931456,"current-db-size":"55 MB","current-db-size-in-use-bytes":30552064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T15:31:50.612037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":224467162,"revision":12755,"compact-revision":10499} {"level":"info","ts":"2026-10-13T15:36:50.527003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15324} {"level":"info","ts":"2026-10-13T15:36:50.628839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15324,"took":"98.643791ms","hash":3900385929,"current-db-size-bytes":54931456,"current-db-size":"55 MB","current-db-size-in-use-bytes":32284672,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-13T15:36:50.628908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3900385929,"revision":15324,"compact-revision":12755} {"level":"info","ts":"2026-10-13T15:37:13.272461Z","caller":"traceutil/trace.go:171","msg":"trace[1799614432] transaction","detail":"{read_only:false; response_revision:17768; number_of_response:1; }","duration":"142.246224ms","start":"2026-10-13T15:37:13.130175Z","end":"2026-10-13T15:37:13.272421Z","steps":["trace[1799614432] 'process raft request' (duration: 142.017637ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:37:19.23824Z","caller":"traceutil/trace.go:171","msg":"trace[62821817] transaction","detail":"{read_only:false; response_revision:17844; number_of_response:1; }","duration":"177.46393ms","start":"2026-10-13T15:37:19.060754Z","end":"2026-10-13T15:37:19.238218Z","steps":["trace[62821817] 'process raft request' (duration: 177.324567ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:37:19.23894Z","caller":"traceutil/trace.go:171","msg":"trace[2012723739] linearizableReadLoop","detail":"{readStateIndex:18443; appliedIndex:18443; }","duration":"145.194458ms","start":"2026-10-13T15:37:19.093733Z","end":"2026-10-13T15:37:19.238928Z","steps":["trace[2012723739] 'read index received' (duration: 145.190118ms)","trace[2012723739] 'applied index is now lower than readState.Index' (duration: 3.53µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:37:19.239089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.356023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-13T15:37:19.239125Z","caller":"traceutil/trace.go:171","msg":"trace[1279942243] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17844; }","duration":"145.442155ms","start":"2026-10-13T15:37:19.093671Z","end":"2026-10-13T15:37:19.239114Z","steps":["trace[1279942243] 'agreement among raft nodes before linearized reading' (duration: 145.318782ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:37:19.342952Z","caller":"traceutil/trace.go:171","msg":"trace[720226791] linearizableReadLoop","detail":"{readStateIndex:18444; appliedIndex:18443; }","duration":"103.957487ms","start":"2026-10-13T15:37:19.238974Z","end":"2026-10-13T15:37:19.342932Z","steps":["trace[720226791] 'read index received' (duration: 98.159908ms)","trace[720226791] 'applied index is now lower than readState.Index' (duration: 5.796719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:37:19.343248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.92383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T15:37:19.343303Z","caller":"traceutil/trace.go:171","msg":"trace[864907633] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17845; }","duration":"223.027103ms","start":"2026-10-13T15:37:19.120261Z","end":"2026-10-13T15:37:19.343288Z","steps":["trace[864907633] 'agreement among raft nodes before linearized reading' (duration: 222.932841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:37:47.858063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.918036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:99 size:975051"} {"level":"info","ts":"2026-10-13T15:37:47.858165Z","caller":"traceutil/trace.go:171","msg":"trace[57031083] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:99; response_revision:18239; }","duration":"125.089411ms","start":"2026-10-13T15:37:47.733054Z","end":"2026-10-13T15:37:47.858143Z","steps":["trace[57031083] 'range keys from in-memory index tree' (duration: 123.88926ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:40:18.576817Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T15:40:18.583734Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-13T15:40:18.583871Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-13T15:41:50.533871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17571} {"level":"info","ts":"2026-10-13T15:41:50.641674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17571,"took":"104.551029ms","hash":3263941376,"current-db-size-bytes":54931456,"current-db-size":"55 MB","current-db-size-in-use-bytes":34332672,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-13T15:41:50.642107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3263941376,"revision":17571,"compact-revision":15324} {"level":"info","ts":"2026-10-13T15:42:25.218968Z","caller":"traceutil/trace.go:171","msg":"trace[2015807732] transaction","detail":"{read_only:false; response_revision:20236; number_of_response:1; }","duration":"160.273872ms","start":"2026-10-13T15:42:25.058652Z","end":"2026-10-13T15:42:25.218926Z","steps":["trace[2015807732] 'process raft request' (duration: 159.988935ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:42:31.131926Z","caller":"traceutil/trace.go:171","msg":"trace[967374160] transaction","detail":"{read_only:false; response_revision:20259; number_of_response:1; }","duration":"170.901765ms","start":"2026-10-13T15:42:30.960996Z","end":"2026-10-13T15:42:31.131898Z","steps":["trace[967374160] 'process raft request' (duration: 170.740331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:42:31.487809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.896174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:42:31.488025Z","caller":"traceutil/trace.go:171","msg":"trace[982463811] linearizableReadLoop","detail":"{readStateIndex:20940; appliedIndex:20937; }","duration":"437.864352ms","start":"2026-10-13T15:42:31.050144Z","end":"2026-10-13T15:42:31.488008Z","steps":["trace[982463811] 'read index received' (duration: 81.68938ms)","trace[982463811] 'applied index is now lower than readState.Index' (duration: 356.173782ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:42:31.488043Z","caller":"traceutil/trace.go:171","msg":"trace[1158587782] transaction","detail":"{read_only:false; response_revision:20261; number_of_response:1; }","duration":"455.162887ms","start":"2026-10-13T15:42:31.032839Z","end":"2026-10-13T15:42:31.488002Z","steps":["trace[1158587782] 'process raft request' (duration: 455.060185ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:42:31.488063Z","caller":"traceutil/trace.go:171","msg":"trace[60655410] transaction","detail":"{read_only:false; response_revision:20260; number_of_response:1; }","duration":"458.105613ms","start":"2026-10-13T15:42:31.02989Z","end":"2026-10-13T15:42:31.487996Z","steps":["trace[60655410] 'process raft request' (duration: 267.938282ms)","trace[60655410] 'compare' (duration: 189.571236ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:42:31.488181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.040737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-13T15:42:31.488176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:42:31.032825Z","time spent":"455.275281ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:42:31.488179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:42:31.029866Z","time spent":"458.259488ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:42:31.48821Z","caller":"traceutil/trace.go:171","msg":"trace[1612474652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20261; }","duration":"438.108658ms","start":"2026-10-13T15:42:31.050089Z","end":"2026-10-13T15:42:31.488198Z","steps":["trace[1612474652] 'agreement among raft nodes before linearized reading' (duration: 437.988775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:42:31.488358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:42:31.050069Z","time spent":"438.276042ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-13T15:42:31.518294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.836653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-13T15:42:31.51838Z","caller":"traceutil/trace.go:171","msg":"trace[1778536418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20262; }","duration":"467.954027ms","start":"2026-10-13T15:42:31.050403Z","end":"2026-10-13T15:42:31.518357Z","steps":["trace[1778536418] 'agreement among raft nodes before linearized reading' (duration: 467.726311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:42:31.518417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:42:31.050392Z","time spent":"468.016338ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-13T15:42:31.518573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.004493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-13T15:42:31.518632Z","caller":"traceutil/trace.go:171","msg":"trace[1746387813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20262; }","duration":"273.110035ms","start":"2026-10-13T15:42:31.245513Z","end":"2026-10-13T15:42:31.518623Z","steps":["trace[1746387813] 'agreement among raft nodes before linearized reading' (duration: 272.997782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:42:31.518411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.747689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-13T15:42:31.520224Z","caller":"traceutil/trace.go:171","msg":"trace[1954144116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20262; }","duration":"271.621426ms","start":"2026-10-13T15:42:31.248577Z","end":"2026-10-13T15:42:31.520198Z","steps":["trace[1954144116] 'agreement among raft nodes before linearized reading' (duration: 269.604785ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:42:31.772889Z","caller":"traceutil/trace.go:171","msg":"trace[2025611671] linearizableReadLoop","detail":"{readStateIndex:20942; appliedIndex:20941; }","duration":"252.069274ms","start":"2026-10-13T15:42:31.520794Z","end":"2026-10-13T15:42:31.772863Z","steps":["trace[2025611671] 'read index received' (duration: 237.035136ms)","trace[2025611671] 'applied index is now lower than readState.Index' (duration: 15.033088ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:42:31.773035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.224637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:42:31.773073Z","caller":"traceutil/trace.go:171","msg":"trace[1548516975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20263; }","duration":"252.278189ms","start":"2026-10-13T15:42:31.520785Z","end":"2026-10-13T15:42:31.773063Z","steps":["trace[1548516975] 'agreement among raft nodes before linearized reading' (duration: 252.167866ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:42:31.77332Z","caller":"traceutil/trace.go:171","msg":"trace[786957616] transaction","detail":"{read_only:false; response_revision:20263; number_of_response:1; }","duration":"280.193137ms","start":"2026-10-13T15:42:31.493098Z","end":"2026-10-13T15:42:31.773291Z","steps":["trace[786957616] 'process raft request' (duration: 264.717078ms)","trace[786957616] 'compare' (duration: 14.747809ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:42:32.11572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.411899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:42:32.115952Z","caller":"traceutil/trace.go:171","msg":"trace[2075825243] transaction","detail":"{read_only:false; response_revision:20265; number_of_response:1; }","duration":"592.640544ms","start":"2026-10-13T15:42:31.52329Z","end":"2026-10-13T15:42:32.11593Z","steps":["trace[2075825243] 'process raft request' (duration: 592.520141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:42:32.116046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:42:31.523282Z","time spent":"592.719165ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:42:32.116094Z","caller":"traceutil/trace.go:171","msg":"trace[964764937] linearizableReadLoop","detail":"{readStateIndex:20945; appliedIndex:20942; }","duration":"244.409466ms","start":"2026-10-13T15:42:31.871666Z","end":"2026-10-13T15:42:32.116076Z","steps":["trace[964764937] 'read index received' (duration: 133.537355ms)","trace[964764937] 'applied index is now lower than readState.Index' (duration: 110.871031ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:42:32.116319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.646072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-13T15:42:32.116376Z","caller":"traceutil/trace.go:171","msg":"trace[610372790] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20266; }","duration":"244.750524ms","start":"2026-10-13T15:42:31.871611Z","end":"2026-10-13T15:42:32.116361Z","steps":["trace[610372790] 'agreement among raft nodes before linearized reading' (duration: 244.57676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:42:32.116313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.284759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T15:42:32.116397Z","caller":"traceutil/trace.go:171","msg":"trace[34728748] transaction","detail":"{read_only:false; response_revision:20264; number_of_response:1; }","duration":"593.2543ms","start":"2026-10-13T15:42:31.52308Z","end":"2026-10-13T15:42:32.116335Z","steps":["trace[34728748] 'process raft request' (duration: 482.150583ms)","trace[34728748] 'compare' (duration: 110.201314ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:42:32.116464Z","caller":"traceutil/trace.go:171","msg":"trace[525026439] transaction","detail":"{read_only:false; response_revision:20266; number_of_response:1; }","duration":"591.626688ms","start":"2026-10-13T15:42:31.524828Z","end":"2026-10-13T15:42:32.116455Z","steps":["trace[525026439] 'process raft request' (duration: 591.056023ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:42:32.116505Z","caller":"traceutil/trace.go:171","msg":"trace[1147963060] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:20266; }","duration":"191.463144ms","start":"2026-10-13T15:42:31.924997Z","end":"2026-10-13T15:42:32.11646Z","steps":["trace[1147963060] 'agreement among raft nodes before linearized reading' (duration: 191.228108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:42:32.116539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:42:31.524789Z","time spent":"591.696939ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:42:32.116609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:42:31.523061Z","time spent":"593.423734ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:46:50.540082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20054} {"level":"info","ts":"2026-10-13T15:46:50.670319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20054,"took":"126.655987ms","hash":2929780841,"current-db-size-bytes":54931456,"current-db-size":"55 MB","current-db-size-in-use-bytes":42295296,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-13T15:46:50.670393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2929780841,"revision":20054,"compact-revision":17571} {"level":"info","ts":"2026-10-13T15:49:32.970967Z","caller":"traceutil/trace.go:171","msg":"trace[731925637] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"154.34777ms","start":"2026-10-13T15:49:32.816592Z","end":"2026-10-13T15:49:32.97094Z","steps":["trace[731925637] 'process raft request' (duration: 154.177105ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:38.47357Z","caller":"traceutil/trace.go:171","msg":"trace[1646875031] linearizableReadLoop","detail":"{readStateIndex:25495; appliedIndex:25494; }","duration":"114.644549ms","start":"2026-10-13T15:49:38.358897Z","end":"2026-10-13T15:49:38.473541Z","steps":["trace[1646875031] 'read index received' (duration: 114.384361ms)","trace[1646875031] 'applied index is now lower than readState.Index' (duration: 258.998µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:38.473909Z","caller":"traceutil/trace.go:171","msg":"trace[903701170] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"130.408575ms","start":"2026-10-13T15:49:38.343482Z","end":"2026-10-13T15:49:38.473891Z","steps":["trace[903701170] 'process raft request' (duration: 129.87545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:38.474288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.376877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T15:49:38.474363Z","caller":"traceutil/trace.go:171","msg":"trace[1280775943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24690; }","duration":"115.4955ms","start":"2026-10-13T15:49:38.358849Z","end":"2026-10-13T15:49:38.474344Z","steps":["trace[1280775943] 'agreement among raft nodes before linearized reading' (duration: 115.276425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:38.759857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.340199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:49:38.759961Z","caller":"traceutil/trace.go:171","msg":"trace[785175908] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"278.100021ms","start":"2026-10-13T15:49:38.481843Z","end":"2026-10-13T15:49:38.759943Z","steps":["trace[785175908] 'process raft request' (duration: 139.580588ms)","trace[785175908] 'compare' (duration: 137.854176ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:38.800841Z","caller":"traceutil/trace.go:171","msg":"trace[1090760476] linearizableReadLoop","detail":"{readStateIndex:25498; appliedIndex:25495; }","duration":"304.753597ms","start":"2026-10-13T15:49:38.496063Z","end":"2026-10-13T15:49:38.800817Z","steps":["trace[1090760476] 'read index received' (duration: 125.374414ms)","trace[1090760476] 'applied index is now lower than readState.Index' (duration: 179.378103ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:38.800881Z","caller":"traceutil/trace.go:171","msg":"trace[2077663964] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"318.728776ms","start":"2026-10-13T15:49:38.482129Z","end":"2026-10-13T15:49:38.800857Z","steps":["trace[2077663964] 'process raft request' (duration: 318.637424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:38.801007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:38.482114Z","time spent":"318.817168ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:38.801058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.977852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-13T15:49:38.801097Z","caller":"traceutil/trace.go:171","msg":"trace[134862603] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24693; }","duration":"305.065695ms","start":"2026-10-13T15:49:38.496021Z","end":"2026-10-13T15:49:38.801086Z","steps":["trace[134862603] 'agreement among raft nodes before linearized reading' (duration: 304.89132ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:38.801132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:38.496004Z","time spent":"305.120866ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-13T15:49:38.801145Z","caller":"traceutil/trace.go:171","msg":"trace[630915764] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"319.146467ms","start":"2026-10-13T15:49:38.481983Z","end":"2026-10-13T15:49:38.80113Z","steps":["trace[630915764] 'process raft request' (duration: 318.629134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:38.801222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:38.481857Z","time spent":"319.313222ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:39.097421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.941246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:49:39.097786Z","caller":"traceutil/trace.go:171","msg":"trace[1030245981] linearizableReadLoop","detail":"{readStateIndex:25500; appliedIndex:25498; }","duration":"198.433073ms","start":"2026-10-13T15:49:38.89933Z","end":"2026-10-13T15:49:39.097763Z","steps":["trace[1030245981] 'read index received' (duration: 33.901132ms)","trace[1030245981] 'applied index is now lower than readState.Index' (duration: 164.530641ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:39.097809Z","caller":"traceutil/trace.go:171","msg":"trace[447943650] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"388.502351ms","start":"2026-10-13T15:49:38.709261Z","end":"2026-10-13T15:49:39.097763Z","steps":["trace[447943650] 'process raft request' (duration: 223.9863ms)","trace[447943650] 'compare' (duration: 163.67343ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:39.09785Z","caller":"traceutil/trace.go:171","msg":"trace[157435520] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"292.382079ms","start":"2026-10-13T15:49:38.805435Z","end":"2026-10-13T15:49:39.097817Z","steps":["trace[157435520] 'process raft request' (duration: 292.175564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:39.097957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:38.709238Z","time spent":"388.641935ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:39.098011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.618427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T15:49:39.098119Z","caller":"traceutil/trace.go:171","msg":"trace[204837072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24695; }","duration":"198.784242ms","start":"2026-10-13T15:49:38.899292Z","end":"2026-10-13T15:49:39.098076Z","steps":["trace[204837072] 'agreement among raft nodes before linearized reading' (duration: 198.565286ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:39.099161Z","caller":"traceutil/trace.go:171","msg":"trace[1124785716] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"109.148407ms","start":"2026-10-13T15:49:38.989994Z","end":"2026-10-13T15:49:39.099143Z","steps":["trace[1124785716] 'process raft request' (duration: 109.040274ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:43.401585Z","caller":"traceutil/trace.go:171","msg":"trace[839481455] linearizableReadLoop","detail":"{readStateIndex:25537; appliedIndex:25536; }","duration":"284.025725ms","start":"2026-10-13T15:49:43.117531Z","end":"2026-10-13T15:49:43.401556Z","steps":["trace[839481455] 'read index received' (duration: 283.83928ms)","trace[839481455] 'applied index is now lower than readState.Index' (duration: 184.765µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:43.401697Z","caller":"traceutil/trace.go:171","msg":"trace[1376129351] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"290.347758ms","start":"2026-10-13T15:49:43.111314Z","end":"2026-10-13T15:49:43.401662Z","steps":["trace[1376129351] 'process raft request' (duration: 290.02131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:43.401829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.270381ms","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-10-13T15:49:43.401887Z","caller":"traceutil/trace.go:171","msg":"trace[668917648] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24731; }","duration":"284.362994ms","start":"2026-10-13T15:49:43.117505Z","end":"2026-10-13T15:49:43.401868Z","steps":["trace[668917648] 'agreement among raft nodes before linearized reading' (duration: 284.165689ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:43.410102Z","caller":"traceutil/trace.go:171","msg":"trace[1419519146] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"292.537174ms","start":"2026-10-13T15:49:43.117538Z","end":"2026-10-13T15:49:43.410075Z","steps":["trace[1419519146] 'process raft request' (duration: 292.018331ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:43.410844Z","caller":"traceutil/trace.go:171","msg":"trace[428142112] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"170.444834ms","start":"2026-10-13T15:49:43.240379Z","end":"2026-10-13T15:49:43.410824Z","steps":["trace[428142112] 'process raft request' (duration: 170.388053ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:43.410987Z","caller":"traceutil/trace.go:171","msg":"trace[1026510778] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"292.438961ms","start":"2026-10-13T15:49:43.118523Z","end":"2026-10-13T15:49:43.410962Z","steps":["trace[1026510778] 'process raft request' (duration: 292.157334ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:43.411005Z","caller":"traceutil/trace.go:171","msg":"trace[2061991682] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"293.366555ms","start":"2026-10-13T15:49:43.117622Z","end":"2026-10-13T15:49:43.410988Z","steps":["trace[2061991682] 'process raft request' (duration: 292.104003ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:43.726134Z","caller":"traceutil/trace.go:171","msg":"trace[1850031512] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"320.438401ms","start":"2026-10-13T15:49:43.405669Z","end":"2026-10-13T15:49:43.726108Z","steps":["trace[1850031512] 'process raft request' (duration: 253.805377ms)","trace[1850031512] 'compare' (duration: 66.461829ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:43.726271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:43.405647Z","time spent":"320.552324ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:49:43.72636Z","caller":"traceutil/trace.go:171","msg":"trace[281212832] linearizableReadLoop","detail":"{readStateIndex:25542; appliedIndex:25541; }","duration":"297.036229ms","start":"2026-10-13T15:49:43.429298Z","end":"2026-10-13T15:49:43.726334Z","steps":["trace[281212832] 'read index received' (duration: 230.18995ms)","trace[281212832] 'applied index is now lower than readState.Index' (duration: 66.845199ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:43.726445Z","caller":"traceutil/trace.go:171","msg":"trace[1824728276] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"140.519044ms","start":"2026-10-13T15:49:43.58591Z","end":"2026-10-13T15:49:43.726429Z","steps":["trace[1824728276] 'process raft request' (duration: 140.167945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:43.726504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.170553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:43.726617Z","caller":"traceutil/trace.go:171","msg":"trace[835353869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24738; }","duration":"297.363448ms","start":"2026-10-13T15:49:43.429241Z","end":"2026-10-13T15:49:43.726605Z","steps":["trace[835353869] 'agreement among raft nodes before linearized reading' (duration: 297.185473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:44.409621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.625449ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-10-13T15:49:44.409692Z","caller":"traceutil/trace.go:171","msg":"trace[1724363621] linearizableReadLoop","detail":"{readStateIndex:25546; appliedIndex:25545; }","duration":"158.429065ms","start":"2026-10-13T15:49:44.251251Z","end":"2026-10-13T15:49:44.40968Z","steps":["trace[1724363621] 'read index received' (duration: 8.666713ms)","trace[1724363621] 'applied index is now lower than readState.Index' (duration: 149.761282ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:44.40975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:44.029088Z","time spent":"380.65958ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-13T15:49:44.409881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.62993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:44.409908Z","caller":"traceutil/trace.go:171","msg":"trace[1489233932] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24739; }","duration":"158.699642ms","start":"2026-10-13T15:49:44.2512Z","end":"2026-10-13T15:49:44.4099Z","steps":["trace[1489233932] 'agreement among raft nodes before linearized reading' (duration: 158.64568ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:45.176389Z","caller":"traceutil/trace.go:171","msg":"trace[1284834987] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"308.382421ms","start":"2026-10-13T15:49:44.86798Z","end":"2026-10-13T15:49:45.176363Z","steps":["trace[1284834987] 'process raft request' (duration: 308.208496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:45.176571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:44.867961Z","time spent":"308.532894ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:49:45.198575Z","caller":"traceutil/trace.go:171","msg":"trace[1095743129] linearizableReadLoop","detail":"{readStateIndex:25551; appliedIndex:25548; }","duration":"328.720054ms","start":"2026-10-13T15:49:44.869831Z","end":"2026-10-13T15:49:45.198551Z","steps":["trace[1095743129] 'read index received' (duration: 306.41351ms)","trace[1095743129] 'applied index is now lower than readState.Index' (duration: 22.305694ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:45.198652Z","caller":"traceutil/trace.go:171","msg":"trace[1711403767] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"329.273577ms","start":"2026-10-13T15:49:44.869336Z","end":"2026-10-13T15:49:45.198609Z","steps":["trace[1711403767] 'process raft request' (duration: 329.028261ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:45.198782Z","caller":"traceutil/trace.go:171","msg":"trace[246370532] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"328.94884ms","start":"2026-10-13T15:49:44.869796Z","end":"2026-10-13T15:49:45.198745Z","steps":["trace[246370532] 'process raft request' (duration: 328.712804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:45.198819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:44.869303Z","time spent":"329.430031ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:45.198824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.96691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-13T15:49:45.198883Z","caller":"traceutil/trace.go:171","msg":"trace[971386706] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24744; }","duration":"329.068553ms","start":"2026-10-13T15:49:44.869798Z","end":"2026-10-13T15:49:45.198866Z","steps":["trace[971386706] 'agreement among raft nodes before linearized reading' (duration: 328.845747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:45.198933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:44.869781Z","time spent":"329.140464ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-13T15:49:45.19898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:44.869782Z","time spent":"329.061202ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:45.480923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.475135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:45.480992Z","caller":"traceutil/trace.go:171","msg":"trace[2026220222] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24744; }","duration":"193.585828ms","start":"2026-10-13T15:49:45.287389Z","end":"2026-10-13T15:49:45.480975Z","steps":["trace[2026220222] 'range keys from in-memory index tree' (duration: 193.329582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:45.481393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.364042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-13T15:49:45.481483Z","caller":"traceutil/trace.go:171","msg":"trace[1598119456] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24744; }","duration":"198.445234ms","start":"2026-10-13T15:49:45.282998Z","end":"2026-10-13T15:49:45.481443Z","steps":["trace[1598119456] 'count revisions from in-memory index tree' (duration: 198.28238ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:45.691277Z","caller":"traceutil/trace.go:171","msg":"trace[1561074860] linearizableReadLoop","detail":"{readStateIndex:25556; appliedIndex:25551; }","duration":"264.263586ms","start":"2026-10-13T15:49:45.426989Z","end":"2026-10-13T15:49:45.691253Z","steps":["trace[1561074860] 'read index received' (duration: 66.004027ms)","trace[1561074860] 'applied index is now lower than readState.Index' (duration: 198.258569ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:45.691681Z","caller":"traceutil/trace.go:171","msg":"trace[1524127264] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"275.154466ms","start":"2026-10-13T15:49:45.41649Z","end":"2026-10-13T15:49:45.691645Z","steps":["trace[1524127264] 'process raft request' (duration: 274.686294ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:45.691968Z","caller":"traceutil/trace.go:171","msg":"trace[848312750] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"276.937892ms","start":"2026-10-13T15:49:45.415018Z","end":"2026-10-13T15:49:45.691956Z","steps":["trace[848312750] 'process raft request' (duration: 236.42263ms)","trace[848312750] 'compare' (duration: 39.487776ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:45.692013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.010715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:45.692103Z","caller":"traceutil/trace.go:171","msg":"trace[958240234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24750; }","duration":"265.078047ms","start":"2026-10-13T15:49:45.426953Z","end":"2026-10-13T15:49:45.692031Z","steps":["trace[958240234] 'agreement among raft nodes before linearized reading' (duration: 265.013455ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:45.692103Z","caller":"traceutil/trace.go:171","msg":"trace[985026683] transaction","detail":"{read_only:false; response_revision:24747; number_of_response:1; }","duration":"276.786687ms","start":"2026-10-13T15:49:45.415308Z","end":"2026-10-13T15:49:45.692095Z","steps":["trace[985026683] 'process raft request' (duration: 275.774012ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:45.692202Z","caller":"traceutil/trace.go:171","msg":"trace[1451949003] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"199.540271ms","start":"2026-10-13T15:49:45.492656Z","end":"2026-10-13T15:49:45.692196Z","steps":["trace[1451949003] 'process raft request' (duration: 198.553026ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:45.692224Z","caller":"traceutil/trace.go:171","msg":"trace[2000566183] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"275.935106ms","start":"2026-10-13T15:49:45.41628Z","end":"2026-10-13T15:49:45.692215Z","steps":["trace[2000566183] 'process raft request' (duration: 274.845169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:45.692579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.229746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:45.692599Z","caller":"traceutil/trace.go:171","msg":"trace[1750841644] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24750; }","duration":"208.279917ms","start":"2026-10-13T15:49:45.484315Z","end":"2026-10-13T15:49:45.692595Z","steps":["trace[1750841644] 'agreement among raft nodes before linearized reading' (duration: 208.198894ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:45.858663Z","caller":"traceutil/trace.go:171","msg":"trace[1238727637] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"126.248077ms","start":"2026-10-13T15:49:45.732392Z","end":"2026-10-13T15:49:45.85864Z","steps":["trace[1238727637] 'process raft request' (duration: 103.261136ms)","trace[1238727637] 'compare' (duration: 22.883769ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:46.051954Z","caller":"traceutil/trace.go:171","msg":"trace[2122395113] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"319.536688ms","start":"2026-10-13T15:49:45.732395Z","end":"2026-10-13T15:49:46.051932Z","steps":["trace[2122395113] 'process raft request' (duration: 250.81111ms)","trace[2122395113] 'compare' (duration: 68.38995ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:46.052084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:45.732382Z","time spent":"319.63601ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:49:46.052062Z","caller":"traceutil/trace.go:171","msg":"trace[219982306] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"317.306861ms","start":"2026-10-13T15:49:45.734726Z","end":"2026-10-13T15:49:46.052033Z","steps":["trace[219982306] 'process raft request' (duration: 316.992333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:46.05221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:45.734677Z","time spent":"317.454964ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:49:46.773648Z","caller":"traceutil/trace.go:171","msg":"trace[1750333559] linearizableReadLoop","detail":"{readStateIndex:25562; appliedIndex:25561; }","duration":"160.794705ms","start":"2026-10-13T15:49:46.612834Z","end":"2026-10-13T15:49:46.773629Z","steps":["trace[1750333559] 'read index received' (duration: 160.59175ms)","trace[1750333559] 'applied index is now lower than readState.Index' (duration: 199.695µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:46.773907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.038151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:46.773985Z","caller":"traceutil/trace.go:171","msg":"trace[1253346191] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24755; }","duration":"161.158425ms","start":"2026-10-13T15:49:46.612808Z","end":"2026-10-13T15:49:46.773966Z","steps":["trace[1253346191] 'agreement among raft nodes before linearized reading' (duration: 160.979949ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:46.774237Z","caller":"traceutil/trace.go:171","msg":"trace[968898168] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"167.417836ms","start":"2026-10-13T15:49:46.606805Z","end":"2026-10-13T15:49:46.774223Z","steps":["trace[968898168] 'process raft request' (duration: 166.685547ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:47.289159Z","caller":"traceutil/trace.go:171","msg":"trace[751649126] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"102.371052ms","start":"2026-10-13T15:49:47.186763Z","end":"2026-10-13T15:49:47.289134Z","steps":["trace[751649126] 'process raft request' (duration: 102.005263ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:47.448758Z","caller":"traceutil/trace.go:171","msg":"trace[777191213] linearizableReadLoop","detail":"{readStateIndex:25565; appliedIndex:25564; }","duration":"138.543493ms","start":"2026-10-13T15:49:47.310162Z","end":"2026-10-13T15:49:47.448705Z","steps":["trace[777191213] 'read index received' (duration: 136.967183ms)","trace[777191213] 'applied index is now lower than readState.Index' (duration: 1.57479ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:47.449059Z","caller":"traceutil/trace.go:171","msg":"trace[1449525930] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"153.700204ms","start":"2026-10-13T15:49:47.295344Z","end":"2026-10-13T15:49:47.449044Z","steps":["trace[1449525930] 'process raft request' (duration: 151.849616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:47.449366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.18302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T15:49:47.449424Z","caller":"traceutil/trace.go:171","msg":"trace[230373758] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24758; }","duration":"139.307984ms","start":"2026-10-13T15:49:47.3101Z","end":"2026-10-13T15:49:47.449408Z","steps":["trace[230373758] 'agreement among raft nodes before linearized reading' (duration: 139.188011ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:47.760214Z","caller":"traceutil/trace.go:171","msg":"trace[1799095151] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"262.44617ms","start":"2026-10-13T15:49:47.497736Z","end":"2026-10-13T15:49:47.760182Z","steps":["trace[1799095151] 'process raft request' (duration: 262.294746ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:48.142942Z","caller":"traceutil/trace.go:171","msg":"trace[1670259542] linearizableReadLoop","detail":"{readStateIndex:25571; appliedIndex:25570; }","duration":"269.888191ms","start":"2026-10-13T15:49:47.873032Z","end":"2026-10-13T15:49:48.142921Z","steps":["trace[1670259542] 'read index received' (duration: 269.808699ms)","trace[1670259542] 'applied index is now lower than readState.Index' (duration: 78.732µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:48.14306Z","caller":"traceutil/trace.go:171","msg":"trace[26061642] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"277.497236ms","start":"2026-10-13T15:49:47.865532Z","end":"2026-10-13T15:49:48.14303Z","steps":["trace[26061642] 'process raft request' (duration: 277.24409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:48.14337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.323541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:268836"} {"level":"info","ts":"2026-10-13T15:49:48.143411Z","caller":"traceutil/trace.go:171","msg":"trace[1152159380] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:24764; }","duration":"270.409414ms","start":"2026-10-13T15:49:47.872991Z","end":"2026-10-13T15:49:48.1434Z","steps":["trace[1152159380] 'agreement among raft nodes before linearized reading' (duration: 270.033884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:48.430001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.212835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:49:48.430088Z","caller":"traceutil/trace.go:171","msg":"trace[61510357] linearizableReadLoop","detail":"{readStateIndex:25572; appliedIndex:25571; }","duration":"287.068083ms","start":"2026-10-13T15:49:48.143005Z","end":"2026-10-13T15:49:48.430073Z","steps":["trace[61510357] 'read index received' (duration: 106.684673ms)","trace[61510357] 'applied index is now lower than readState.Index' (duration: 180.38243ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:48.430333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.085086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:48.430364Z","caller":"traceutil/trace.go:171","msg":"trace[1939472903] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24765; }","duration":"174.161179ms","start":"2026-10-13T15:49:48.256192Z","end":"2026-10-13T15:49:48.430353Z","steps":["trace[1939472903] 'agreement among raft nodes before linearized reading' (duration: 174.106958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:48.430498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.668479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T15:49:48.430593Z","caller":"traceutil/trace.go:171","msg":"trace[958333500] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24765; }","duration":"406.835983ms","start":"2026-10-13T15:49:48.023735Z","end":"2026-10-13T15:49:48.430571Z","steps":["trace[958333500] 'agreement among raft nodes before linearized reading' (duration: 406.537105ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:48.430647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:48.023713Z","time spent":"406.918795ms","remote":"127.0.0.1:48574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":90,"response size":32,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-10-13T15:49:48.430897Z","caller":"traceutil/trace.go:171","msg":"trace[1643712951] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"365.48786ms","start":"2026-10-13T15:49:48.065387Z","end":"2026-10-13T15:49:48.430875Z","steps":["trace[1643712951] 'process raft request' (duration: 184.32746ms)","trace[1643712951] 'compare' (duration: 179.64667ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:48.431009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:48.06537Z","time spent":"365.564661ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:48.431148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.851524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-13T15:49:48.431167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.915583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-13T15:49:48.43118Z","caller":"traceutil/trace.go:171","msg":"trace[1512231083] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24765; }","duration":"373.912696ms","start":"2026-10-13T15:49:48.057256Z","end":"2026-10-13T15:49:48.431169Z","steps":["trace[1512231083] 'agreement among raft nodes before linearized reading' (duration: 373.787953ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:48.431203Z","caller":"traceutil/trace.go:171","msg":"trace[2550456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24765; }","duration":"348.982335ms","start":"2026-10-13T15:49:48.082211Z","end":"2026-10-13T15:49:48.431193Z","steps":["trace[2550456] 'agreement among raft nodes before linearized reading' (duration: 348.853501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:48.431205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:48.057237Z","time spent":"373.962147ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-13T15:49:48.431233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:48.082198Z","time spent":"349.028035ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-13T15:49:48.89764Z","caller":"traceutil/trace.go:171","msg":"trace[1864986623] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"114.407642ms","start":"2026-10-13T15:49:48.783207Z","end":"2026-10-13T15:49:48.897615Z","steps":["trace[1864986623] 'process raft request' (duration: 114.189537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:49.322048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.884985ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-13T15:49:49.678452Z","caller":"traceutil/trace.go:171","msg":"trace[1499418827] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"351.319794ms","start":"2026-10-13T15:49:49.327107Z","end":"2026-10-13T15:49:49.678426Z","steps":["trace[1499418827] 'process raft request' (duration: 351.16675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:49.678666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:49.327084Z","time spent":"351.458768ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:49.905251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.10958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:49:49.905324Z","caller":"traceutil/trace.go:171","msg":"trace[344729083] linearizableReadLoop","detail":"{readStateIndex:25578; appliedIndex:25576; }","duration":"488.473152ms","start":"2026-10-13T15:49:49.416839Z","end":"2026-10-13T15:49:49.905312Z","steps":["trace[344729083] 'read index received' (duration: 261.442643ms)","trace[344729083] 'applied index is now lower than readState.Index' (duration: 227.029599ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:49.905376Z","caller":"traceutil/trace.go:171","msg":"trace[235270591] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"576.990809ms","start":"2026-10-13T15:49:49.328379Z","end":"2026-10-13T15:49:49.90537Z","steps":["trace[235270591] 'process raft request' (duration: 412.671934ms)","trace[235270591] 'compare' (duration: 163.932615ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:49.905406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:49.328359Z","time spent":"577.02973ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:49.905563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.706918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:49.905656Z","caller":"traceutil/trace.go:171","msg":"trace[1750233013] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24770; }","duration":"488.849082ms","start":"2026-10-13T15:49:49.416784Z","end":"2026-10-13T15:49:49.905633Z","steps":["trace[1750233013] 'agreement among raft nodes before linearized reading' (duration: 488.670567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:49.905713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:49.416763Z","time spent":"488.931683ms","remote":"127.0.0.1:40758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"info","ts":"2026-10-13T15:49:49.918456Z","caller":"traceutil/trace.go:171","msg":"trace[542161678] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"153.572939ms","start":"2026-10-13T15:49:49.764861Z","end":"2026-10-13T15:49:49.918434Z","steps":["trace[542161678] 'process raft request' (duration: 153.421325ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:50.034204Z","caller":"traceutil/trace.go:171","msg":"trace[1410111032] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"238.397781ms","start":"2026-10-13T15:49:49.795777Z","end":"2026-10-13T15:49:50.034175Z","steps":["trace[1410111032] 'process raft request' (duration: 238.305918ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:50.034295Z","caller":"traceutil/trace.go:171","msg":"trace[1347779762] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"239.867438ms","start":"2026-10-13T15:49:49.794395Z","end":"2026-10-13T15:49:50.034262Z","steps":["trace[1347779762] 'process raft request' (duration: 239.481268ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:50.034324Z","caller":"traceutil/trace.go:171","msg":"trace[1484390242] linearizableReadLoop","detail":"{readStateIndex:25583; appliedIndex:25578; }","duration":"128.957307ms","start":"2026-10-13T15:49:49.905344Z","end":"2026-10-13T15:49:50.034302Z","steps":["trace[1484390242] 'read index received' (duration: 12.900582ms)","trace[1484390242] 'applied index is now lower than readState.Index' (duration: 116.055315ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:50.034377Z","caller":"traceutil/trace.go:171","msg":"trace[1464173493] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"238.933454ms","start":"2026-10-13T15:49:49.79542Z","end":"2026-10-13T15:49:50.034353Z","steps":["trace[1464173493] 'process raft request' (duration: 238.622776ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:50.034393Z","caller":"traceutil/trace.go:171","msg":"trace[1782114760] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"231.644756ms","start":"2026-10-13T15:49:49.802714Z","end":"2026-10-13T15:49:50.034359Z","steps":["trace[1782114760] 'process raft request' (duration: 231.4115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:50.034518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.094496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T15:49:50.034532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.530443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:50.034574Z","caller":"traceutil/trace.go:171","msg":"trace[429572337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24775; }","duration":"597.200038ms","start":"2026-10-13T15:49:49.437358Z","end":"2026-10-13T15:49:50.034558Z","steps":["trace[429572337] 'agreement among raft nodes before linearized reading' (duration: 597.047175ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:50.034595Z","caller":"traceutil/trace.go:171","msg":"trace[1577254350] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24775; }","duration":"521.646026ms","start":"2026-10-13T15:49:49.512929Z","end":"2026-10-13T15:49:50.034575Z","steps":["trace[1577254350] 'agreement among raft nodes before linearized reading' (duration: 521.471051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:50.034603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.34603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-13T15:49:50.034625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:49.437338Z","time spent":"597.276041ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T15:49:50.034644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:49.512905Z","time spent":"521.723377ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-10-13T15:49:50.034621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.090199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-13T15:49:50.034674Z","caller":"traceutil/trace.go:171","msg":"trace[175288371] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24775; }","duration":"224.449672ms","start":"2026-10-13T15:49:49.810206Z","end":"2026-10-13T15:49:50.034656Z","steps":["trace[175288371] 'agreement among raft nodes before linearized reading' (duration: 224.326859ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:50.034696Z","caller":"traceutil/trace.go:171","msg":"trace[1859247887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24775; }","duration":"580.248313ms","start":"2026-10-13T15:49:49.454436Z","end":"2026-10-13T15:49:50.034684Z","steps":["trace[1859247887] 'agreement among raft nodes before linearized reading' (duration: 580.082228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:50.034746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:49.454416Z","time spent":"580.303265ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-13T15:49:50.260388Z","caller":"traceutil/trace.go:171","msg":"trace[347012135] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"111.114068ms","start":"2026-10-13T15:49:50.149247Z","end":"2026-10-13T15:49:50.260361Z","steps":["trace[347012135] 'process raft request' (duration: 73.938832ms)","trace[347012135] 'compare' (duration: 37.072283ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:50.358189Z","caller":"traceutil/trace.go:171","msg":"trace[1938749020] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"206.624994ms","start":"2026-10-13T15:49:50.151532Z","end":"2026-10-13T15:49:50.358157Z","steps":["trace[1938749020] 'process raft request' (duration: 206.47104ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:54.531294Z","caller":"traceutil/trace.go:171","msg":"trace[1442388552] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"257.464812ms","start":"2026-10-13T15:49:54.273799Z","end":"2026-10-13T15:49:54.531264Z","steps":["trace[1442388552] 'process raft request' (duration: 254.819054ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:54.531312Z","caller":"traceutil/trace.go:171","msg":"trace[4297291] linearizableReadLoop","detail":"{readStateIndex:25619; appliedIndex:25618; }","duration":"240.021003ms","start":"2026-10-13T15:49:54.291238Z","end":"2026-10-13T15:49:54.531259Z","steps":["trace[4297291] 'read index received' (duration: 237.395915ms)","trace[4297291] 'applied index is now lower than readState.Index' (duration: 2.623698ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:54.531742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.483505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:54.531793Z","caller":"traceutil/trace.go:171","msg":"trace[1077063924] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24810; }","duration":"240.599078ms","start":"2026-10-13T15:49:54.29118Z","end":"2026-10-13T15:49:54.531779Z","steps":["trace[1077063924] 'agreement among raft nodes before linearized reading' (duration: 240.226759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:54.541648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.835469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"warn","ts":"2026-10-13T15:49:54.541697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.13179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-10-13T15:49:54.541729Z","caller":"traceutil/trace.go:171","msg":"trace[551803018] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24811; }","duration":"248.964613ms","start":"2026-10-13T15:49:54.292745Z","end":"2026-10-13T15:49:54.54171Z","steps":["trace[551803018] 'agreement among raft nodes before linearized reading' (duration: 248.744037ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:54.541779Z","caller":"traceutil/trace.go:171","msg":"trace[980339151] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24811; }","duration":"106.257803ms","start":"2026-10-13T15:49:54.435497Z","end":"2026-10-13T15:49:54.541755Z","steps":["trace[980339151] 'agreement among raft nodes before linearized reading' (duration: 106.093499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:54.541945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.58609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:54.542019Z","caller":"traceutil/trace.go:171","msg":"trace[1702554661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24811; }","duration":"113.668283ms","start":"2026-10-13T15:49:54.428334Z","end":"2026-10-13T15:49:54.542002Z","steps":["trace[1702554661] 'agreement among raft nodes before linearized reading' (duration: 113.55008ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:54.846068Z","caller":"traceutil/trace.go:171","msg":"trace[446474195] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"299.421441ms","start":"2026-10-13T15:49:54.546618Z","end":"2026-10-13T15:49:54.846039Z","steps":["trace[446474195] 'process raft request' (duration: 298.814205ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:54.863704Z","caller":"traceutil/trace.go:171","msg":"trace[2027124954] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"316.92636ms","start":"2026-10-13T15:49:54.546753Z","end":"2026-10-13T15:49:54.863679Z","steps":["trace[2027124954] 'process raft request' (duration: 316.755886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:54.863873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:54.54673Z","time spent":"317.057024ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:49:54.863999Z","caller":"traceutil/trace.go:171","msg":"trace[242383601] linearizableReadLoop","detail":"{readStateIndex:25622; appliedIndex:25620; }","duration":"316.176381ms","start":"2026-10-13T15:49:54.5478Z","end":"2026-10-13T15:49:54.863976Z","steps":["trace[242383601] 'read index received' (duration: 297.635295ms)","trace[242383601] 'applied index is now lower than readState.Index' (duration: 18.540046ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:54.864295Z","caller":"traceutil/trace.go:171","msg":"trace[1778670737] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"314.461338ms","start":"2026-10-13T15:49:54.549819Z","end":"2026-10-13T15:49:54.864281Z","steps":["trace[1778670737] 'process raft request' (duration: 313.8088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:54.864372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:54.549797Z","time spent":"314.53865ms","remote":"127.0.0.1:48666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:54.864437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.617693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:54.864417Z","caller":"traceutil/trace.go:171","msg":"trace[664799484] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"314.358035ms","start":"2026-10-13T15:49:54.549991Z","end":"2026-10-13T15:49:54.864349Z","steps":["trace[664799484] 'process raft request' (duration: 313.899703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:54.864438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.585792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-13T15:49:54.864511Z","caller":"traceutil/trace.go:171","msg":"trace[918231916] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24815; }","duration":"316.692915ms","start":"2026-10-13T15:49:54.547803Z","end":"2026-10-13T15:49:54.864496Z","steps":["trace[918231916] 'agreement among raft nodes before linearized reading' (duration: 316.591832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:54.864545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:54.547768Z","time spent":"316.767266ms","remote":"127.0.0.1:48574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-10-13T15:49:54.864541Z","caller":"traceutil/trace.go:171","msg":"trace[1729299796] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24815; }","duration":"316.757416ms","start":"2026-10-13T15:49:54.547769Z","end":"2026-10-13T15:49:54.864526Z","steps":["trace[1729299796] 'agreement among raft nodes before linearized reading' (duration: 316.475608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:54.86461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:54.547759Z","time spent":"316.839109ms","remote":"127.0.0.1:48530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"warn","ts":"2026-10-13T15:49:54.864628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:54.549977Z","time spent":"314.54779ms","remote":"127.0.0.1:48666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:54.865128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.663964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:54.865389Z","caller":"traceutil/trace.go:171","msg":"trace[1970600505] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24815; }","duration":"247.210127ms","start":"2026-10-13T15:49:54.61811Z","end":"2026-10-13T15:49:54.86532Z","steps":["trace[1970600505] 'agreement among raft nodes before linearized reading' (duration: 246.378416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:55.373033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2408758628086088825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-13T15:49:55.463664Z","caller":"traceutil/trace.go:171","msg":"trace[1807679697] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"591.495292ms","start":"2026-10-13T15:49:54.872129Z","end":"2026-10-13T15:49:55.463625Z","steps":["trace[1807679697] 'process raft request' (duration: 568.910081ms)","trace[1807679697] 'compare' (duration: 22.408596ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:49:55.463854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:54.872103Z","time spent":"591.655036ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:49:55.800308Z","caller":"traceutil/trace.go:171","msg":"trace[1842225024] linearizableReadLoop","detail":"{readStateIndex:25626; appliedIndex:25624; }","duration":"927.659968ms","start":"2026-10-13T15:49:54.872622Z","end":"2026-10-13T15:49:55.800281Z","steps":["trace[1842225024] 'read index received' (duration: 568.516891ms)","trace[1842225024] 'applied index is now lower than readState.Index' (duration: 359.142127ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:49:55.800446Z","caller":"traceutil/trace.go:171","msg":"trace[1938295399] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"741.523251ms","start":"2026-10-13T15:49:55.058892Z","end":"2026-10-13T15:49:55.800415Z","steps":["trace[1938295399] 'process raft request' (duration: 735.63745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:55.800563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:55.058862Z","time spent":"741.627984ms","remote":"127.0.0.1:33482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:49:55.800539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.905194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:55.800632Z","caller":"traceutil/trace.go:171","msg":"trace[2040029960] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24817; }","duration":"928.044428ms","start":"2026-10-13T15:49:54.872562Z","end":"2026-10-13T15:49:55.800606Z","steps":["trace[2040029960] 'agreement among raft nodes before linearized reading' (duration: 927.867813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:55.800699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:54.872542Z","time spent":"928.13898ms","remote":"127.0.0.1:48648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-10-13T15:49:55.801704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.448688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T15:49:55.801804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.43965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:49:55.801814Z","caller":"traceutil/trace.go:171","msg":"trace[715929705] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24818; }","duration":"183.602442ms","start":"2026-10-13T15:49:55.618185Z","end":"2026-10-13T15:49:55.801787Z","steps":["trace[715929705] 'agreement among raft nodes before linearized reading' (duration: 183.441727ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:55.801852Z","caller":"traceutil/trace.go:171","msg":"trace[784576406] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24818; }","duration":"290.508752ms","start":"2026-10-13T15:49:55.511327Z","end":"2026-10-13T15:49:55.801836Z","steps":["trace[784576406] 'agreement among raft nodes before linearized reading' (duration: 290.43811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:55.802171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.59639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-13T15:49:55.802167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.867555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-13T15:49:55.802232Z","caller":"traceutil/trace.go:171","msg":"trace[1701803618] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:24818; }","duration":"774.698183ms","start":"2026-10-13T15:49:55.02752Z","end":"2026-10-13T15:49:55.802218Z","steps":["trace[1701803618] 'agreement among raft nodes before linearized reading' (duration: 774.59394ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:55.802249Z","caller":"traceutil/trace.go:171","msg":"trace[585232780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24818; }","duration":"100.998968ms","start":"2026-10-13T15:49:55.701227Z","end":"2026-10-13T15:49:55.802226Z","steps":["trace[585232780] 'agreement among raft nodes before linearized reading' (duration: 100.787582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:55.801732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.165157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-13T15:49:55.802335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.420375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T15:49:55.802277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:55.0275Z","time spent":"774.764065ms","remote":"127.0.0.1:48612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":7,"response size":32,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-10-13T15:49:55.802414Z","caller":"traceutil/trace.go:171","msg":"trace[1145546659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24818; }","duration":"882.832114ms","start":"2026-10-13T15:49:54.919521Z","end":"2026-10-13T15:49:55.802353Z","steps":["trace[1145546659] 'agreement among raft nodes before linearized reading' (duration: 882.055994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:55.802183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.084627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18de2187a22097a9\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-10-13T15:49:55.802572Z","caller":"traceutil/trace.go:171","msg":"trace[36006659] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18de2187a22097a9; range_end:; response_count:1; response_revision:24818; }","duration":"335.514108ms","start":"2026-10-13T15:49:55.467036Z","end":"2026-10-13T15:49:55.80255Z","steps":["trace[36006659] 'agreement among raft nodes before linearized reading' (duration: 335.039406ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:49:55.802552Z","caller":"traceutil/trace.go:171","msg":"trace[1121140152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24818; }","duration":"375.629011ms","start":"2026-10-13T15:49:55.426749Z","end":"2026-10-13T15:49:55.802378Z","steps":["trace[1121140152] 'agreement among raft nodes before linearized reading' (duration: 375.419925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:49:55.80263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:55.467019Z","time spent":"335.59655ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":746,"request content":"key:\"/registry/events/openstack/container-infra-registry.18de2187a22097a9\" "} {"level":"warn","ts":"2026-10-13T15:49:55.802645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:54.919506Z","time spent":"883.014579ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-13T15:49:55.802664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:49:55.426728Z","time spent":"375.919297ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T15:50:03.488862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.254287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a2542662-9ffc-4407-98f0-2263518922e0\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-13T15:50:03.48893Z","caller":"traceutil/trace.go:171","msg":"trace[1256326386] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a2542662-9ffc-4407-98f0-2263518922e0; range_end:; response_count:1; response_revision:24949; }","duration":"383.37206ms","start":"2026-10-13T15:50:03.105543Z","end":"2026-10-13T15:50:03.488915Z","steps":["trace[1256326386] 'range keys from in-memory index tree' (duration: 383.102203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:03.488955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:03.10551Z","time spent":"383.438861ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a2542662-9ffc-4407-98f0-2263518922e0\" "} {"level":"warn","ts":"2026-10-13T15:50:03.489013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.69921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:03.489059Z","caller":"traceutil/trace.go:171","msg":"trace[660793150] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24949; }","duration":"100.793922ms","start":"2026-10-13T15:50:03.388252Z","end":"2026-10-13T15:50:03.489046Z","steps":["trace[660793150] 'count revisions from in-memory index tree' (duration: 100.558336ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:03.817371Z","caller":"traceutil/trace.go:171","msg":"trace[1353986483] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"320.344819ms","start":"2026-10-13T15:50:03.497001Z","end":"2026-10-13T15:50:03.817346Z","steps":["trace[1353986483] 'process raft request' (duration: 320.168214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:03.817622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:03.496978Z","time spent":"320.566655ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:04.432422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.760441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:50:04.432534Z","caller":"traceutil/trace.go:171","msg":"trace[2112647675] linearizableReadLoop","detail":"{readStateIndex:25762; appliedIndex:25761; }","duration":"563.709547ms","start":"2026-10-13T15:50:03.868808Z","end":"2026-10-13T15:50:04.432518Z","steps":["trace[2112647675] 'read index received' (duration: 287.790472ms)","trace[2112647675] 'applied index is now lower than readState.Index' (duration: 275.917625ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:04.432587Z","caller":"traceutil/trace.go:171","msg":"trace[598669539] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"574.867154ms","start":"2026-10-13T15:50:03.857681Z","end":"2026-10-13T15:50:04.432548Z","steps":["trace[598669539] 'process raft request' (duration: 298.896527ms)","trace[598669539] 'compare' (duration: 275.424093ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:50:04.432746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:03.857489Z","time spent":"575.174622ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:04.43289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.083636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-10-13T15:50:04.432924Z","caller":"traceutil/trace.go:171","msg":"trace[2111203641] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:24952; }","duration":"564.117677ms","start":"2026-10-13T15:50:03.868794Z","end":"2026-10-13T15:50:04.432912Z","steps":["trace[2111203641] 'agreement among raft nodes before linearized reading' (duration: 563.795999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.432956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:03.868733Z","time spent":"564.21544ms","remote":"127.0.0.1:48590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-10-13T15:50:04.529351Z","caller":"traceutil/trace.go:171","msg":"trace[1538720210] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"556.065851ms","start":"2026-10-13T15:50:03.973256Z","end":"2026-10-13T15:50:04.529322Z","steps":["trace[1538720210] 'process raft request' (duration: 555.834125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.529553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:03.97322Z","time spent":"556.211615ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:04.529703Z","caller":"traceutil/trace.go:171","msg":"trace[980179040] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"432.094854ms","start":"2026-10-13T15:50:04.097571Z","end":"2026-10-13T15:50:04.529666Z","steps":["trace[980179040] 'process raft request' (duration: 431.689303ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:04.529734Z","caller":"traceutil/trace.go:171","msg":"trace[1071510107] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"421.946692ms","start":"2026-10-13T15:50:04.107759Z","end":"2026-10-13T15:50:04.529706Z","steps":["trace[1071510107] 'process raft request' (duration: 421.784598ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:04.529792Z","caller":"traceutil/trace.go:171","msg":"trace[374666658] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"414.817768ms","start":"2026-10-13T15:50:04.114954Z","end":"2026-10-13T15:50:04.529772Z","steps":["trace[374666658] 'process raft request' (duration: 414.726856ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:04.529802Z","caller":"traceutil/trace.go:171","msg":"trace[883781252] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"416.82471ms","start":"2026-10-13T15:50:04.112933Z","end":"2026-10-13T15:50:04.529758Z","steps":["trace[883781252] 'process raft request' (duration: 416.699537ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.529833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.107732Z","time spent":"422.054295ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:04.52989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.114927Z","time spent":"414.91163ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:04.529953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.112918Z","time spent":"416.960713ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:04.530015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.119305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-13T15:50:04.530041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.768961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a2542662-9ffc-4407-98f0-2263518922e0\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-10-13T15:50:04.530081Z","caller":"traceutil/trace.go:171","msg":"trace[1251148156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24957; }","duration":"539.216178ms","start":"2026-10-13T15:50:03.990845Z","end":"2026-10-13T15:50:04.530061Z","steps":["trace[1251148156] 'agreement among raft nodes before linearized reading' (duration: 539.020303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.530106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.369109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:04.530116Z","caller":"traceutil/trace.go:171","msg":"trace[243815598] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a2542662-9ffc-4407-98f0-2263518922e0; range_end:; response_count:1; response_revision:24957; }","duration":"551.894364ms","start":"2026-10-13T15:50:03.978203Z","end":"2026-10-13T15:50:04.530097Z","steps":["trace[243815598] 'agreement among raft nodes before linearized reading' (duration: 551.617127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.530121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.004244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:04.530136Z","caller":"traceutil/trace.go:171","msg":"trace[1686688713] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24957; }","duration":"362.451801ms","start":"2026-10-13T15:50:04.167674Z","end":"2026-10-13T15:50:04.530126Z","steps":["trace[1686688713] 'agreement among raft nodes before linearized reading' (duration: 362.38881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.530128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:03.990829Z","time spent":"539.28655ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-13T15:50:04.530163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.167649Z","time spent":"362.509333ms","remote":"127.0.0.1:40782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"warn","ts":"2026-10-13T15:50:04.530159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:03.978182Z","time spent":"551.968045ms","remote":"127.0.0.1:48558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a2542662-9ffc-4407-98f0-2263518922e0\" "} {"level":"info","ts":"2026-10-13T15:50:04.530166Z","caller":"traceutil/trace.go:171","msg":"trace[348419525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24957; }","duration":"102.074785ms","start":"2026-10-13T15:50:04.428078Z","end":"2026-10-13T15:50:04.530153Z","steps":["trace[348419525] 'agreement among raft nodes before linearized reading' (duration: 102.013624ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.530176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.930112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-13T15:50:04.53005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.966354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.104\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-13T15:50:04.530232Z","caller":"traceutil/trace.go:171","msg":"trace[1811423691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24957; }","duration":"389.041336ms","start":"2026-10-13T15:50:04.141172Z","end":"2026-10-13T15:50:04.530214Z","steps":["trace[1811423691] 'agreement among raft nodes before linearized reading' (duration: 388.915222ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:04.530248Z","caller":"traceutil/trace.go:171","msg":"trace[37663343] range","detail":"{range_begin:/registry/masterleases/162.253.55.104; range_end:; response_count:1; response_revision:24957; }","duration":"501.22697ms","start":"2026-10-13T15:50:04.029006Z","end":"2026-10-13T15:50:04.530233Z","steps":["trace[37663343] 'agreement among raft nodes before linearized reading' (duration: 500.82534ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.530273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.141155Z","time spent":"389.106467ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-13T15:50:04.530293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.028985Z","time spent":"501.295072ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/162.253.55.104\" "} {"level":"warn","ts":"2026-10-13T15:50:04.530065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.457204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:04.530364Z","caller":"traceutil/trace.go:171","msg":"trace[1503499703] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:24957; }","duration":"170.791592ms","start":"2026-10-13T15:50:04.359561Z","end":"2026-10-13T15:50:04.530352Z","steps":["trace[1503499703] 'agreement among raft nodes before linearized reading' (duration: 170.461904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:04.530751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.097546Z","time spent":"432.236827ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:04.99384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.498458ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"warn","ts":"2026-10-13T15:50:04.993924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.532956Z","time spent":"460.964625ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-13T15:50:05.081525Z","caller":"traceutil/trace.go:171","msg":"trace[1753902908] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"545.696304ms","start":"2026-10-13T15:50:04.535804Z","end":"2026-10-13T15:50:05.081501Z","steps":["trace[1753902908] 'process raft request' (duration: 545.460688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:05.081666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.535784Z","time spent":"545.810297ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:05.081842Z","caller":"traceutil/trace.go:171","msg":"trace[669661052] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"544.011131ms","start":"2026-10-13T15:50:04.537801Z","end":"2026-10-13T15:50:05.081812Z","steps":["trace[669661052] 'process raft request' (duration: 543.627841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:05.082007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.53778Z","time spent":"544.138804ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:05.082015Z","caller":"traceutil/trace.go:171","msg":"trace[1369595504] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"490.739781ms","start":"2026-10-13T15:50:04.59126Z","end":"2026-10-13T15:50:05.081999Z","steps":["trace[1369595504] 'process raft request' (duration: 490.444023ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:05.082093Z","caller":"traceutil/trace.go:171","msg":"trace[209377416] linearizableReadLoop","detail":"{readStateIndex:25772; appliedIndex:25767; }","duration":"359.362592ms","start":"2026-10-13T15:50:04.722712Z","end":"2026-10-13T15:50:05.082075Z","steps":["trace[209377416] 'read index received' (duration: 40.700186ms)","trace[209377416] 'applied index is now lower than readState.Index' (duration: 318.661606ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:05.082204Z","caller":"traceutil/trace.go:171","msg":"trace[67806597] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"490.620607ms","start":"2026-10-13T15:50:04.591566Z","end":"2026-10-13T15:50:05.082186Z","steps":["trace[67806597] 'process raft request' (duration: 490.376551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:05.082215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.591235Z","time spent":"490.901365ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:05.082302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.573007ms","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:6"} {"level":"info","ts":"2026-10-13T15:50:05.082359Z","caller":"traceutil/trace.go:171","msg":"trace[655121704] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24961; }","duration":"359.661799ms","start":"2026-10-13T15:50:04.72268Z","end":"2026-10-13T15:50:05.082341Z","steps":["trace[655121704] 'agreement among raft nodes before linearized reading' (duration: 359.485815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:05.082396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.722663Z","time spent":"359.722311ms","remote":"127.0.0.1:33262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-13T15:50:05.082307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.591556Z","time spent":"490.6985ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:05.820585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.254609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:50:05.820807Z","caller":"traceutil/trace.go:171","msg":"trace[667103682] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"451.162303ms","start":"2026-10-13T15:50:05.36962Z","end":"2026-10-13T15:50:05.820783Z","steps":["trace[667103682] 'process raft request' (duration: 451.078421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:05.820907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.369586Z","time spent":"451.274066ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:05.8209Z","caller":"traceutil/trace.go:171","msg":"trace[550070697] linearizableReadLoop","detail":"{readStateIndex:25773; appliedIndex:25772; }","duration":"738.728989ms","start":"2026-10-13T15:50:05.082136Z","end":"2026-10-13T15:50:05.820865Z","steps":["trace[550070697] 'read index received' (duration: 318.210934ms)","trace[550070697] 'applied index is now lower than readState.Index' (duration: 420.513345ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:05.82095Z","caller":"traceutil/trace.go:171","msg":"trace[928153340] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"825.892581ms","start":"2026-10-13T15:50:04.995005Z","end":"2026-10-13T15:50:05.820897Z","steps":["trace[928153340] 'process raft request' (duration: 405.253082ms)","trace[928153340] 'compare' (duration: 420.133115ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:50:05.821098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.591302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-13T15:50:05.821104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.994985Z","time spent":"826.025574ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:05.821125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.7254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:05.821162Z","caller":"traceutil/trace.go:171","msg":"trace[2004694340] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24963; }","duration":"867.692595ms","start":"2026-10-13T15:50:04.953448Z","end":"2026-10-13T15:50:05.821141Z","steps":["trace[2004694340] 'agreement among raft nodes before linearized reading' (duration: 867.476059ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:05.82123Z","caller":"traceutil/trace.go:171","msg":"trace[238419760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24963; }","duration":"392.847143ms","start":"2026-10-13T15:50:05.428334Z","end":"2026-10-13T15:50:05.821182Z","steps":["trace[238419760] 'agreement among raft nodes before linearized reading' (duration: 392.706789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:05.821242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.953433Z","time spent":"867.795748ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-13T15:50:05.821242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.284324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-13T15:50:05.821258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.942488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"warn","ts":"2026-10-13T15:50:05.821258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.238166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a2542662-9ffc-4407-98f0-2263518922e0\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-10-13T15:50:05.821288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.428315Z","time spent":"392.962845ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-13T15:50:05.821308Z","caller":"traceutil/trace.go:171","msg":"trace[1424661453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24963; }","duration":"935.434918ms","start":"2026-10-13T15:50:04.885856Z","end":"2026-10-13T15:50:05.821291Z","steps":["trace[1424661453] 'agreement among raft nodes before linearized reading' (duration: 935.162091ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:05.821315Z","caller":"traceutil/trace.go:171","msg":"trace[756861696] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:24963; }","duration":"285.041851ms","start":"2026-10-13T15:50:05.536258Z","end":"2026-10-13T15:50:05.8213Z","steps":["trace[756861696] 'agreement among raft nodes before linearized reading' (duration: 284.787064ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:05.821333Z","caller":"traceutil/trace.go:171","msg":"trace[444703380] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a2542662-9ffc-4407-98f0-2263518922e0; range_end:; response_count:1; response_revision:24963; }","duration":"285.319808ms","start":"2026-10-13T15:50:05.535993Z","end":"2026-10-13T15:50:05.821312Z","steps":["trace[444703380] 'agreement among raft nodes before linearized reading' (duration: 285.095313ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:05.821352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:04.885839Z","time spent":"935.504979ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-13T15:50:06.283498Z","caller":"traceutil/trace.go:171","msg":"trace[434941160] linearizableReadLoop","detail":"{readStateIndex:25775; appliedIndex:25774; }","duration":"458.611495ms","start":"2026-10-13T15:50:05.824866Z","end":"2026-10-13T15:50:06.283477Z","steps":["trace[434941160] 'read index received' (duration: 458.498642ms)","trace[434941160] 'applied index is now lower than readState.Index' (duration: 112.153µs)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:06.283597Z","caller":"traceutil/trace.go:171","msg":"trace[1490352592] transaction","detail":"{read_only:false; response_revision:24964; number_of_response:1; }","duration":"459.892957ms","start":"2026-10-13T15:50:05.823695Z","end":"2026-10-13T15:50:06.283588Z","steps":["trace[1490352592] 'process raft request' (duration: 459.644921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.283672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.82368Z","time spent":"459.933059ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:06.283808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.942503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c3f1ee49-d20e-4d44-85d8-af773fe65be6\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-10-13T15:50:06.283924Z","caller":"traceutil/trace.go:171","msg":"trace[1341715989] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c3f1ee49-d20e-4d44-85d8-af773fe65be6; range_end:; response_count:1; response_revision:24964; }","duration":"459.090757ms","start":"2026-10-13T15:50:05.824814Z","end":"2026-10-13T15:50:06.283905Z","steps":["trace[1341715989] 'agreement among raft nodes before linearized reading' (duration: 458.866711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.284001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.824798Z","time spent":"459.189559ms","remote":"127.0.0.1:48516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1308,"request content":"key:\"/registry/persistentvolumes/pvc-c3f1ee49-d20e-4d44-85d8-af773fe65be6\" "} {"level":"info","ts":"2026-10-13T15:50:06.303856Z","caller":"traceutil/trace.go:171","msg":"trace[1052864573] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"477.465919ms","start":"2026-10-13T15:50:05.826362Z","end":"2026-10-13T15:50:06.303828Z","steps":["trace[1052864573] 'process raft request' (duration: 477.346696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.303897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.62971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-13T15:50:06.303931Z","caller":"traceutil/trace.go:171","msg":"trace[725326280] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24966; }","duration":"478.686531ms","start":"2026-10-13T15:50:05.825236Z","end":"2026-10-13T15:50:06.303923Z","steps":["trace[725326280] 'agreement among raft nodes before linearized reading' (duration: 478.573298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.303952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.825223Z","time spent":"478.724201ms","remote":"127.0.0.1:48398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-10-13T15:50:06.303993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.826349Z","time spent":"477.568602ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:06.304073Z","caller":"traceutil/trace.go:171","msg":"trace[718788361] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"477.655354ms","start":"2026-10-13T15:50:05.826411Z","end":"2026-10-13T15:50:06.304066Z","steps":["trace[718788361] 'process raft request' (duration: 477.366216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.304124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.826397Z","time spent":"477.695945ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:06.304215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.464274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-13T15:50:06.304237Z","caller":"traceutil/trace.go:171","msg":"trace[2140822709] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24966; }","duration":"107.517725ms","start":"2026-10-13T15:50:06.196712Z","end":"2026-10-13T15:50:06.30423Z","steps":["trace[2140822709] 'agreement among raft nodes before linearized reading' (duration: 107.455883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.304377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.277654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-13T15:50:06.3044Z","caller":"traceutil/trace.go:171","msg":"trace[2005817792] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24966; }","duration":"136.336326ms","start":"2026-10-13T15:50:06.168058Z","end":"2026-10-13T15:50:06.304394Z","steps":["trace[2005817792] 'agreement among raft nodes before linearized reading' (duration: 136.276024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.304546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.124937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:06.304594Z","caller":"traceutil/trace.go:171","msg":"trace[631150785] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24966; }","duration":"466.19575ms","start":"2026-10-13T15:50:05.838385Z","end":"2026-10-13T15:50:06.30458Z","steps":["trace[631150785] 'agreement among raft nodes before linearized reading' (duration: 466.116898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.304625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.838374Z","time spent":"466.24007ms","remote":"127.0.0.1:41076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-10-13T15:50:06.304787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.92995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T15:50:06.304813Z","caller":"traceutil/trace.go:171","msg":"trace[2140683630] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24966; }","duration":"153.98045ms","start":"2026-10-13T15:50:06.150824Z","end":"2026-10-13T15:50:06.304804Z","steps":["trace[2140683630] 'agreement among raft nodes before linearized reading' (duration: 153.933649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.30488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.305264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-13T15:50:06.304902Z","caller":"traceutil/trace.go:171","msg":"trace[250033389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24966; }","duration":"454.350605ms","start":"2026-10-13T15:50:05.850545Z","end":"2026-10-13T15:50:06.304896Z","steps":["trace[250033389] 'agreement among raft nodes before linearized reading' (duration: 454.283733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.304919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.850535Z","time spent":"454.379856ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-13T15:50:06.304922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.616714ms","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:6"} {"level":"info","ts":"2026-10-13T15:50:06.304988Z","caller":"traceutil/trace.go:171","msg":"trace[235315533] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24966; }","duration":"353.743417ms","start":"2026-10-13T15:50:05.951227Z","end":"2026-10-13T15:50:06.30497Z","steps":["trace[235315533] 'agreement among raft nodes before linearized reading' (duration: 353.612184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:06.305034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:05.951206Z","time spent":"353.815979ms","remote":"127.0.0.1:33174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-13T15:50:08.599996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.92231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:50:08.600134Z","caller":"traceutil/trace.go:171","msg":"trace[902127417] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"284.805495ms","start":"2026-10-13T15:50:08.315312Z","end":"2026-10-13T15:50:08.600117Z","steps":["trace[902127417] 'process raft request' (duration: 149.709411ms)","trace[902127417] 'compare' (duration: 134.800337ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:08.600309Z","caller":"traceutil/trace.go:171","msg":"trace[585079645] linearizableReadLoop","detail":"{readStateIndex:25798; appliedIndex:25796; }","duration":"280.725019ms","start":"2026-10-13T15:50:08.319562Z","end":"2026-10-13T15:50:08.600287Z","steps":["trace[585079645] 'read index received' (duration: 145.41894ms)","trace[585079645] 'applied index is now lower than readState.Index' (duration: 135.304719ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:08.600359Z","caller":"traceutil/trace.go:171","msg":"trace[582559791] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"282.435764ms","start":"2026-10-13T15:50:08.317906Z","end":"2026-10-13T15:50:08.600342Z","steps":["trace[582559791] 'process raft request' (duration: 282.172837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:08.600473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.896473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-13T15:50:08.600512Z","caller":"traceutil/trace.go:171","msg":"trace[2134196162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24986; }","duration":"280.987756ms","start":"2026-10-13T15:50:08.319513Z","end":"2026-10-13T15:50:08.600501Z","steps":["trace[2134196162] 'agreement among raft nodes before linearized reading' (duration: 280.861333ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:08.672508Z","caller":"traceutil/trace.go:171","msg":"trace[1662043160] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"102.841435ms","start":"2026-10-13T15:50:08.569609Z","end":"2026-10-13T15:50:08.67245Z","steps":["trace[1662043160] 'process raft request' (duration: 102.346921ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:08.672546Z","caller":"traceutil/trace.go:171","msg":"trace[1780671226] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"102.843564ms","start":"2026-10-13T15:50:08.569668Z","end":"2026-10-13T15:50:08.672512Z","steps":["trace[1780671226] 'process raft request' (duration: 102.529295ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:08.672585Z","caller":"traceutil/trace.go:171","msg":"trace[601482158] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"101.973292ms","start":"2026-10-13T15:50:08.570568Z","end":"2026-10-13T15:50:08.672541Z","steps":["trace[601482158] 'process raft request' (duration: 101.695704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:08.672933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.591025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-13T15:50:08.672956Z","caller":"traceutil/trace.go:171","msg":"trace[64612807] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"101.959582ms","start":"2026-10-13T15:50:08.570984Z","end":"2026-10-13T15:50:08.672944Z","steps":["trace[64612807] 'process raft request' (duration: 101.326046ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:08.673007Z","caller":"traceutil/trace.go:171","msg":"trace[1214159303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24992; }","duration":"217.708298ms","start":"2026-10-13T15:50:08.455281Z","end":"2026-10-13T15:50:08.672989Z","steps":["trace[1214159303] 'agreement among raft nodes before linearized reading' (duration: 217.492943ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:11.996092Z","caller":"traceutil/trace.go:171","msg":"trace[666194753] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"510.53847ms","start":"2026-10-13T15:50:11.485528Z","end":"2026-10-13T15:50:11.996067Z","steps":["trace[666194753] 'process raft request' (duration: 510.314194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:11.996231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:11.485514Z","time spent":"510.656333ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2119,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-13T15:50:11.99628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.960693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:11.99636Z","caller":"traceutil/trace.go:171","msg":"trace[1613096485] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25030; }","duration":"471.076665ms","start":"2026-10-13T15:50:11.525264Z","end":"2026-10-13T15:50:11.99634Z","steps":["trace[1613096485] 'agreement among raft nodes before linearized reading' (duration: 470.907241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:11.9964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:11.525171Z","time spent":"471.220039ms","remote":"127.0.0.1:48818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-13T15:50:11.998283Z","caller":"traceutil/trace.go:171","msg":"trace[285161530] linearizableReadLoop","detail":"{readStateIndex:25844; appliedIndex:25843; }","duration":"470.713766ms","start":"2026-10-13T15:50:11.525304Z","end":"2026-10-13T15:50:11.996017Z","steps":["trace[285161530] 'read index received' (duration: 470.419468ms)","trace[285161530] 'applied index is now lower than readState.Index' (duration: 293.178µs)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:50:11.998448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.484516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T15:50:11.998534Z","caller":"traceutil/trace.go:171","msg":"trace[1214636480] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25030; }","duration":"179.613769ms","start":"2026-10-13T15:50:11.818908Z","end":"2026-10-13T15:50:11.998521Z","steps":["trace[1214636480] 'agreement among raft nodes before linearized reading' (duration: 179.489896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:11.999011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.346117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-10-13T15:50:11.999064Z","caller":"traceutil/trace.go:171","msg":"trace[1933830402] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25030; }","duration":"255.43803ms","start":"2026-10-13T15:50:11.74361Z","end":"2026-10-13T15:50:11.999048Z","steps":["trace[1933830402] 'agreement among raft nodes before linearized reading' (duration: 254.757572ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:13.382901Z","caller":"traceutil/trace.go:171","msg":"trace[1118533408] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"253.948791ms","start":"2026-10-13T15:50:13.128928Z","end":"2026-10-13T15:50:13.382877Z","steps":["trace[1118533408] 'process raft request' (duration: 253.791917ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:13.528057Z","caller":"traceutil/trace.go:171","msg":"trace[1004987856] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"200.192209ms","start":"2026-10-13T15:50:13.327846Z","end":"2026-10-13T15:50:13.528038Z","steps":["trace[1004987856] 'process raft request' (duration: 199.905781ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:13.528211Z","caller":"traceutil/trace.go:171","msg":"trace[728307398] linearizableReadLoop","detail":"{readStateIndex:25861; appliedIndex:25859; }","duration":"378.952415ms","start":"2026-10-13T15:50:13.149035Z","end":"2026-10-13T15:50:13.527987Z","steps":["trace[728307398] 'read index received' (duration: 233.73308ms)","trace[728307398] 'applied index is now lower than readState.Index' (duration: 145.218475ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:50:13.528232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.190232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-13T15:50:13.528289Z","caller":"traceutil/trace.go:171","msg":"trace[920340427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25048; }","duration":"379.257194ms","start":"2026-10-13T15:50:13.149021Z","end":"2026-10-13T15:50:13.528278Z","steps":["trace[920340427] 'agreement among raft nodes before linearized reading' (duration: 379.09401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:13.528314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:13.149008Z","time spent":"379.300714ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-13T15:50:13.52842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.004232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:13.528091Z","caller":"traceutil/trace.go:171","msg":"trace[1754249700] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"388.613384ms","start":"2026-10-13T15:50:13.139453Z","end":"2026-10-13T15:50:13.528066Z","steps":["trace[1754249700] 'process raft request' (duration: 388.120982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:13.528476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.5859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-10-13T15:50:13.528495Z","caller":"traceutil/trace.go:171","msg":"trace[1280912708] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25048; }","duration":"247.104765ms","start":"2026-10-13T15:50:13.281348Z","end":"2026-10-13T15:50:13.528452Z","steps":["trace[1280912708] 'agreement among raft nodes before linearized reading' (duration: 247.024073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:13.528495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.621293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T15:50:13.528544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:13.139432Z","time spent":"389.064706ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:13.528542Z","caller":"traceutil/trace.go:171","msg":"trace[599310926] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25048; }","duration":"153.718693ms","start":"2026-10-13T15:50:13.374807Z","end":"2026-10-13T15:50:13.528526Z","steps":["trace[599310926] 'agreement among raft nodes before linearized reading' (duration: 153.539568ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:13.528571Z","caller":"traceutil/trace.go:171","msg":"trace[640207868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25048; }","duration":"101.766007ms","start":"2026-10-13T15:50:13.426786Z","end":"2026-10-13T15:50:13.528552Z","steps":["trace[640207868] 'agreement among raft nodes before linearized reading' (duration: 101.619513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:13.528491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.20277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-13T15:50:13.528658Z","caller":"traceutil/trace.go:171","msg":"trace[680452155] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25048; }","duration":"146.435866ms","start":"2026-10-13T15:50:13.382201Z","end":"2026-10-13T15:50:13.528637Z","steps":["trace[680452155] 'agreement among raft nodes before linearized reading' (duration: 146.118308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:13.528926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.111769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-13T15:50:13.52897Z","caller":"traceutil/trace.go:171","msg":"trace[1304437598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25048; }","duration":"351.186231ms","start":"2026-10-13T15:50:13.177774Z","end":"2026-10-13T15:50:13.52896Z","steps":["trace[1304437598] 'agreement among raft nodes before linearized reading' (duration: 350.524204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:13.528997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:13.177761Z","time spent":"351.228203ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-13T15:50:33.051346Z","caller":"traceutil/trace.go:171","msg":"trace[1749597239] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"117.549253ms","start":"2026-10-13T15:50:32.933771Z","end":"2026-10-13T15:50:33.05132Z","steps":["trace[1749597239] 'process raft request' (duration: 117.41156ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:39.606424Z","caller":"traceutil/trace.go:171","msg":"trace[985413912] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"528.058071ms","start":"2026-10-13T15:50:39.078345Z","end":"2026-10-13T15:50:39.606403Z","steps":["trace[985413912] 'process raft request' (duration: 527.905667ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:39.60658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:39.078326Z","time spent":"528.197664ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:39.906438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.268964ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-13T15:50:39.907172Z","caller":"traceutil/trace.go:171","msg":"trace[605663299] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"271.007789ms","start":"2026-10-13T15:50:39.636143Z","end":"2026-10-13T15:50:39.90715Z","steps":["trace[605663299] 'process raft request' (duration: 270.940487ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:39.907633Z","caller":"traceutil/trace.go:171","msg":"trace[1157433838] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"421.747255ms","start":"2026-10-13T15:50:39.485872Z","end":"2026-10-13T15:50:39.907619Z","steps":["trace[1157433838] 'process raft request' (duration: 421.052588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:39.907739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:39.485854Z","time spent":"421.811808ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:39.907912Z","caller":"traceutil/trace.go:171","msg":"trace[1370707675] linearizableReadLoop","detail":"{readStateIndex:26088; appliedIndex:26086; }","duration":"703.724648ms","start":"2026-10-13T15:50:39.204173Z","end":"2026-10-13T15:50:39.907898Z","steps":["trace[1370707675] 'read index received' (duration: 402.135162ms)","trace[1370707675] 'applied index is now lower than readState.Index' (duration: 301.588186ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:50:39.908281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.098218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-13T15:50:39.908323Z","caller":"traceutil/trace.go:171","msg":"trace[1953975536] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25270; }","duration":"704.142859ms","start":"2026-10-13T15:50:39.204168Z","end":"2026-10-13T15:50:39.908311Z","steps":["trace[1953975536] 'agreement among raft nodes before linearized reading' (duration: 703.994135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:39.908357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:39.204129Z","time spent":"704.218631ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-13T15:50:39.90861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.488367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:39.908648Z","caller":"traceutil/trace.go:171","msg":"trace[1514986184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25270; }","duration":"480.566919ms","start":"2026-10-13T15:50:39.42807Z","end":"2026-10-13T15:50:39.908637Z","steps":["trace[1514986184] 'agreement among raft nodes before linearized reading' (duration: 480.505618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:39.908684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:39.428031Z","time spent":"480.645031ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T15:50:39.910279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.250288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"warn","ts":"2026-10-13T15:50:39.910309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.268614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-13T15:50:39.910361Z","caller":"traceutil/trace.go:171","msg":"trace[1483570315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25270; }","duration":"206.347746ms","start":"2026-10-13T15:50:39.703999Z","end":"2026-10-13T15:50:39.910347Z","steps":["trace[1483570315] 'agreement among raft nodes before linearized reading' (duration: 206.203452ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:39.910357Z","caller":"traceutil/trace.go:171","msg":"trace[1395276710] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:25270; }","duration":"345.380463ms","start":"2026-10-13T15:50:39.56496Z","end":"2026-10-13T15:50:39.91034Z","steps":["trace[1395276710] 'agreement among raft nodes before linearized reading' (duration: 345.171847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:39.910444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:39.564931Z","time spent":"345.502905ms","remote":"127.0.0.1:48530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1590,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" "} {"level":"warn","ts":"2026-10-13T15:50:39.909168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.725133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-13T15:50:39.915497Z","caller":"traceutil/trace.go:171","msg":"trace[927844413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25270; }","duration":"209.042456ms","start":"2026-10-13T15:50:39.70643Z","end":"2026-10-13T15:50:39.915473Z","steps":["trace[927844413] 'agreement among raft nodes before linearized reading' (duration: 202.655172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:40.162608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.702598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:50:40.162753Z","caller":"traceutil/trace.go:171","msg":"trace[953706741] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"247.845684ms","start":"2026-10-13T15:50:39.914883Z","end":"2026-10-13T15:50:40.162729Z","steps":["trace[953706741] 'process raft request' (duration: 92.867298ms)","trace[953706741] 'compare' (duration: 154.434441ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:40.19944Z","caller":"traceutil/trace.go:171","msg":"trace[113043053] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"283.993495ms","start":"2026-10-13T15:50:39.915412Z","end":"2026-10-13T15:50:40.199406Z","steps":["trace[113043053] 'process raft request' (duration: 283.749248ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:40.199638Z","caller":"traceutil/trace.go:171","msg":"trace[589748253] linearizableReadLoop","detail":"{readStateIndex:26092; appliedIndex:26090; }","duration":"282.29179ms","start":"2026-10-13T15:50:39.917321Z","end":"2026-10-13T15:50:40.199613Z","steps":["trace[589748253] 'read index received' (duration: 90.439475ms)","trace[589748253] 'applied index is now lower than readState.Index' (duration: 191.850075ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:40.199682Z","caller":"traceutil/trace.go:171","msg":"trace[548189705] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"279.985591ms","start":"2026-10-13T15:50:39.919671Z","end":"2026-10-13T15:50:40.199656Z","steps":["trace[548189705] 'process raft request' (duration: 279.670443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:40.199996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.65339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-10-13T15:50:40.200076Z","caller":"traceutil/trace.go:171","msg":"trace[147507740] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:25273; }","duration":"282.785703ms","start":"2026-10-13T15:50:39.917267Z","end":"2026-10-13T15:50:40.200053Z","steps":["trace[147507740] 'agreement among raft nodes before linearized reading' (duration: 282.489275ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:41.098703Z","caller":"traceutil/trace.go:171","msg":"trace[1185874075] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"264.460822ms","start":"2026-10-13T15:50:40.834203Z","end":"2026-10-13T15:50:41.098664Z","steps":["trace[1185874075] 'process raft request' (duration: 173.476961ms)","trace[1185874075] 'compare' (duration: 90.852927ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:41.402915Z","caller":"traceutil/trace.go:171","msg":"trace[1364128140] linearizableReadLoop","detail":"{readStateIndex:26096; appliedIndex:26094; }","duration":"528.982434ms","start":"2026-10-13T15:50:40.873904Z","end":"2026-10-13T15:50:41.402886Z","steps":["trace[1364128140] 'read index received' (duration: 133.75593ms)","trace[1364128140] 'applied index is now lower than readState.Index' (duration: 395.224584ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:41.402952Z","caller":"traceutil/trace.go:171","msg":"trace[369199751] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"517.15427ms","start":"2026-10-13T15:50:40.885768Z","end":"2026-10-13T15:50:41.402922Z","steps":["trace[369199751] 'process raft request' (duration: 517.016376ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:41.403049Z","caller":"traceutil/trace.go:171","msg":"trace[660983850] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"449.848849ms","start":"2026-10-13T15:50:40.953185Z","end":"2026-10-13T15:50:41.403034Z","steps":["trace[660983850] 'process raft request' (duration: 449.649683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:41.403139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:40.885759Z","time spent":"517.269722ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:41.403144Z","caller":"traceutil/trace.go:171","msg":"trace[505462468] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"558.602345ms","start":"2026-10-13T15:50:40.844505Z","end":"2026-10-13T15:50:41.403108Z","steps":["trace[505462468] 'process raft request' (duration: 473.760774ms)","trace[505462468] 'compare' (duration: 84.039031ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:50:41.403178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:40.953159Z","time spent":"449.92389ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-13T15:50:41.403188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.265082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-13T15:50:41.403247Z","caller":"traceutil/trace.go:171","msg":"trace[148465275] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25279; }","duration":"529.370645ms","start":"2026-10-13T15:50:40.873861Z","end":"2026-10-13T15:50:41.403231Z","steps":["trace[148465275] 'agreement among raft nodes before linearized reading' (duration: 529.19496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:41.403256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:40.84448Z","time spent":"558.715609ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:41.402929Z","caller":"traceutil/trace.go:171","msg":"trace[104403446] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"526.178452ms","start":"2026-10-13T15:50:40.876723Z","end":"2026-10-13T15:50:41.402901Z","steps":["trace[104403446] 'process raft request' (duration: 525.944186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:41.403282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:40.873845Z","time spent":"529.427016ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-13T15:50:41.403357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:40.876707Z","time spent":"526.593323ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-13T15:50:41.437987Z","caller":"traceutil/trace.go:171","msg":"trace[152073542] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"105.659477ms","start":"2026-10-13T15:50:41.332304Z","end":"2026-10-13T15:50:41.437964Z","steps":["trace[152073542] 'process raft request' (duration: 105.128634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:41.444814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.829933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:41.444884Z","caller":"traceutil/trace.go:171","msg":"trace[1019653043] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25282; }","duration":"301.910685ms","start":"2026-10-13T15:50:41.142959Z","end":"2026-10-13T15:50:41.444869Z","steps":["trace[1019653043] 'agreement among raft nodes before linearized reading' (duration: 301.803302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:41.444934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:41.142897Z","time spent":"302.009888ms","remote":"127.0.0.1:33222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-13T15:50:41.444921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.51322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-13T15:50:41.444976Z","caller":"traceutil/trace.go:171","msg":"trace[388578909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25282; }","duration":"470.582593ms","start":"2026-10-13T15:50:40.97438Z","end":"2026-10-13T15:50:41.444963Z","steps":["trace[388578909] 'agreement among raft nodes before linearized reading' (duration: 470.406489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:41.44497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.089644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6ff8566cbf-vcndl.18de1fc4d21091bd\" ","response":"range_response_count:1 size:1138"} {"level":"warn","ts":"2026-10-13T15:50:41.445004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:40.974321Z","time spent":"470.676515ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-13T15:50:41.445047Z","caller":"traceutil/trace.go:171","msg":"trace[1156331761] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6ff8566cbf-vcndl.18de1fc4d21091bd; range_end:; response_count:1; response_revision:25282; }","duration":"336.202147ms","start":"2026-10-13T15:50:41.10882Z","end":"2026-10-13T15:50:41.445023Z","steps":["trace[1156331761] 'agreement among raft nodes before linearized reading' (duration: 335.95674ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:41.445081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:41.108806Z","time spent":"336.265348ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1162,"request content":"key:\"/registry/events/openstack/barbican-api-6ff8566cbf-vcndl.18de1fc4d21091bd\" "} {"level":"info","ts":"2026-10-13T15:50:42.020761Z","caller":"traceutil/trace.go:171","msg":"trace[2111991] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"105.486083ms","start":"2026-10-13T15:50:41.915251Z","end":"2026-10-13T15:50:42.020737Z","steps":["trace[2111991] 'process raft request' (duration: 105.285728ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:42.194355Z","caller":"traceutil/trace.go:171","msg":"trace[432996162] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"272.812997ms","start":"2026-10-13T15:50:41.921519Z","end":"2026-10-13T15:50:42.194332Z","steps":["trace[432996162] 'process raft request' (duration: 223.991931ms)","trace[432996162] 'compare' (duration: 48.516108ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:42.194955Z","caller":"traceutil/trace.go:171","msg":"trace[1998783619] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"236.476042ms","start":"2026-10-13T15:50:41.958444Z","end":"2026-10-13T15:50:42.19492Z","steps":["trace[1998783619] 'process raft request' (duration: 235.851986ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:42.409165Z","caller":"traceutil/trace.go:171","msg":"trace[564206114] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"201.516511ms","start":"2026-10-13T15:50:42.207616Z","end":"2026-10-13T15:50:42.409133Z","steps":["trace[564206114] 'process raft request' (duration: 140.647837ms)","trace[564206114] 'compare' (duration: 60.624088ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:50:43.012021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.590204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:43.012123Z","caller":"traceutil/trace.go:171","msg":"trace[804327098] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25291; }","duration":"117.746228ms","start":"2026-10-13T15:50:42.894352Z","end":"2026-10-13T15:50:43.012098Z","steps":["trace[804327098] 'count revisions from in-memory index tree' (duration: 117.469081ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:43.229431Z","caller":"traceutil/trace.go:171","msg":"trace[1707652020] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"125.631951ms","start":"2026-10-13T15:50:43.103769Z","end":"2026-10-13T15:50:43.229401Z","steps":["trace[1707652020] 'process raft request' (duration: 125.286112ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:43.558303Z","caller":"traceutil/trace.go:171","msg":"trace[514235282] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"149.188438ms","start":"2026-10-13T15:50:43.409089Z","end":"2026-10-13T15:50:43.558278Z","steps":["trace[514235282] 'process raft request' (duration: 74.768413ms)","trace[514235282] 'compare' (duration: 74.312452ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:43.602366Z","caller":"traceutil/trace.go:171","msg":"trace[68216354] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"193.181278ms","start":"2026-10-13T15:50:43.409159Z","end":"2026-10-13T15:50:43.60234Z","steps":["trace[68216354] 'process raft request' (duration: 193.012744ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:43.602397Z","caller":"traceutil/trace.go:171","msg":"trace[720010600] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"193.105216ms","start":"2026-10-13T15:50:43.409279Z","end":"2026-10-13T15:50:43.602384Z","steps":["trace[720010600] 'process raft request' (duration: 192.993873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:43.602474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.869433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:43.602363Z","caller":"traceutil/trace.go:171","msg":"trace[173674219] linearizableReadLoop","detail":"{readStateIndex:26116; appliedIndex:26112; }","duration":"175.767229ms","start":"2026-10-13T15:50:43.426579Z","end":"2026-10-13T15:50:43.602346Z","steps":["trace[173674219] 'read index received' (duration: 57.271692ms)","trace[173674219] 'applied index is now lower than readState.Index' (duration: 118.494687ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:43.602509Z","caller":"traceutil/trace.go:171","msg":"trace[1932443447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25296; }","duration":"175.966714ms","start":"2026-10-13T15:50:43.426533Z","end":"2026-10-13T15:50:43.602499Z","steps":["trace[1932443447] 'agreement among raft nodes before linearized reading' (duration: 175.869212ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:43.602544Z","caller":"traceutil/trace.go:171","msg":"trace[278395794] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"192.825548ms","start":"2026-10-13T15:50:43.409706Z","end":"2026-10-13T15:50:43.602531Z","steps":["trace[278395794] 'process raft request' (duration: 192.604942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:43.834724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.801047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-13T15:50:43.834811Z","caller":"traceutil/trace.go:171","msg":"trace[633663413] linearizableReadLoop","detail":"{readStateIndex:26117; appliedIndex:26116; }","duration":"232.291984ms","start":"2026-10-13T15:50:43.602504Z","end":"2026-10-13T15:50:43.834796Z","steps":["trace[633663413] 'read index received' (duration: 89.727088ms)","trace[633663413] 'applied index is now lower than readState.Index' (duration: 142.563506ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:43.8349Z","caller":"traceutil/trace.go:171","msg":"trace[1429707498] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"282.090004ms","start":"2026-10-13T15:50:43.552799Z","end":"2026-10-13T15:50:43.834889Z","steps":["trace[1429707498] 'process raft request' (duration: 140.058752ms)","trace[1429707498] 'compare' (duration: 141.705653ms)"],"step_count":2} {"level":"warn","ts":"2026-10-13T15:50:43.835103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.073995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-13T15:50:43.835135Z","caller":"traceutil/trace.go:171","msg":"trace[1210212683] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25297; }","duration":"235.148447ms","start":"2026-10-13T15:50:43.599978Z","end":"2026-10-13T15:50:43.835126Z","steps":["trace[1210212683] 'agreement among raft nodes before linearized reading' (duration: 235.080555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:43.835284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.326925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-13T15:50:43.835349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.086045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-13T15:50:43.835379Z","caller":"traceutil/trace.go:171","msg":"trace[1614385867] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25297; }","duration":"137.144607ms","start":"2026-10-13T15:50:43.698225Z","end":"2026-10-13T15:50:43.83537Z","steps":["trace[1614385867] 'agreement among raft nodes before linearized reading' (duration: 137.038594ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:43.835371Z","caller":"traceutil/trace.go:171","msg":"trace[1133852086] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25297; }","duration":"386.461078ms","start":"2026-10-13T15:50:43.448887Z","end":"2026-10-13T15:50:43.835349Z","steps":["trace[1133852086] 'agreement among raft nodes before linearized reading' (duration: 386.216301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:43.835423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:43.448859Z","time spent":"386.550631ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-13T15:50:43.835492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.441028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-13T15:50:43.835524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.499822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-13T15:50:43.83559Z","caller":"traceutil/trace.go:171","msg":"trace[1467743134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25297; }","duration":"372.600832ms","start":"2026-10-13T15:50:43.462965Z","end":"2026-10-13T15:50:43.835566Z","steps":["trace[1467743134] 'agreement among raft nodes before linearized reading' (duration: 372.406157ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:43.835623Z","caller":"traceutil/trace.go:171","msg":"trace[1518946493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25297; }","duration":"311.652616ms","start":"2026-10-13T15:50:43.523953Z","end":"2026-10-13T15:50:43.835605Z","steps":["trace[1518946493] 'agreement among raft nodes before linearized reading' (duration: 311.366239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:43.835648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:43.462955Z","time spent":"372.676035ms","remote":"127.0.0.1:48352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-13T15:50:43.83568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-13T15:50:43.523933Z","time spent":"311.728967ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-13T15:50:44.078895Z","caller":"traceutil/trace.go:171","msg":"trace[702014856] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"238.218246ms","start":"2026-10-13T15:50:43.840653Z","end":"2026-10-13T15:50:44.078871Z","steps":["trace[702014856] 'process raft request' (duration: 199.536412ms)","trace[702014856] 'compare' (duration: 38.426908ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:44.079111Z","caller":"traceutil/trace.go:171","msg":"trace[1366038189] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26118; }","duration":"151.717143ms","start":"2026-10-13T15:50:43.927363Z","end":"2026-10-13T15:50:44.07908Z","steps":["trace[1366038189] 'read index received' (duration: 112.838503ms)","trace[1366038189] 'applied index is now lower than readState.Index' (duration: 38.87721ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:44.079377Z","caller":"traceutil/trace.go:171","msg":"trace[1195141646] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"238.666979ms","start":"2026-10-13T15:50:43.840692Z","end":"2026-10-13T15:50:44.079359Z","steps":["trace[1195141646] 'process raft request' (duration: 238.140954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:44.079479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.080092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:44.07954Z","caller":"traceutil/trace.go:171","msg":"trace[2076335476] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25300; }","duration":"152.216635ms","start":"2026-10-13T15:50:43.927307Z","end":"2026-10-13T15:50:44.079524Z","steps":["trace[2076335476] 'agreement among raft nodes before linearized reading' (duration: 152.085042ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:44.301729Z","caller":"traceutil/trace.go:171","msg":"trace[13208143] linearizableReadLoop","detail":"{readStateIndex:26122; appliedIndex:26121; }","duration":"159.785ms","start":"2026-10-13T15:50:44.141915Z","end":"2026-10-13T15:50:44.3017Z","steps":["trace[13208143] 'read index received' (duration: 67.159208ms)","trace[13208143] 'applied index is now lower than readState.Index' (duration: 92.622802ms)"],"step_count":2} {"level":"info","ts":"2026-10-13T15:50:44.301837Z","caller":"traceutil/trace.go:171","msg":"trace[1260148900] transaction","detail":"{read_only:false; response_revision:25302; number_of_response:1; }","duration":"102.053315ms","start":"2026-10-13T15:50:44.199647Z","end":"2026-10-13T15:50:44.301701Z","steps":["trace[1260148900] 'process raft request' (duration: 101.936662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:44.301823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.877232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:44.302002Z","caller":"traceutil/trace.go:171","msg":"trace[702745621] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25302; }","duration":"160.096807ms","start":"2026-10-13T15:50:44.141871Z","end":"2026-10-13T15:50:44.301968Z","steps":["trace[702745621] 'agreement among raft nodes before linearized reading' (duration: 159.863311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:44.532139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.587554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-13T15:50:44.53222Z","caller":"traceutil/trace.go:171","msg":"trace[1617801662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25304; }","duration":"103.722057ms","start":"2026-10-13T15:50:44.428479Z","end":"2026-10-13T15:50:44.532201Z","steps":["trace[1617801662] 'range keys from in-memory index tree' (duration: 103.483081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:44.532341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.476647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-13T15:50:44.532414Z","caller":"traceutil/trace.go:171","msg":"trace[1087309374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25304; }","duration":"118.596999ms","start":"2026-10-13T15:50:44.413799Z","end":"2026-10-13T15:50:44.532396Z","steps":["trace[1087309374] 'range keys from in-memory index tree' (duration: 118.277402ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:44.673735Z","caller":"traceutil/trace.go:171","msg":"trace[1951515105] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"137.996489ms","start":"2026-10-13T15:50:44.535714Z","end":"2026-10-13T15:50:44.673711Z","steps":["trace[1951515105] 'process raft request' (duration: 137.796084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:46.188993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.380475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-13T15:50:46.189074Z","caller":"traceutil/trace.go:171","msg":"trace[2108659265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25312; }","duration":"106.492088ms","start":"2026-10-13T15:50:46.082558Z","end":"2026-10-13T15:50:46.18905Z","steps":["trace[2108659265] 'range keys from in-memory index tree' (duration: 106.16226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-13T15:50:46.189353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.948744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-13T15:50:46.189426Z","caller":"traceutil/trace.go:171","msg":"trace[1784818862] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25312; }","duration":"106.064167ms","start":"2026-10-13T15:50:46.083341Z","end":"2026-10-13T15:50:46.189405Z","steps":["trace[1784818862] 'range keys from in-memory index tree' (duration: 105.793401ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:46.222932Z","caller":"traceutil/trace.go:171","msg":"trace[1848725683] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"102.044894ms","start":"2026-10-13T15:50:46.120866Z","end":"2026-10-13T15:50:46.222911Z","steps":["trace[1848725683] 'process raft request' (duration: 101.935031ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:47.721775Z","caller":"traceutil/trace.go:171","msg":"trace[462864868] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"106.005297ms","start":"2026-10-13T15:50:47.615731Z","end":"2026-10-13T15:50:47.721736Z","steps":["trace[462864868] 'process raft request' (duration: 104.404675ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:50:47.721856Z","caller":"traceutil/trace.go:171","msg":"trace[1479160924] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"102.99365ms","start":"2026-10-13T15:50:47.618808Z","end":"2026-10-13T15:50:47.721801Z","steps":["trace[1479160924] 'process raft request' (duration: 102.827805ms)"],"step_count":1} {"level":"info","ts":"2026-10-13T15:51:50.546597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22975} {"level":"info","ts":"2026-10-13T15:51:50.688902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22975,"took":"138.076721ms","hash":2900718708,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":36143104,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-13T15:51:50.688968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2900718708,"revision":22975,"compact-revision":20054} {"level":"info","ts":"2026-10-13T15:52:36.38275Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a4c.wal"} {"level":"info","ts":"2026-10-13T15:56:50.557093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25964} {"level":"info","ts":"2026-10-13T15:56:50.72346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25964,"took":"158.818384ms","hash":3777693019,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":34611200,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-13T15:56:50.723539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3777693019,"revision":25964,"compact-revision":22975} {"level":"info","ts":"2026-10-13T15:57:48.596211Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T15:57:48.604305Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-13T15:57:48.604474Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-13T16:01:50.564872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28662} {"level":"info","ts":"2026-10-13T16:01:50.717018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28662,"took":"148.012197ms","hash":3835695977,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T16:01:50.717159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3835695977,"revision":28662,"compact-revision":25964} {"level":"info","ts":"2026-10-13T16:06:50.572158Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30947} {"level":"info","ts":"2026-10-13T16:06:50.714335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30947,"took":"138.302209ms","hash":3583581827,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":30621696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T16:06:50.714407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3583581827,"revision":30947,"compact-revision":28662} {"level":"info","ts":"2026-10-13T16:11:50.579281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33241} {"level":"info","ts":"2026-10-13T16:11:50.756528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33241,"took":"172.890429ms","hash":2087659161,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":31068160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-13T16:11:50.756581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2087659161,"revision":33241,"compact-revision":30947} {"level":"info","ts":"2026-10-13T16:16:50.58573Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35533} {"level":"info","ts":"2026-10-13T16:16:50.751946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35533,"took":"160.122631ms","hash":769931822,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-13T16:16:50.752027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":769931822,"revision":35533,"compact-revision":33241} {"level":"info","ts":"2026-10-13T16:19:06.843828Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8572cc3a5351216d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-13T16:19:06.850473Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-13T16:19:06.850753Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-13T16:21:50.59282Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37762} {"level":"info","ts":"2026-10-13T16:21:50.725075Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37762,"took":"127.47607ms","hash":78092852,"current-db-size-bytes":56619008,"current-db-size":"57 MB","current-db-size-in-use-bytes":32768000,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-13T16:21:50.725163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":78092852,"revision":37762,"compact-revision":35533}