{"level":"warn","ts":"2026-05-05T06:40:50.597134Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:40:50.597236Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T06:40:50.597301Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:40:50.597314Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-05T06:40:50.597332Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T06:40:50.597949Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-05-05T06:40:50.598021Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T06:40:50.601961Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.716584ms"} {"level":"info","ts":"2026-05-05T06:40:50.606688Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-05-05T06:40:50.606748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:40:50.60677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-05-05T06:40:50.606779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:40:50.606792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-05-05T06:40:50.606832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-05-05T06:40:50.612616Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:40:50.613772Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:40:50.614918Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T06:40:50.616911Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:40:50.61716Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:40:50.61731Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:40:50.617383Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T06:40:50.617632Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:40:50.619538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-05-05T06:40:50.619716Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-05T06:40:50.623579Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-05T06:40:50.623662Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-05T06:40:50.623445Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T06:40:50.625229Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:40:50.625303Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:40:51.507987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:40:51.508032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:40:51.508056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-05-05T06:40:51.508068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:40:51.508074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-05T06:40:51.508082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-05-05T06:40:51.508089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-05T06:40:51.509185Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:40:51.509893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:40:51.51004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:40:51.510177Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:40:51.510209Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:40:51.510411Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:40:51.510484Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:40:51.51265Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:40:51.513425Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-05-05T06:40:51.513504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:40:51.520552Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:41:01.211455Z","caller":"traceutil/trace.go:171","msg":"trace[690322239] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"142.632188ms","start":"2026-05-05T06:41:01.06879Z","end":"2026-05-05T06:41:01.211422Z","steps":["trace[690322239] 'process raft request' (duration: 137.664815ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:41:06.436397Z","caller":"traceutil/trace.go:171","msg":"trace[2097181146] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"415.643498ms","start":"2026-05-05T06:41:06.020725Z","end":"2026-05-05T06:41:06.436368Z","steps":["trace[2097181146] 'process raft request' (duration: 415.505183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:06.437209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:41:06.020698Z","time spent":"415.809944ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:41:06.440032Z","caller":"traceutil/trace.go:171","msg":"trace[998094489] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"347.474513ms","start":"2026-05-05T06:41:06.092543Z","end":"2026-05-05T06:41:06.440017Z","steps":["trace[998094489] 'process raft request' (duration: 347.333798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:06.440093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:41:06.092524Z","time spent":"347.539405ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:41:06.440139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.821252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:41:06.440227Z","caller":"traceutil/trace.go:171","msg":"trace[2010274231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"218.010978ms","start":"2026-05-05T06:41:06.222202Z","end":"2026-05-05T06:41:06.440213Z","steps":["trace[2010274231] 'agreement among raft nodes before linearized reading' (duration: 217.853082ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:41:06.440013Z","caller":"traceutil/trace.go:171","msg":"trace[618005622] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:419; }","duration":"217.721928ms","start":"2026-05-05T06:41:06.222269Z","end":"2026-05-05T06:41:06.439991Z","steps":["trace[618005622] 'read index received' (duration: 214.007329ms)","trace[618005622] 'applied index is now lower than readState.Index' (duration: 3.713779ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:41:06.554002Z","caller":"traceutil/trace.go:171","msg":"trace[934216129] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"107.723036ms","start":"2026-05-05T06:41:06.446255Z","end":"2026-05-05T06:41:06.553978Z","steps":["trace[934216129] 'process raft request' (duration: 100.621593ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:41:08.247918Z","caller":"traceutil/trace.go:171","msg":"trace[809913394] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"272.171502ms","start":"2026-05-05T06:41:07.975716Z","end":"2026-05-05T06:41:08.247888Z","steps":["trace[809913394] 'process raft request' (duration: 272.006876ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:41:28.713634Z","caller":"traceutil/trace.go:171","msg":"trace[1030404111] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"147.627133ms","start":"2026-05-05T06:41:28.565983Z","end":"2026-05-05T06:41:28.71361Z","steps":["trace[1030404111] 'process raft request' (duration: 147.491837ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:41:30.864188Z","caller":"traceutil/trace.go:171","msg":"trace[136937917] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"122.2199ms","start":"2026-05-05T06:41:30.741932Z","end":"2026-05-05T06:41:30.864152Z","steps":["trace[136937917] 'read index received' (duration: 121.868636ms)","trace[136937917] 'applied index is now lower than readState.Index' (duration: 349.834µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:41:30.864714Z","caller":"traceutil/trace.go:171","msg":"trace[1004304603] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"141.705254ms","start":"2026-05-05T06:41:30.722988Z","end":"2026-05-05T06:41:30.864694Z","steps":["trace[1004304603] 'process raft request' (duration: 140.910624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:30.865268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.292802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T06:41:30.865336Z","caller":"traceutil/trace.go:171","msg":"trace[1511537998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"123.431167ms","start":"2026-05-05T06:41:30.741887Z","end":"2026-05-05T06:41:30.865318Z","steps":["trace[1511537998] 'agreement among raft nodes before linearized reading' (duration: 123.152846ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:41:31.053172Z","caller":"traceutil/trace.go:171","msg":"trace[1127058554] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"146.593164ms","start":"2026-05-05T06:41:30.906557Z","end":"2026-05-05T06:41:31.05315Z","steps":["trace[1127058554] 'read index received' (duration: 103.62593ms)","trace[1127058554] 'applied index is now lower than readState.Index' (duration: 42.966004ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:41:31.053274Z","caller":"traceutil/trace.go:171","msg":"trace[1815904586] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"182.87211ms","start":"2026-05-05T06:41:30.870354Z","end":"2026-05-05T06:41:31.053226Z","steps":["trace[1815904586] 'process raft request' (duration: 139.831074ms)","trace[1815904586] 'compare' (duration: 42.163712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:41:31.053371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.785661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:41:31.053465Z","caller":"traceutil/trace.go:171","msg":"trace[2012897327] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:680; }","duration":"146.923407ms","start":"2026-05-05T06:41:30.906518Z","end":"2026-05-05T06:41:31.053441Z","steps":["trace[2012897327] 'agreement among raft nodes before linearized reading' (duration: 146.761901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:31.053823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.967946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:41:31.053866Z","caller":"traceutil/trace.go:171","msg":"trace[707450853] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:680; }","duration":"109.022567ms","start":"2026-05-05T06:41:30.944833Z","end":"2026-05-05T06:41:31.053855Z","steps":["trace[707450853] 'agreement among raft nodes before linearized reading' (duration: 108.957545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:31.05383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.128371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:41:31.053912Z","caller":"traceutil/trace.go:171","msg":"trace[1277850134] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:680; }","duration":"109.223095ms","start":"2026-05-05T06:41:30.944676Z","end":"2026-05-05T06:41:31.053899Z","steps":["trace[1277850134] 'agreement among raft nodes before linearized reading' (duration: 109.115761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:31.054024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.970985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:41:31.054049Z","caller":"traceutil/trace.go:171","msg":"trace[579558540] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:680; }","duration":"109.021237ms","start":"2026-05-05T06:41:30.945022Z","end":"2026-05-05T06:41:31.054043Z","steps":["trace[579558540] 'agreement among raft nodes before linearized reading' (duration: 108.978306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:31.054195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.901786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T06:41:31.054313Z","caller":"traceutil/trace.go:171","msg":"trace[512544349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"113.064802ms","start":"2026-05-05T06:41:30.94122Z","end":"2026-05-05T06:41:31.054285Z","steps":["trace[512544349] 'agreement among raft nodes before linearized reading' (duration: 112.800602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:31.053393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.802512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:41:31.054582Z","caller":"traceutil/trace.go:171","msg":"trace[1451480335] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:680; }","duration":"148.037518ms","start":"2026-05-05T06:41:30.90653Z","end":"2026-05-05T06:41:31.054567Z","steps":["trace[1451480335] 'agreement among raft nodes before linearized reading' (duration: 146.76385ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:41:47.730406Z","caller":"traceutil/trace.go:171","msg":"trace[603648688] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"201.715863ms","start":"2026-05-05T06:41:47.528665Z","end":"2026-05-05T06:41:47.730381Z","steps":["trace[603648688] 'process raft request' (duration: 201.550797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:01.34321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.83405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:01.343345Z","caller":"traceutil/trace.go:171","msg":"trace[1031055326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"121.051627ms","start":"2026-05-05T06:43:01.222273Z","end":"2026-05-05T06:43:01.343324Z","steps":["trace[1031055326] 'range keys from in-memory index tree' (duration: 120.744806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.06471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118163538885845,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T06:43:02.22231Z","caller":"traceutil/trace.go:171","msg":"trace[1797677747] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"658.005469ms","start":"2026-05-05T06:43:01.564275Z","end":"2026-05-05T06:43:02.22228Z","steps":["trace[1797677747] 'read index received' (duration: 657.851053ms)","trace[1797677747] 'applied index is now lower than readState.Index' (duration: 152.926µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:43:02.222685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.392054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T06:43:02.22277Z","caller":"traceutil/trace.go:171","msg":"trace[475562163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"658.52048ms","start":"2026-05-05T06:43:01.564229Z","end":"2026-05-05T06:43:02.222749Z","steps":["trace[475562163] 'agreement among raft nodes before linearized reading' (duration: 658.2625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.222804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:01.564189Z","time spent":"658.608812ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T06:43:02.222896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.030695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T06:43:02.223023Z","caller":"traceutil/trace.go:171","msg":"trace[961713570] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"681.364021ms","start":"2026-05-05T06:43:01.541637Z","end":"2026-05-05T06:43:02.223001Z","steps":["trace[961713570] 'process raft request' (duration: 680.481797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.223163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.739537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T06:43:02.223213Z","caller":"traceutil/trace.go:171","msg":"trace[991161074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1859; }","duration":"499.825161ms","start":"2026-05-05T06:43:01.72338Z","end":"2026-05-05T06:43:02.223205Z","steps":["trace[991161074] 'agreement among raft nodes before linearized reading' (duration: 499.683866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.223286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.03765ms","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-05-05T06:43:02.223323Z","caller":"traceutil/trace.go:171","msg":"trace[1709393893] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1859; }","duration":"287.097373ms","start":"2026-05-05T06:43:01.936214Z","end":"2026-05-05T06:43:02.223311Z","steps":["trace[1709393893] 'agreement among raft nodes before linearized reading' (duration: 286.979219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.22329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:01.723326Z","time spent":"499.953127ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:43:02.222759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.342412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:02.223389Z","caller":"traceutil/trace.go:171","msg":"trace[121180071] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1859; }","duration":"362.073961ms","start":"2026-05-05T06:43:01.861304Z","end":"2026-05-05T06:43:02.223378Z","steps":["trace[121180071] 'agreement among raft nodes before linearized reading' (duration: 361.322762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.223421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:01.861293Z","time spent":"362.116293ms","remote":"127.0.0.1:47714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:43:02.223171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.061707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T06:43:02.223653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.071465ms","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-05-05T06:43:02.223681Z","caller":"traceutil/trace.go:171","msg":"trace[954043561] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"368.140037ms","start":"2026-05-05T06:43:01.855535Z","end":"2026-05-05T06:43:02.223675Z","steps":["trace[954043561] 'agreement among raft nodes before linearized reading' (duration: 368.070545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.223701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:01.855515Z","time spent":"368.181339ms","remote":"127.0.0.1:44902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T06:43:02.223804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:01.541611Z","time spent":"681.469465ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:02.223032Z","caller":"traceutil/trace.go:171","msg":"trace[2064519516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1859; }","duration":"642.338008ms","start":"2026-05-05T06:43:01.580667Z","end":"2026-05-05T06:43:02.223005Z","steps":["trace[2064519516] 'agreement among raft nodes before linearized reading' (duration: 641.867799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.223924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:01.580638Z","time spent":"643.278094ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T06:43:02.223993Z","caller":"traceutil/trace.go:171","msg":"trace[537301573] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1859; }","duration":"377.554746ms","start":"2026-05-05T06:43:01.846047Z","end":"2026-05-05T06:43:02.223602Z","steps":["trace[537301573] 'agreement among raft nodes before linearized reading' (duration: 376.912061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.224072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:01.846025Z","time spent":"378.006803ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":56,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-05T06:43:02.557537Z","caller":"traceutil/trace.go:171","msg":"trace[1835135668] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"326.858395ms","start":"2026-05-05T06:43:02.230658Z","end":"2026-05-05T06:43:02.557516Z","steps":["trace[1835135668] 'process raft request' (duration: 326.830194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.55772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:02.23064Z","time spent":"326.988781ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:02.557977Z","caller":"traceutil/trace.go:171","msg":"trace[215696184] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"328.266701ms","start":"2026-05-05T06:43:02.229701Z","end":"2026-05-05T06:43:02.557968Z","steps":["trace[215696184] 'process raft request' (duration: 327.766931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.558046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:02.22969Z","time spent":"328.309683ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:02.558282Z","caller":"traceutil/trace.go:171","msg":"trace[500353763] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"328.714829ms","start":"2026-05-05T06:43:02.22956Z","end":"2026-05-05T06:43:02.558274Z","steps":["trace[500353763] 'process raft request' (duration: 327.704089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.558365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:02.229549Z","time spent":"328.75139ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:02.558406Z","caller":"traceutil/trace.go:171","msg":"trace[1075292383] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"328.766141ms","start":"2026-05-05T06:43:02.229609Z","end":"2026-05-05T06:43:02.558375Z","steps":["trace[1075292383] 'process raft request' (duration: 327.832354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:02.558611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:02.229583Z","time spent":"328.910477ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:02.820483Z","caller":"traceutil/trace.go:171","msg":"trace[225252346] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"247.86695ms","start":"2026-05-05T06:43:02.572575Z","end":"2026-05-05T06:43:02.820442Z","steps":["trace[225252346] 'process raft request' (duration: 247.801188ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:02.820732Z","caller":"traceutil/trace.go:171","msg":"trace[1457121804] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"257.254637ms","start":"2026-05-05T06:43:02.563444Z","end":"2026-05-05T06:43:02.820699Z","steps":["trace[1457121804] 'process raft request' (duration: 249.504714ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:03.01258Z","caller":"traceutil/trace.go:171","msg":"trace[1174827249] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"126.017472ms","start":"2026-05-05T06:43:02.886531Z","end":"2026-05-05T06:43:03.012549Z","steps":["trace[1174827249] 'process raft request' (duration: 38.620409ms)","trace[1174827249] 'compare' (duration: 87.267498ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:03.737449Z","caller":"traceutil/trace.go:171","msg":"trace[2088611344] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"173.435544ms","start":"2026-05-05T06:43:03.563993Z","end":"2026-05-05T06:43:03.737429Z","steps":["trace[2088611344] 'read index received' (duration: 173.261847ms)","trace[2088611344] 'applied index is now lower than readState.Index' (duration: 172.847µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:03.737521Z","caller":"traceutil/trace.go:171","msg":"trace[1009327157] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"193.5343ms","start":"2026-05-05T06:43:03.543964Z","end":"2026-05-05T06:43:03.737498Z","steps":["trace[1009327157] 'process raft request' (duration: 193.332312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:03.737829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.819639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b22fdc9-634d-4e82-a699-de2c75eef778\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-05T06:43:03.737921Z","caller":"traceutil/trace.go:171","msg":"trace[112341318] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4b22fdc9-634d-4e82-a699-de2c75eef778; range_end:; response_count:1; response_revision:1871; }","duration":"173.949324ms","start":"2026-05-05T06:43:03.563954Z","end":"2026-05-05T06:43:03.737904Z","steps":["trace[112341318] 'agreement among raft nodes before linearized reading' (duration: 173.697395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:03.73842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.457317ms","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-05-05T06:43:03.738489Z","caller":"traceutil/trace.go:171","msg":"trace[1132264103] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"171.571011ms","start":"2026-05-05T06:43:03.566901Z","end":"2026-05-05T06:43:03.738472Z","steps":["trace[1132264103] 'agreement among raft nodes before linearized reading' (duration: 171.357593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:13.249146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.010184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T06:43:13.249226Z","caller":"traceutil/trace.go:171","msg":"trace[2134244880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1954; }","duration":"633.118595ms","start":"2026-05-05T06:43:12.616089Z","end":"2026-05-05T06:43:13.249207Z","steps":["trace[2134244880] 'range keys from in-memory index tree' (duration: 632.863978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:13.249281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:12.616082Z","time spent":"633.180079ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:43:13.249675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.535066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T06:43:13.249698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.577451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T06:43:13.249746Z","caller":"traceutil/trace.go:171","msg":"trace[1347171578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1954; }","duration":"635.638786ms","start":"2026-05-05T06:43:12.61409Z","end":"2026-05-05T06:43:13.249729Z","steps":["trace[1347171578] 'range keys from in-memory index tree' (duration: 634.670821ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:13.24978Z","caller":"traceutil/trace.go:171","msg":"trace[534645751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1954; }","duration":"642.688331ms","start":"2026-05-05T06:43:12.607055Z","end":"2026-05-05T06:43:13.249743Z","steps":["trace[534645751] 'range keys from in-memory index tree' (duration: 642.401456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:13.249794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:12.614076Z","time spent":"635.70529ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T06:43:13.249819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:12.607036Z","time spent":"642.776043ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T06:43:13.249772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.064829ms","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-05-05T06:43:13.24996Z","caller":"traceutil/trace.go:171","msg":"trace[904310753] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1954; }","duration":"641.304458ms","start":"2026-05-05T06:43:12.608625Z","end":"2026-05-05T06:43:13.24993Z","steps":["trace[904310753] 'range keys from in-memory index tree' (duration: 640.880886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:13.250088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:12.608615Z","time spent":"641.430967ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T06:43:13.250438Z","caller":"traceutil/trace.go:171","msg":"trace[923688447] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"174.954053ms","start":"2026-05-05T06:43:13.075468Z","end":"2026-05-05T06:43:13.250422Z","steps":["trace[923688447] 'process raft request' (duration: 174.8742ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:13.250642Z","caller":"traceutil/trace.go:171","msg":"trace[4584707] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"390.506944ms","start":"2026-05-05T06:43:12.860124Z","end":"2026-05-05T06:43:13.250631Z","steps":["trace[4584707] 'process raft request' (duration: 384.936404ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:13.25068Z","caller":"traceutil/trace.go:171","msg":"trace[1579839102] linearizableReadLoop","detail":"{readStateIndex:2029; appliedIndex:2028; }","duration":"382.393357ms","start":"2026-05-05T06:43:12.868257Z","end":"2026-05-05T06:43:13.25065Z","steps":["trace[1579839102] 'read index received' (duration: 376.823066ms)","trace[1579839102] 'applied index is now lower than readState.Index' (duration: 5.567021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:43:13.250754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:12.8601Z","time spent":"390.569759ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:43:13.250846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.578071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-05-05T06:43:13.250909Z","caller":"traceutil/trace.go:171","msg":"trace[2116902689] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1956; }","duration":"382.660093ms","start":"2026-05-05T06:43:12.86823Z","end":"2026-05-05T06:43:13.25089Z","steps":["trace[2116902689] 'agreement among raft nodes before linearized reading' (duration: 382.507167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:13.25096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:12.868219Z","time spent":"382.729237ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T06:43:14.495217Z","caller":"traceutil/trace.go:171","msg":"trace[805613370] linearizableReadLoop","detail":"{readStateIndex:2036; appliedIndex:2035; }","duration":"272.304104ms","start":"2026-05-05T06:43:14.222883Z","end":"2026-05-05T06:43:14.495187Z","steps":["trace[805613370] 'read index received' (duration: 271.791618ms)","trace[805613370] 'applied index is now lower than readState.Index' (duration: 511.096µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:14.495358Z","caller":"traceutil/trace.go:171","msg":"trace[276359710] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"709.933911ms","start":"2026-05-05T06:43:13.785376Z","end":"2026-05-05T06:43:14.49531Z","steps":["trace[276359710] 'process raft request' (duration: 709.379848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:14.49546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.550464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:14.495502Z","caller":"traceutil/trace.go:171","msg":"trace[650690456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1962; }","duration":"272.667213ms","start":"2026-05-05T06:43:14.222824Z","end":"2026-05-05T06:43:14.495491Z","steps":["trace[650690456] 'agreement among raft nodes before linearized reading' (duration: 272.539894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:14.495718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.606931ms","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-05-05T06:43:14.496054Z","caller":"traceutil/trace.go:171","msg":"trace[13951535] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1962; }","duration":"238.058313ms","start":"2026-05-05T06:43:14.257943Z","end":"2026-05-05T06:43:14.496001Z","steps":["trace[13951535] 'agreement among raft nodes before linearized reading' (duration: 237.473703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:14.496525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:13.785355Z","time spent":"711.058955ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:43:16.005059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118163538886088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:43:16.506111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118163538886088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:43:16.808431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539337608s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:43:16.808523Z","caller":"traceutil/trace.go:171","msg":"trace[846351444] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"1.542104127s","start":"2026-05-05T06:43:15.266403Z","end":"2026-05-05T06:43:16.808507Z","steps":["trace[846351444] 'compare' (duration: 1.538991185s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:16.80858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:15.266392Z","time spent":"1.542160292s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:43:16.810366Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.541641399s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T06:43:16.811348Z","caller":"traceutil/trace.go:171","msg":"trace[355804044] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"1.542991274s","start":"2026-05-05T06:43:15.268305Z","end":"2026-05-05T06:43:16.811297Z","steps":["trace[355804044] 'process raft request' (duration: 1.542410328s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:16.811508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:15.268292Z","time spent":"1.543124393s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:16.811505Z","caller":"traceutil/trace.go:171","msg":"trace[389057613] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"1.543166129s","start":"2026-05-05T06:43:15.268316Z","end":"2026-05-05T06:43:16.811482Z","steps":["trace[389057613] 'process raft request' (duration: 1.542841184s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:16.811618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:15.268307Z","time spent":"1.543253233s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:16.811646Z","caller":"traceutil/trace.go:171","msg":"trace[380709178] linearizableReadLoop","detail":"{readStateIndex:2046; appliedIndex:2043; }","duration":"1.307090786s","start":"2026-05-05T06:43:15.504478Z","end":"2026-05-05T06:43:16.811569Z","steps":["trace[380709178] 'read index received' (duration: 1.306210363s)","trace[380709178] 'applied index is now lower than readState.Index' (duration: 879.483µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:16.811763Z","caller":"traceutil/trace.go:171","msg":"trace[2024252327] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"1.543266613s","start":"2026-05-05T06:43:15.268482Z","end":"2026-05-05T06:43:16.811749Z","steps":["trace[2024252327] 'process raft request' (duration: 1.542874283s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:16.811819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:15.268445Z","time spent":"1.543343407s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:43:16.812142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307619625s","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-05-05T06:43:16.812269Z","caller":"traceutil/trace.go:171","msg":"trace[1212775809] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1971; }","duration":"1.307842927s","start":"2026-05-05T06:43:15.504409Z","end":"2026-05-05T06:43:16.812252Z","steps":["trace[1212775809] 'agreement among raft nodes before linearized reading' (duration: 1.30742968s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:16.812447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:15.504384Z","time spent":"1.307983756s","remote":"127.0.0.1:44902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T06:43:16.812551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.811891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:43:16.812556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.936012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T06:43:16.812607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.947083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:43:16.81246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.100477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:16.812629Z","caller":"traceutil/trace.go:171","msg":"trace[1506837539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1972; }","duration":"589.012818ms","start":"2026-05-05T06:43:16.223612Z","end":"2026-05-05T06:43:16.812625Z","steps":["trace[1506837539] 'agreement among raft nodes before linearized reading' (duration: 588.976401ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:16.812638Z","caller":"traceutil/trace.go:171","msg":"trace[406741250] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1972; }","duration":"898.324769ms","start":"2026-05-05T06:43:15.914303Z","end":"2026-05-05T06:43:16.812628Z","steps":["trace[406741250] 'agreement among raft nodes before linearized reading' (duration: 898.112346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:16.812674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:15.914279Z","time spent":"898.383114ms","remote":"127.0.0.1:47822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:43:16.812647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:16.223557Z","time spent":"589.085603ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:43:16.812757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.873619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:43:16.812603Z","caller":"traceutil/trace.go:171","msg":"trace[1318624027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1972; }","duration":"287.022115ms","start":"2026-05-05T06:43:16.525568Z","end":"2026-05-05T06:43:16.81259Z","steps":["trace[1318624027] 'agreement among raft nodes before linearized reading' (duration: 286.856848ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:16.812834Z","caller":"traceutil/trace.go:171","msg":"trace[370894618] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1972; }","duration":"248.9934ms","start":"2026-05-05T06:43:16.563824Z","end":"2026-05-05T06:43:16.812817Z","steps":["trace[370894618] 'agreement among raft nodes before linearized reading' (duration: 248.769118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:16.812523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.15175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T06:43:16.812575Z","caller":"traceutil/trace.go:171","msg":"trace[1417592698] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1972; }","duration":"561.906285ms","start":"2026-05-05T06:43:16.25066Z","end":"2026-05-05T06:43:16.812567Z","steps":["trace[1417592698] 'agreement among raft nodes before linearized reading' (duration: 561.874807ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:16.812931Z","caller":"traceutil/trace.go:171","msg":"trace[1286490058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1972; }","duration":"310.548509ms","start":"2026-05-05T06:43:16.502367Z","end":"2026-05-05T06:43:16.812915Z","steps":["trace[1286490058] 'agreement among raft nodes before linearized reading' (duration: 310.111893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:16.812967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:16.502278Z","time spent":"310.678678ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:43:16.812969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:16.250624Z","time spent":"562.316213ms","remote":"127.0.0.1:47808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-05T06:44:55.860319Z","caller":"traceutil/trace.go:171","msg":"trace[807998457] transaction","detail":"{read_only:false; response_revision:2621; number_of_response:1; }","duration":"232.850858ms","start":"2026-05-05T06:44:55.627435Z","end":"2026-05-05T06:44:55.860286Z","steps":["trace[807998457] 'process raft request' (duration: 232.659885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:55.860515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.553399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-05T06:44:55.860577Z","caller":"traceutil/trace.go:171","msg":"trace[319794134] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2621; }","duration":"212.661472ms","start":"2026-05-05T06:44:55.647902Z","end":"2026-05-05T06:44:55.860563Z","steps":["trace[319794134] 'agreement among raft nodes before linearized reading' (duration: 212.467188ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:44:55.860261Z","caller":"traceutil/trace.go:171","msg":"trace[2099898158] linearizableReadLoop","detail":"{readStateIndex:2718; appliedIndex:2717; }","duration":"212.293435ms","start":"2026-05-05T06:44:55.647943Z","end":"2026-05-05T06:44:55.860236Z","steps":["trace[2099898158] 'read index received' (duration: 212.257594ms)","trace[2099898158] 'applied index is now lower than readState.Index' (duration: 34.75µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:44:55.874109Z","caller":"traceutil/trace.go:171","msg":"trace[1448107774] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"179.079364ms","start":"2026-05-05T06:44:55.695021Z","end":"2026-05-05T06:44:55.8741Z","steps":["trace[1448107774] 'process raft request' (duration: 178.962852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:55.874186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.926087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T06:44:55.874221Z","caller":"traceutil/trace.go:171","msg":"trace[2057327007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2623; }","duration":"102.995248ms","start":"2026-05-05T06:44:55.771214Z","end":"2026-05-05T06:44:55.874209Z","steps":["trace[2057327007] 'agreement among raft nodes before linearized reading' (duration: 102.886816ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:44:55.87435Z","caller":"traceutil/trace.go:171","msg":"trace[1561182751] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"172.503152ms","start":"2026-05-05T06:44:55.701811Z","end":"2026-05-05T06:44:55.874314Z","steps":["trace[1561182751] 'process raft request' (duration: 172.249897ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:44:59.450978Z","caller":"traceutil/trace.go:171","msg":"trace[667275553] linearizableReadLoop","detail":"{readStateIndex:2732; appliedIndex:2731; }","duration":"128.283426ms","start":"2026-05-05T06:44:59.322678Z","end":"2026-05-05T06:44:59.450962Z","steps":["trace[667275553] 'read index received' (duration: 128.180804ms)","trace[667275553] 'applied index is now lower than readState.Index' (duration: 101.492µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:44:59.451489Z","caller":"traceutil/trace.go:171","msg":"trace[483832129] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"352.619892ms","start":"2026-05-05T06:44:59.098849Z","end":"2026-05-05T06:44:59.451469Z","steps":["trace[483832129] 'process raft request' (duration: 351.984499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:59.451582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:44:59.098815Z","time spent":"352.695023ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:44:59.451727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.048002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:44:59.451751Z","caller":"traceutil/trace.go:171","msg":"trace[1136025141] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2635; }","duration":"129.109133ms","start":"2026-05-05T06:44:59.322635Z","end":"2026-05-05T06:44:59.451744Z","steps":["trace[1136025141] 'agreement among raft nodes before linearized reading' (duration: 129.058102ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:45:00.31612Z","caller":"traceutil/trace.go:171","msg":"trace[1641406813] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"360.634655ms","start":"2026-05-05T06:44:59.955453Z","end":"2026-05-05T06:45:00.316088Z","steps":["trace[1641406813] 'process raft request' (duration: 358.721606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:00.316317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:44:59.955442Z","time spent":"360.767118ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:45:28.661943Z","caller":"traceutil/trace.go:171","msg":"trace[511915999] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"192.282826ms","start":"2026-05-05T06:45:28.469632Z","end":"2026-05-05T06:45:28.661915Z","steps":["trace[511915999] 'process raft request' (duration: 192.129303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:28.967318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.316415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ac98642882489d\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-05T06:45:28.968266Z","caller":"traceutil/trace.go:171","msg":"trace[1179573804] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ac98642882489d; range_end:; response_count:1; response_revision:2801; }","duration":"117.349565ms","start":"2026-05-05T06:45:28.850884Z","end":"2026-05-05T06:45:28.968234Z","steps":["trace[1179573804] 'range keys from in-memory index tree' (duration: 116.067079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:59.351295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118163538888666,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T06:45:59.550271Z","caller":"traceutil/trace.go:171","msg":"trace[2079046516] linearizableReadLoop","detail":"{readStateIndex:3071; appliedIndex:3070; }","duration":"700.270191ms","start":"2026-05-05T06:45:58.849983Z","end":"2026-05-05T06:45:59.550253Z","steps":["trace[2079046516] 'read index received' (duration: 700.184049ms)","trace[2079046516] 'applied index is now lower than readState.Index' (duration: 85.392µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:45:59.55036Z","caller":"traceutil/trace.go:171","msg":"trace[236586133] transaction","detail":"{read_only:false; response_revision:2961; number_of_response:1; }","duration":"720.268889ms","start":"2026-05-05T06:45:58.830065Z","end":"2026-05-05T06:45:59.550334Z","steps":["trace[236586133] 'process raft request' (duration: 720.077395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:59.550632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:45:58.830052Z","time spent":"720.517774ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:45:59.550496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.500595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ac98642882489d\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-05T06:45:59.550781Z","caller":"traceutil/trace.go:171","msg":"trace[1181317746] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ac98642882489d; range_end:; response_count:1; response_revision:2961; }","duration":"700.73384ms","start":"2026-05-05T06:45:58.849932Z","end":"2026-05-05T06:45:59.550666Z","steps":["trace[1181317746] 'agreement among raft nodes before linearized reading' (duration: 700.453025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:59.551027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:45:58.849913Z","time spent":"700.969965ms","remote":"127.0.0.1:37170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18ac98642882489d\" "} {"level":"warn","ts":"2026-05-05T06:45:59.556517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.137803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:45:59.556563Z","caller":"traceutil/trace.go:171","msg":"trace[1187576699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2961; }","duration":"323.234605ms","start":"2026-05-05T06:45:59.233319Z","end":"2026-05-05T06:45:59.556553Z","steps":["trace[1187576699] 'agreement among raft nodes before linearized reading' (duration: 323.134123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:59.556586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:45:59.2333Z","time spent":"323.281475ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:46:00.101364Z","caller":"traceutil/trace.go:171","msg":"trace[994168716] transaction","detail":"{read_only:false; response_revision:2965; number_of_response:1; }","duration":"254.468972ms","start":"2026-05-05T06:45:59.846879Z","end":"2026-05-05T06:46:00.101348Z","steps":["trace[994168716] 'process raft request' (duration: 254.3893ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:46:00.101372Z","caller":"traceutil/trace.go:171","msg":"trace[1960765912] transaction","detail":"{read_only:false; response_revision:2964; number_of_response:1; }","duration":"254.558124ms","start":"2026-05-05T06:45:59.846776Z","end":"2026-05-05T06:46:00.101334Z","steps":["trace[1960765912] 'process raft request' (duration: 246.591532ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:46:00.101436Z","caller":"traceutil/trace.go:171","msg":"trace[1141519851] transaction","detail":"{read_only:false; response_revision:2966; number_of_response:1; }","duration":"254.498863ms","start":"2026-05-05T06:45:59.846929Z","end":"2026-05-05T06:46:00.101428Z","steps":["trace[1141519851] 'process raft request' (duration: 254.39808ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:46:00.101572Z","caller":"traceutil/trace.go:171","msg":"trace[636258991] transaction","detail":"{read_only:false; response_revision:2967; number_of_response:1; }","duration":"254.601624ms","start":"2026-05-05T06:45:59.846963Z","end":"2026-05-05T06:46:00.101564Z","steps":["trace[636258991] 'process raft request' (duration: 254.437991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:46:00.101764Z","caller":"traceutil/trace.go:171","msg":"trace[2058884030] transaction","detail":"{read_only:false; response_revision:2968; number_of_response:1; }","duration":"254.602135ms","start":"2026-05-05T06:45:59.847156Z","end":"2026-05-05T06:46:00.101758Z","steps":["trace[2058884030] 'process raft request' (duration: 254.37829ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:13.028763Z","caller":"traceutil/trace.go:171","msg":"trace[11753960] transaction","detail":"{read_only:false; response_revision:4168; number_of_response:1; }","duration":"124.612038ms","start":"2026-05-05T06:49:12.904126Z","end":"2026-05-05T06:49:13.028738Z","steps":["trace[11753960] 'process raft request' (duration: 124.264659ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:20.821144Z","caller":"traceutil/trace.go:171","msg":"trace[2061791655] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"129.735771ms","start":"2026-05-05T06:49:20.691386Z","end":"2026-05-05T06:49:20.821122Z","steps":["trace[2061791655] 'process raft request' (duration: 106.237709ms)","trace[2061791655] 'compare' (duration: 22.863407ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:20.821312Z","caller":"traceutil/trace.go:171","msg":"trace[721685329] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"123.465081ms","start":"2026-05-05T06:49:20.697831Z","end":"2026-05-05T06:49:20.821296Z","steps":["trace[721685329] 'process raft request' (duration: 122.841956ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:20.821684Z","caller":"traceutil/trace.go:171","msg":"trace[753997911] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"128.058411ms","start":"2026-05-05T06:49:20.693604Z","end":"2026-05-05T06:49:20.821663Z","steps":["trace[753997911] 'process raft request' (duration: 127.012516ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:21.025865Z","caller":"traceutil/trace.go:171","msg":"trace[1424004152] linearizableReadLoop","detail":"{readStateIndex:4733; appliedIndex:4732; }","duration":"184.623852ms","start":"2026-05-05T06:49:20.841209Z","end":"2026-05-05T06:49:21.025833Z","steps":["trace[1424004152] 'read index received' (duration: 150.598529ms)","trace[1424004152] 'applied index is now lower than readState.Index' (duration: 34.023813ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:21.025974Z","caller":"traceutil/trace.go:171","msg":"trace[319378345] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"197.487481ms","start":"2026-05-05T06:49:20.828455Z","end":"2026-05-05T06:49:21.025942Z","steps":["trace[319378345] 'process raft request' (duration: 163.339994ms)","trace[319378345] 'compare' (duration: 33.85619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:21.026051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.812507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:49:21.026118Z","caller":"traceutil/trace.go:171","msg":"trace[1620314917] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:0; response_revision:4556; }","duration":"184.92488ms","start":"2026-05-05T06:49:20.841173Z","end":"2026-05-05T06:49:21.026098Z","steps":["trace[1620314917] 'agreement among raft nodes before linearized reading' (duration: 184.809418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:21.028064Z","caller":"traceutil/trace.go:171","msg":"trace[1159573544] transaction","detail":"{read_only:false; response_revision:4557; number_of_response:1; }","duration":"143.279175ms","start":"2026-05-05T06:49:20.884736Z","end":"2026-05-05T06:49:21.028015Z","steps":["trace[1159573544] 'process raft request' (duration: 142.796543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:21.028326Z","caller":"traceutil/trace.go:171","msg":"trace[1814654521] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"131.182065ms","start":"2026-05-05T06:49:20.897119Z","end":"2026-05-05T06:49:21.028301Z","steps":["trace[1814654521] 'process raft request' (duration: 130.734615ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:21.028443Z","caller":"traceutil/trace.go:171","msg":"trace[1025884497] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"129.631049ms","start":"2026-05-05T06:49:20.898788Z","end":"2026-05-05T06:49:21.02842Z","steps":["trace[1025884497] 'process raft request' (duration: 129.400834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:21.028454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.382543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-05T06:49:21.02854Z","caller":"traceutil/trace.go:171","msg":"trace[1893053938] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4559; }","duration":"142.517117ms","start":"2026-05-05T06:49:20.886007Z","end":"2026-05-05T06:49:21.028524Z","steps":["trace[1893053938] 'agreement among raft nodes before linearized reading' (duration: 142.301711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:21.028798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.769808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T06:49:21.028835Z","caller":"traceutil/trace.go:171","msg":"trace[1829168380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4559; }","duration":"138.84616ms","start":"2026-05-05T06:49:20.889979Z","end":"2026-05-05T06:49:21.028825Z","steps":["trace[1829168380] 'agreement among raft nodes before linearized reading' (duration: 138.754136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:21.03117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.27119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-05T06:49:21.031292Z","caller":"traceutil/trace.go:171","msg":"trace[1493328970] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4559; }","duration":"142.408734ms","start":"2026-05-05T06:49:20.888839Z","end":"2026-05-05T06:49:21.031248Z","steps":["trace[1493328970] 'agreement among raft nodes before linearized reading' (duration: 139.927544ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:22.100505Z","caller":"traceutil/trace.go:171","msg":"trace[1411053541] linearizableReadLoop","detail":"{readStateIndex:4801; appliedIndex:4800; }","duration":"105.942663ms","start":"2026-05-05T06:49:21.994543Z","end":"2026-05-05T06:49:22.100486Z","steps":["trace[1411053541] 'read index received' (duration: 105.805789ms)","trace[1411053541] 'applied index is now lower than readState.Index' (duration: 136.114µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:22.100593Z","caller":"traceutil/trace.go:171","msg":"trace[1453059659] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"113.367919ms","start":"2026-05-05T06:49:21.987197Z","end":"2026-05-05T06:49:22.100565Z","steps":["trace[1453059659] 'process raft request' (duration: 113.166125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:22.100653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.086607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:49:22.10073Z","caller":"traceutil/trace.go:171","msg":"trace[394286934] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4624; }","duration":"106.200149ms","start":"2026-05-05T06:49:21.994523Z","end":"2026-05-05T06:49:22.100724Z","steps":["trace[394286934] 'agreement among raft nodes before linearized reading' (duration: 106.067826ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:23.830991Z","caller":"traceutil/trace.go:171","msg":"trace[261402753] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"109.937348ms","start":"2026-05-05T06:49:23.720994Z","end":"2026-05-05T06:49:23.830931Z","steps":["trace[261402753] 'process raft request' (duration: 109.796354ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:26.037433Z","caller":"traceutil/trace.go:171","msg":"trace[50715153] linearizableReadLoop","detail":"{readStateIndex:4903; appliedIndex:4902; }","duration":"137.534819ms","start":"2026-05-05T06:49:25.899876Z","end":"2026-05-05T06:49:26.03741Z","steps":["trace[50715153] 'read index received' (duration: 137.327264ms)","trace[50715153] 'applied index is now lower than readState.Index' (duration: 206.695µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:26.037562Z","caller":"traceutil/trace.go:171","msg":"trace[935507595] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"140.358976ms","start":"2026-05-05T06:49:25.897184Z","end":"2026-05-05T06:49:26.037543Z","steps":["trace[935507595] 'process raft request' (duration: 140.076729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:26.038017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.121443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-05-05T06:49:26.03807Z","caller":"traceutil/trace.go:171","msg":"trace[324923663] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4722; }","duration":"138.201185ms","start":"2026-05-05T06:49:25.899856Z","end":"2026-05-05T06:49:26.038057Z","steps":["trace[324923663] 'agreement among raft nodes before linearized reading' (duration: 137.880167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:26.038091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.75163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:57101"} {"level":"info","ts":"2026-05-05T06:49:26.038235Z","caller":"traceutil/trace.go:171","msg":"trace[472785593] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4722; }","duration":"136.934175ms","start":"2026-05-05T06:49:25.901282Z","end":"2026-05-05T06:49:26.038217Z","steps":["trace[472785593] 'agreement among raft nodes before linearized reading' (duration: 136.571996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:26.403897Z","caller":"traceutil/trace.go:171","msg":"trace[1549116716] linearizableReadLoop","detail":"{readStateIndex:4910; appliedIndex:4909; }","duration":"146.259977ms","start":"2026-05-05T06:49:26.25761Z","end":"2026-05-05T06:49:26.40387Z","steps":["trace[1549116716] 'read index received' (duration: 127.303814ms)","trace[1549116716] 'applied index is now lower than readState.Index' (duration: 18.955013ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:26.404201Z","caller":"traceutil/trace.go:171","msg":"trace[555098941] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"155.104679ms","start":"2026-05-05T06:49:26.249082Z","end":"2026-05-05T06:49:26.404187Z","steps":["trace[555098941] 'process raft request' (duration: 135.776516ms)","trace[555098941] 'compare' (duration: 18.699928ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:26.404574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.940973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-05T06:49:26.404643Z","caller":"traceutil/trace.go:171","msg":"trace[1820706025] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4729; }","duration":"147.004165ms","start":"2026-05-05T06:49:26.257603Z","end":"2026-05-05T06:49:26.404608Z","steps":["trace[1820706025] 'agreement among raft nodes before linearized reading' (duration: 146.80298ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:27.635393Z","caller":"traceutil/trace.go:171","msg":"trace[1839569945] linearizableReadLoop","detail":"{readStateIndex:4926; appliedIndex:4925; }","duration":"325.926235ms","start":"2026-05-05T06:49:27.30945Z","end":"2026-05-05T06:49:27.635377Z","steps":["trace[1839569945] 'read index received' (duration: 325.867713ms)","trace[1839569945] 'applied index is now lower than readState.Index' (duration: 57.802µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:27.635469Z","caller":"traceutil/trace.go:171","msg":"trace[1582081946] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"326.502118ms","start":"2026-05-05T06:49:27.308947Z","end":"2026-05-05T06:49:27.635449Z","steps":["trace[1582081946] 'process raft request' (duration: 326.303754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.635612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:27.308929Z","time spent":"326.608741ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4926,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:49:27.635626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.13726ms","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-05-05T06:49:27.63571Z","caller":"traceutil/trace.go:171","msg":"trace[284641212] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4745; }","duration":"326.228232ms","start":"2026-05-05T06:49:27.309426Z","end":"2026-05-05T06:49:27.635654Z","steps":["trace[284641212] 'agreement among raft nodes before linearized reading' (duration: 326.042988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.635822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:27.309416Z","time spent":"326.374475ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T06:49:27.638713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.207473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T06:49:27.638807Z","caller":"traceutil/trace.go:171","msg":"trace[1140911243] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4747; }","duration":"329.329816ms","start":"2026-05-05T06:49:27.309447Z","end":"2026-05-05T06:49:27.638777Z","steps":["trace[1140911243] 'agreement among raft nodes before linearized reading' (duration: 329.104681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.638856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:27.309426Z","time spent":"329.420189ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T06:49:27.639236Z","caller":"traceutil/trace.go:171","msg":"trace[888320498] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"328.526817ms","start":"2026-05-05T06:49:27.310698Z","end":"2026-05-05T06:49:27.639225Z","steps":["trace[888320498] 'process raft request' (duration: 326.117719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.639355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:27.310688Z","time spent":"328.609598ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:49:27.639557Z","caller":"traceutil/trace.go:171","msg":"trace[2033559548] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"327.985933ms","start":"2026-05-05T06:49:27.31156Z","end":"2026-05-05T06:49:27.639546Z","steps":["trace[2033559548] 'process raft request' (duration: 326.923268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.639619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:27.311549Z","time spent":"328.041075ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:49:33.401698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.266066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:49:33.401809Z","caller":"traceutil/trace.go:171","msg":"trace[182316068] linearizableReadLoop","detail":"{readStateIndex:4973; appliedIndex:4972; }","duration":"609.880849ms","start":"2026-05-05T06:49:32.791912Z","end":"2026-05-05T06:49:33.401793Z","steps":["trace[182316068] 'read index received' (duration: 38.399818ms)","trace[182316068] 'applied index is now lower than readState.Index' (duration: 571.479911ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:33.401873Z","caller":"traceutil/trace.go:171","msg":"trace[34287640] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"667.721194ms","start":"2026-05-05T06:49:32.73414Z","end":"2026-05-05T06:49:33.401861Z","steps":["trace[34287640] 'process raft request' (duration: 96.226022ms)","trace[34287640] 'compare' (duration: 571.129643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:33.401924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:32.734116Z","time spent":"667.786225ms","remote":"127.0.0.1:37170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:49:33.402229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.31564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-05-05T06:49:33.40227Z","caller":"traceutil/trace.go:171","msg":"trace[2108529552] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4790; }","duration":"610.395432ms","start":"2026-05-05T06:49:32.791863Z","end":"2026-05-05T06:49:33.402259Z","steps":["trace[2108529552] 'agreement among raft nodes before linearized reading' (duration: 610.241139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.402301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:32.791842Z","time spent":"610.452973ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12998,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-05T06:49:33.617143Z","caller":"traceutil/trace.go:171","msg":"trace[634164477] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"763.742152ms","start":"2026-05-05T06:49:32.853381Z","end":"2026-05-05T06:49:33.617124Z","steps":["trace[634164477] 'process raft request' (duration: 762.915262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.617394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:32.853358Z","time spent":"763.855354ms","remote":"127.0.0.1:44708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:49:33.650166Z","caller":"traceutil/trace.go:171","msg":"trace[1571563044] linearizableReadLoop","detail":"{readStateIndex:4976; appliedIndex:4973; }","duration":"248.306451ms","start":"2026-05-05T06:49:33.401834Z","end":"2026-05-05T06:49:33.650141Z","steps":["trace[1571563044] 'read index received' (duration: 214.484272ms)","trace[1571563044] 'applied index is now lower than readState.Index' (duration: 33.821259ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:33.650543Z","caller":"traceutil/trace.go:171","msg":"trace[597945897] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"580.61803ms","start":"2026-05-05T06:49:33.06991Z","end":"2026-05-05T06:49:33.650528Z","steps":["trace[597945897] 'process raft request' (duration: 580.003785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.650664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:33.069884Z","time spent":"580.708082ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:49:33.650838Z","caller":"traceutil/trace.go:171","msg":"trace[2013253027] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"550.971641ms","start":"2026-05-05T06:49:33.099857Z","end":"2026-05-05T06:49:33.650828Z","steps":["trace[2013253027] 'process raft request' (duration: 550.185543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.650898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:33.099842Z","time spent":"551.025643ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:49:33.65156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.147773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T06:49:33.65165Z","caller":"traceutil/trace.go:171","msg":"trace[991787089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4794; }","duration":"413.289487ms","start":"2026-05-05T06:49:33.238332Z","end":"2026-05-05T06:49:33.651622Z","steps":["trace[991787089] 'agreement among raft nodes before linearized reading' (duration: 413.040331ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.651127Z","caller":"traceutil/trace.go:171","msg":"trace[2026878651] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"236.745133ms","start":"2026-05-05T06:49:33.414372Z","end":"2026-05-05T06:49:33.651118Z","steps":["trace[2026878651] 'process raft request' (duration: 235.710698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.651699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:33.23831Z","time spent":"413.380029ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T06:49:33.651983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.359797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T06:49:33.652018Z","caller":"traceutil/trace.go:171","msg":"trace[1123014625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4794; }","duration":"555.431839ms","start":"2026-05-05T06:49:33.096577Z","end":"2026-05-05T06:49:33.652008Z","steps":["trace[1123014625] 'agreement among raft nodes before linearized reading' (duration: 555.337337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.652047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:33.096555Z","time spent":"555.48519ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:49:33.652197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.312465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-05T06:49:33.652245Z","caller":"traceutil/trace.go:171","msg":"trace[2055128201] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4794; }","duration":"774.362676ms","start":"2026-05-05T06:49:32.877868Z","end":"2026-05-05T06:49:33.652231Z","steps":["trace[2055128201] 'agreement among raft nodes before linearized reading' (duration: 774.220693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.652282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:32.877848Z","time spent":"774.426757ms","remote":"127.0.0.1:37246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-05-05T06:49:33.652474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.259212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6c55ccc94b-ndtmt.18ac98a1605ebfb8\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-05T06:49:33.652517Z","caller":"traceutil/trace.go:171","msg":"trace[1345038557] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6c55ccc94b-ndtmt.18ac98a1605ebfb8; range_end:; response_count:1; response_revision:4794; }","duration":"246.321374ms","start":"2026-05-05T06:49:33.406181Z","end":"2026-05-05T06:49:33.652502Z","steps":["trace[1345038557] 'agreement among raft nodes before linearized reading' (duration: 246.230991ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.775757Z","caller":"traceutil/trace.go:171","msg":"trace[547186271] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"118.396273ms","start":"2026-05-05T06:49:33.657323Z","end":"2026-05-05T06:49:33.775719Z","steps":["trace[547186271] 'process raft request' (duration: 118.121497ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.775792Z","caller":"traceutil/trace.go:171","msg":"trace[58222514] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"118.032083ms","start":"2026-05-05T06:49:33.65773Z","end":"2026-05-05T06:49:33.775762Z","steps":["trace[58222514] 'process raft request' (duration: 117.88053ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.776079Z","caller":"traceutil/trace.go:171","msg":"trace[671905699] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"118.233508ms","start":"2026-05-05T06:49:33.657822Z","end":"2026-05-05T06:49:33.776055Z","steps":["trace[671905699] 'process raft request' (duration: 117.846809ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.776355Z","caller":"traceutil/trace.go:171","msg":"trace[1048217504] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"118.402843ms","start":"2026-05-05T06:49:33.657935Z","end":"2026-05-05T06:49:33.776338Z","steps":["trace[1048217504] 'process raft request' (duration: 117.773418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.776592Z","caller":"traceutil/trace.go:171","msg":"trace[1052833948] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"118.141526ms","start":"2026-05-05T06:49:33.658437Z","end":"2026-05-05T06:49:33.776578Z","steps":["trace[1052833948] 'process raft request' (duration: 117.820439ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.77677Z","caller":"traceutil/trace.go:171","msg":"trace[1881948950] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"117.658645ms","start":"2026-05-05T06:49:33.659101Z","end":"2026-05-05T06:49:33.77676Z","steps":["trace[1881948950] 'process raft request' (duration: 117.421699ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.776869Z","caller":"traceutil/trace.go:171","msg":"trace[12099057] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4977; }","duration":"116.728473ms","start":"2026-05-05T06:49:33.660131Z","end":"2026-05-05T06:49:33.776859Z","steps":["trace[12099057] 'read index received' (duration: 40.567081ms)","trace[12099057] 'applied index is now lower than readState.Index' (duration: 76.160482ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:33.776932Z","caller":"traceutil/trace.go:171","msg":"trace[1023928220] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"117.152342ms","start":"2026-05-05T06:49:33.65977Z","end":"2026-05-05T06:49:33.776922Z","steps":["trace[1023928220] 'process raft request' (duration: 116.942378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.777152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.998909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-845de090-9b46-42d8-8705-cb3464d5bae9\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-05T06:49:33.777232Z","caller":"traceutil/trace.go:171","msg":"trace[623394909] range","detail":"{range_begin:/registry/persistentvolumes/pvc-845de090-9b46-42d8-8705-cb3464d5bae9; range_end:; response_count:1; response_revision:4802; }","duration":"117.108412ms","start":"2026-05-05T06:49:33.660101Z","end":"2026-05-05T06:49:33.77721Z","steps":["trace[623394909] 'agreement among raft nodes before linearized reading' (duration: 116.883897ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.841085Z","caller":"traceutil/trace.go:171","msg":"trace[911487160] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"136.224439ms","start":"2026-05-05T06:49:33.704838Z","end":"2026-05-05T06:49:33.841063Z","steps":["trace[911487160] 'process raft request' (duration: 136.006324ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.841131Z","caller":"traceutil/trace.go:171","msg":"trace[1744154231] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"136.407683ms","start":"2026-05-05T06:49:33.704703Z","end":"2026-05-05T06:49:33.841111Z","steps":["trace[1744154231] 'process raft request' (duration: 136.008744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.841542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.369891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T06:49:33.841584Z","caller":"traceutil/trace.go:171","msg":"trace[1152448287] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4806; }","duration":"156.444383ms","start":"2026-05-05T06:49:33.685127Z","end":"2026-05-05T06:49:33.841572Z","steps":["trace[1152448287] 'agreement among raft nodes before linearized reading' (duration: 156.275249ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.841683Z","caller":"traceutil/trace.go:171","msg":"trace[369830889] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"136.437823ms","start":"2026-05-05T06:49:33.705227Z","end":"2026-05-05T06:49:33.841664Z","steps":["trace[369830889] 'process raft request' (duration: 135.698766ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.841776Z","caller":"traceutil/trace.go:171","msg":"trace[813668975] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"132.701875ms","start":"2026-05-05T06:49:33.709063Z","end":"2026-05-05T06:49:33.841765Z","steps":["trace[813668975] 'process raft request' (duration: 131.910316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.841915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.366729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-05T06:49:33.842019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.251493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123189"} {"level":"warn","ts":"2026-05-05T06:49:33.842013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.916268ms","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-05-05T06:49:33.842074Z","caller":"traceutil/trace.go:171","msg":"trace[2128930743] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4806; }","duration":"137.332976ms","start":"2026-05-05T06:49:33.704731Z","end":"2026-05-05T06:49:33.842064Z","steps":["trace[2128930743] 'agreement among raft nodes before linearized reading' (duration: 137.180452ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.842101Z","caller":"traceutil/trace.go:171","msg":"trace[470072013] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4806; }","duration":"158.050841ms","start":"2026-05-05T06:49:33.684028Z","end":"2026-05-05T06:49:33.842079Z","steps":["trace[470072013] 'agreement among raft nodes before linearized reading' (duration: 157.782725ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.842175Z","caller":"traceutil/trace.go:171","msg":"trace[498408568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4806; }","duration":"161.555554ms","start":"2026-05-05T06:49:33.680483Z","end":"2026-05-05T06:49:33.842038Z","steps":["trace[498408568] 'agreement among raft nodes before linearized reading' (duration: 160.97013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:33.842353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.50318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T06:49:33.842394Z","caller":"traceutil/trace.go:171","msg":"trace[1912750930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4806; }","duration":"160.564532ms","start":"2026-05-05T06:49:33.681819Z","end":"2026-05-05T06:49:33.842383Z","steps":["trace[1912750930] 'agreement among raft nodes before linearized reading' (duration: 160.46592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:33.969237Z","caller":"traceutil/trace.go:171","msg":"trace[2059816368] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4989; }","duration":"128.176877ms","start":"2026-05-05T06:49:33.841033Z","end":"2026-05-05T06:49:33.96921Z","steps":["trace[2059816368] 'read index received' (duration: 50.190751ms)","trace[2059816368] 'applied index is now lower than readState.Index' (duration: 77.985066ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:33.969281Z","caller":"traceutil/trace.go:171","msg":"trace[1726821718] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"189.488604ms","start":"2026-05-05T06:49:33.779752Z","end":"2026-05-05T06:49:33.96924Z","steps":["trace[1726821718] 'process raft request' (duration: 111.391075ms)","trace[1726821718] 'compare' (duration: 77.925915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:33.969684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.137175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16232"} {"level":"info","ts":"2026-05-05T06:49:33.969762Z","caller":"traceutil/trace.go:171","msg":"trace[1058994651] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4807; }","duration":"171.257257ms","start":"2026-05-05T06:49:33.798486Z","end":"2026-05-05T06:49:33.969744Z","steps":["trace[1058994651] 'agreement among raft nodes before linearized reading' (duration: 170.847277ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.010935Z","caller":"traceutil/trace.go:171","msg":"trace[1554866734] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"163.550843ms","start":"2026-05-05T06:49:33.847364Z","end":"2026-05-05T06:49:34.010915Z","steps":["trace[1554866734] 'process raft request' (duration: 163.366969ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.01095Z","caller":"traceutil/trace.go:171","msg":"trace[1798142338] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"165.159001ms","start":"2026-05-05T06:49:33.845761Z","end":"2026-05-05T06:49:34.01092Z","steps":["trace[1798142338] 'process raft request' (duration: 164.808203ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.010979Z","caller":"traceutil/trace.go:171","msg":"trace[254586502] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"163.298928ms","start":"2026-05-05T06:49:33.847646Z","end":"2026-05-05T06:49:34.010945Z","steps":["trace[254586502] 'process raft request' (duration: 163.167095ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.011358Z","caller":"traceutil/trace.go:171","msg":"trace[386511074] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"163.524972ms","start":"2026-05-05T06:49:33.847712Z","end":"2026-05-05T06:49:34.011237Z","steps":["trace[386511074] 'process raft request' (duration: 163.133833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:34.011448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.012093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-05T06:49:34.011581Z","caller":"traceutil/trace.go:171","msg":"trace[1627205286] 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:4812; }","duration":"128.150856ms","start":"2026-05-05T06:49:33.883373Z","end":"2026-05-05T06:49:34.011524Z","steps":["trace[1627205286] 'agreement among raft nodes before linearized reading' (duration: 127.946071ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.011372Z","caller":"traceutil/trace.go:171","msg":"trace[525141065] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"163.736107ms","start":"2026-05-05T06:49:33.84757Z","end":"2026-05-05T06:49:34.011306Z","steps":["trace[525141065] 'process raft request' (duration: 163.208575ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.155884Z","caller":"traceutil/trace.go:171","msg":"trace[36836317] linearizableReadLoop","detail":"{readStateIndex:4996; appliedIndex:4995; }","duration":"144.968818ms","start":"2026-05-05T06:49:34.010885Z","end":"2026-05-05T06:49:34.155854Z","steps":["trace[36836317] 'read index received' (duration: 144.749043ms)","trace[36836317] 'applied index is now lower than readState.Index' (duration: 218.445µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:34.155941Z","caller":"traceutil/trace.go:171","msg":"trace[1467229542] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"171.967304ms","start":"2026-05-05T06:49:33.983942Z","end":"2026-05-05T06:49:34.155909Z","steps":["trace[1467229542] 'process raft request' (duration: 171.740629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:34.156245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.819515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6c55ccc94b-ndtmt.18ac98a1605ebfb8\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-05T06:49:34.156325Z","caller":"traceutil/trace.go:171","msg":"trace[2131297813] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6c55ccc94b-ndtmt.18ac98a1605ebfb8; range_end:; response_count:1; response_revision:4813; }","duration":"182.959068ms","start":"2026-05-05T06:49:33.973344Z","end":"2026-05-05T06:49:34.156303Z","steps":["trace[2131297813] 'agreement among raft nodes before linearized reading' (duration: 182.66688ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.165988Z","caller":"traceutil/trace.go:171","msg":"trace[61011087] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"153.164855ms","start":"2026-05-05T06:49:34.012796Z","end":"2026-05-05T06:49:34.165961Z","steps":["trace[61011087] 'process raft request' (duration: 153.030572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:34.166077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.621267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2e49203e-fd15-4552-890a-c337e2f9c560\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-05T06:49:34.166126Z","caller":"traceutil/trace.go:171","msg":"trace[990189358] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2e49203e-fd15-4552-890a-c337e2f9c560; range_end:; response_count:1; response_revision:4814; }","duration":"151.70326ms","start":"2026-05-05T06:49:34.01441Z","end":"2026-05-05T06:49:34.166114Z","steps":["trace[990189358] 'agreement among raft nodes before linearized reading' (duration: 151.547016ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.272645Z","caller":"traceutil/trace.go:171","msg":"trace[1603018725] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"109.840948ms","start":"2026-05-05T06:49:34.162773Z","end":"2026-05-05T06:49:34.272614Z","steps":["trace[1603018725] 'process raft request' (duration: 109.709925ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.272608Z","caller":"traceutil/trace.go:171","msg":"trace[2028707600] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"112.765828ms","start":"2026-05-05T06:49:34.159815Z","end":"2026-05-05T06:49:34.272581Z","steps":["trace[2028707600] 'process raft request' (duration: 112.501672ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.272714Z","caller":"traceutil/trace.go:171","msg":"trace[259645063] linearizableReadLoop","detail":"{readStateIndex:5002; appliedIndex:4997; }","duration":"106.787884ms","start":"2026-05-05T06:49:34.165902Z","end":"2026-05-05T06:49:34.27269Z","steps":["trace[259645063] 'read index received' (duration: 106.402235ms)","trace[259645063] 'applied index is now lower than readState.Index' (duration: 384.579µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:34.272833Z","caller":"traceutil/trace.go:171","msg":"trace[1664355374] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"109.171172ms","start":"2026-05-05T06:49:34.16365Z","end":"2026-05-05T06:49:34.272821Z","steps":["trace[1664355374] 'process raft request' (duration: 108.954096ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.27294Z","caller":"traceutil/trace.go:171","msg":"trace[853525582] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"109.442568ms","start":"2026-05-05T06:49:34.163477Z","end":"2026-05-05T06:49:34.27292Z","steps":["trace[853525582] 'process raft request' (duration: 109.086449ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.27306Z","caller":"traceutil/trace.go:171","msg":"trace[1668736116] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"109.187772ms","start":"2026-05-05T06:49:34.163862Z","end":"2026-05-05T06:49:34.27305Z","steps":["trace[1668736116] 'process raft request' (duration: 108.788573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:34.273087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.156329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:49:34.273132Z","caller":"traceutil/trace.go:171","msg":"trace[777127299] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4819; }","duration":"111.239711ms","start":"2026-05-05T06:49:34.161877Z","end":"2026-05-05T06:49:34.273117Z","steps":["trace[777127299] 'agreement among raft nodes before linearized reading' (duration: 111.146438ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.297632Z","caller":"traceutil/trace.go:171","msg":"trace[1563773720] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"128.639397ms","start":"2026-05-05T06:49:34.168954Z","end":"2026-05-05T06:49:34.297594Z","steps":["trace[1563773720] 'process raft request' (duration: 128.537445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:34.297773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.637118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-05T06:49:34.297837Z","caller":"traceutil/trace.go:171","msg":"trace[1194020162] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4823; }","duration":"128.71605ms","start":"2026-05-05T06:49:34.169106Z","end":"2026-05-05T06:49:34.297822Z","steps":["trace[1194020162] 'agreement among raft nodes before linearized reading' (duration: 128.517645ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.298015Z","caller":"traceutil/trace.go:171","msg":"trace[1504959971] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"128.855273ms","start":"2026-05-05T06:49:34.169149Z","end":"2026-05-05T06:49:34.298004Z","steps":["trace[1504959971] 'process raft request' (duration: 128.379312ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.298051Z","caller":"traceutil/trace.go:171","msg":"trace[1832007219] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"128.175167ms","start":"2026-05-05T06:49:34.169855Z","end":"2026-05-05T06:49:34.29803Z","steps":["trace[1832007219] 'process raft request' (duration: 127.715406ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.298376Z","caller":"traceutil/trace.go:171","msg":"trace[1960947275] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"128.74751ms","start":"2026-05-05T06:49:34.168825Z","end":"2026-05-05T06:49:34.297573Z","steps":["trace[1960947275] 'process raft request' (duration: 128.592736ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:34.647365Z","caller":"traceutil/trace.go:171","msg":"trace[576353917] linearizableReadLoop","detail":"{readStateIndex:5008; appliedIndex:5007; }","duration":"310.403566ms","start":"2026-05-05T06:49:34.336939Z","end":"2026-05-05T06:49:34.647342Z","steps":["trace[576353917] 'read index received' (duration: 310.241292ms)","trace[576353917] 'applied index is now lower than readState.Index' (duration: 161.184µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:34.647705Z","caller":"traceutil/trace.go:171","msg":"trace[820331283] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"342.562856ms","start":"2026-05-05T06:49:34.304853Z","end":"2026-05-05T06:49:34.647416Z","steps":["trace[820331283] 'process raft request' (duration: 342.351041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:34.647697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.527496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T06:49:34.64798Z","caller":"traceutil/trace.go:171","msg":"trace[1801299901] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:4825; }","duration":"192.849844ms","start":"2026-05-05T06:49:34.455107Z","end":"2026-05-05T06:49:34.647957Z","steps":["trace[1801299901] 'agreement among raft nodes before linearized reading' (duration: 192.382402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:34.648095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:34.304843Z","time spent":"342.987716ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1147,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:49:34.647703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.54331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-05T06:49:34.64852Z","caller":"traceutil/trace.go:171","msg":"trace[1633103323] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4825; }","duration":"344.312497ms","start":"2026-05-05T06:49:34.304103Z","end":"2026-05-05T06:49:34.648415Z","steps":["trace[1633103323] 'agreement among raft nodes before linearized reading' (duration: 343.345845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:34.648657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:34.304097Z","time spent":"344.528822ms","remote":"127.0.0.1:37486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-05-05T06:49:35.642699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.576533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:49:35.642782Z","caller":"traceutil/trace.go:171","msg":"trace[483881205] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:4871; }","duration":"142.717536ms","start":"2026-05-05T06:49:35.500047Z","end":"2026-05-05T06:49:35.642764Z","steps":["trace[483881205] 'count revisions from in-memory index tree' (duration: 142.43099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:38.584433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.887517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:49:38.585148Z","caller":"traceutil/trace.go:171","msg":"trace[774613822] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4892; }","duration":"234.653626ms","start":"2026-05-05T06:49:38.350474Z","end":"2026-05-05T06:49:38.585127Z","steps":["trace[774613822] 'count revisions from in-memory index tree' (duration: 233.802695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:38.584611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.935141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-05T06:49:38.584739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.739427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-05-05T06:49:38.585889Z","caller":"traceutil/trace.go:171","msg":"trace[794974321] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4892; }","duration":"557.872945ms","start":"2026-05-05T06:49:38.02799Z","end":"2026-05-05T06:49:38.585863Z","steps":["trace[794974321] 'range keys from in-memory index tree' (duration: 556.386629ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:38.585818Z","caller":"traceutil/trace.go:171","msg":"trace[2034003537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4892; }","duration":"558.057349ms","start":"2026-05-05T06:49:38.027599Z","end":"2026-05-05T06:49:38.585656Z","steps":["trace[2034003537] 'range keys from in-memory index tree' (duration: 556.690845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:38.584749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.387356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:49:38.584974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.743401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-05T06:49:38.58499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.939995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-05T06:49:38.585978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:38.027978Z","time spent":"557.983827ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T06:49:38.586066Z","caller":"traceutil/trace.go:171","msg":"trace[728896425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4892; }","duration":"561.051992ms","start":"2026-05-05T06:49:38.025006Z","end":"2026-05-05T06:49:38.586058Z","steps":["trace[728896425] 'range keys from in-memory index tree' (duration: 559.73829ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:38.586038Z","caller":"traceutil/trace.go:171","msg":"trace[658848352] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4892; }","duration":"558.846909ms","start":"2026-05-05T06:49:38.027181Z","end":"2026-05-05T06:49:38.586028Z","steps":["trace[658848352] 'range keys from in-memory index tree' (duration: 557.594418ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:38.586122Z","caller":"traceutil/trace.go:171","msg":"trace[871037723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4892; }","duration":"364.723758ms","start":"2026-05-05T06:49:38.221308Z","end":"2026-05-05T06:49:38.586032Z","steps":["trace[871037723] 'range keys from in-memory index tree' (duration: 363.275783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:38.586301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:38.221286Z","time spent":"364.993655ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:49:38.586127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:38.024987Z","time spent":"561.130584ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T06:49:38.586164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:38.027157Z","time spent":"558.977442ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T06:49:38.586776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:38.027574Z","time spent":"559.146565ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:49:43.018578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.026093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-b9f6q\" ","response":"range_response_count:1 size:9901"} {"level":"info","ts":"2026-05-05T06:49:43.018652Z","caller":"traceutil/trace.go:171","msg":"trace[1367263916] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-b9f6q; range_end:; response_count:1; response_revision:4994; }","duration":"114.136615ms","start":"2026-05-05T06:49:42.904498Z","end":"2026-05-05T06:49:43.018634Z","steps":["trace[1367263916] 'range keys from in-memory index tree' (duration: 113.853018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.01874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.93515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-05T06:49:43.018832Z","caller":"traceutil/trace.go:171","msg":"trace[1606992927] 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:4994; }","duration":"105.057313ms","start":"2026-05-05T06:49:42.913753Z","end":"2026-05-05T06:49:43.01881Z","steps":["trace[1606992927] 'range keys from in-memory index tree' (duration: 104.793897ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:46.679132Z","caller":"traceutil/trace.go:171","msg":"trace[959015914] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"183.94599ms","start":"2026-05-05T06:49:46.495135Z","end":"2026-05-05T06:49:46.679081Z","steps":["trace[959015914] 'process raft request' (duration: 183.757515ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:46.840618Z","caller":"traceutil/trace.go:171","msg":"trace[972146989] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"155.755454ms","start":"2026-05-05T06:49:46.684835Z","end":"2026-05-05T06:49:46.840591Z","steps":["trace[972146989] 'process raft request' (duration: 155.668842ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:46.841007Z","caller":"traceutil/trace.go:171","msg":"trace[1831888008] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"156.381571ms","start":"2026-05-05T06:49:46.684612Z","end":"2026-05-05T06:49:46.840993Z","steps":["trace[1831888008] 'process raft request' (duration: 155.761445ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:46.841106Z","caller":"traceutil/trace.go:171","msg":"trace[2138372143] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"156.024572ms","start":"2026-05-05T06:49:46.685018Z","end":"2026-05-05T06:49:46.841042Z","steps":["trace[2138372143] 'process raft request' (duration: 155.523579ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:13.010485Z","caller":"traceutil/trace.go:171","msg":"trace[232613816] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"143.631513ms","start":"2026-05-05T06:50:12.866828Z","end":"2026-05-05T06:50:13.01046Z","steps":["trace[232613816] 'process raft request' (duration: 143.394918ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:21.295776Z","caller":"traceutil/trace.go:171","msg":"trace[60169301] linearizableReadLoop","detail":"{readStateIndex:5544; appliedIndex:5542; }","duration":"237.083778ms","start":"2026-05-05T06:50:21.058669Z","end":"2026-05-05T06:50:21.295752Z","steps":["trace[60169301] 'read index received' (duration: 978.535µs)","trace[60169301] 'applied index is now lower than readState.Index' (duration: 236.104153ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:21.295835Z","caller":"traceutil/trace.go:171","msg":"trace[1229967745] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"241.095844ms","start":"2026-05-05T06:50:21.05471Z","end":"2026-05-05T06:50:21.295806Z","steps":["trace[1229967745] 'process raft request' (duration: 214.618422ms)","trace[1229967745] 'compare' (duration: 26.084333ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:21.295898Z","caller":"traceutil/trace.go:171","msg":"trace[1710245982] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"240.731526ms","start":"2026-05-05T06:50:21.055145Z","end":"2026-05-05T06:50:21.295877Z","steps":["trace[1710245982] 'process raft request' (duration: 240.520171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:21.296014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.324063ms","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-05-05T06:50:21.296059Z","caller":"traceutil/trace.go:171","msg":"trace[597095180] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5346; }","duration":"237.401945ms","start":"2026-05-05T06:50:21.058643Z","end":"2026-05-05T06:50:21.296045Z","steps":["trace[597095180] 'agreement among raft nodes before linearized reading' (duration: 237.250961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:21.296104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.826993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T06:50:21.296149Z","caller":"traceutil/trace.go:171","msg":"trace[1870221316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5346; }","duration":"234.897714ms","start":"2026-05-05T06:50:21.061239Z","end":"2026-05-05T06:50:21.296137Z","steps":["trace[1870221316] 'agreement among raft nodes before linearized reading' (duration: 234.767231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:21.297011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.543487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2350222"} {"level":"info","ts":"2026-05-05T06:50:21.297054Z","caller":"traceutil/trace.go:171","msg":"trace[924929440] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5346; }","duration":"114.618389ms","start":"2026-05-05T06:50:21.182424Z","end":"2026-05-05T06:50:21.297043Z","steps":["trace[924929440] 'agreement among raft nodes before linearized reading' (duration: 113.798279ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:29.211962Z","caller":"traceutil/trace.go:171","msg":"trace[817217771] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"118.132623ms","start":"2026-05-05T06:50:29.093802Z","end":"2026-05-05T06:50:29.211934Z","steps":["trace[817217771] 'process raft request' (duration: 117.944938ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:33.341327Z","caller":"traceutil/trace.go:171","msg":"trace[1770153818] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"112.078184ms","start":"2026-05-05T06:50:33.229212Z","end":"2026-05-05T06:50:33.34129Z","steps":["trace[1770153818] 'process raft request' (duration: 111.870699ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:34.14732Z","caller":"traceutil/trace.go:171","msg":"trace[1672430143] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"182.216333ms","start":"2026-05-05T06:50:33.965026Z","end":"2026-05-05T06:50:34.147243Z","steps":["trace[1672430143] 'process raft request' (duration: 163.533682ms)","trace[1672430143] 'compare' (duration: 18.321623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:46.045905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118163538895213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:50:46.546366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118163538895213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:50:47.047065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118163538895213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:50:47.547876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6890118163538895213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T06:50:47.645332Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.244591828s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T06:50:47.68631Z","caller":"traceutil/trace.go:171","msg":"trace[1569426180] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"2.285662582s","start":"2026-05-05T06:50:45.400617Z","end":"2026-05-05T06:50:47.686279Z","steps":["trace[1569426180] 'process raft request' (duration: 2.244874366s)","trace[1569426180] 'compare' (duration: 39.138097ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:47.686457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.400601Z","time spent":"2.285783124s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:47.689135Z","caller":"traceutil/trace.go:171","msg":"trace[812378024] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"2.261637219s","start":"2026-05-05T06:50:45.42747Z","end":"2026-05-05T06:50:47.689108Z","steps":["trace[812378024] 'process raft request' (duration: 2.261400984s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.689267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.427449Z","time spent":"2.261752233s","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":72216,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:47.689354Z","caller":"traceutil/trace.go:171","msg":"trace[475148890] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"1.646303355s","start":"2026-05-05T06:50:46.043037Z","end":"2026-05-05T06:50:47.68934Z","steps":["trace[475148890] 'process raft request' (duration: 1.646002018s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.689426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:46.043003Z","time spent":"1.646382798s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:47.689617Z","caller":"traceutil/trace.go:171","msg":"trace[891859808] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"1.480472772s","start":"2026-05-05T06:50:46.209132Z","end":"2026-05-05T06:50:47.689605Z","steps":["trace[891859808] 'process raft request' (duration: 1.480150034s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:47.689635Z","caller":"traceutil/trace.go:171","msg":"trace[1623317066] linearizableReadLoop","detail":"{readStateIndex:5742; appliedIndex:5737; }","duration":"2.14470414s","start":"2026-05-05T06:50:45.544915Z","end":"2026-05-05T06:50:47.68962Z","steps":["trace[1623317066] 'read index received' (duration: 2.100661094s)","trace[1623317066] 'applied index is now lower than readState.Index' (duration: 44.042096ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:50:47.689683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:46.209091Z","time spent":"1.480556624s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:50:47.689723Z","caller":"traceutil/trace.go:171","msg":"trace[28973296] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"316.763886ms","start":"2026-05-05T06:50:47.372917Z","end":"2026-05-05T06:50:47.689681Z","steps":["trace[28973296] 'process raft request' (duration: 316.574422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.689806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.144897975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T06:50:47.689851Z","caller":"traceutil/trace.go:171","msg":"trace[589668973] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5539; }","duration":"2.145016118s","start":"2026-05-05T06:50:45.544822Z","end":"2026-05-05T06:50:47.689838Z","steps":["trace[589668973] 'agreement among raft nodes before linearized reading' (duration: 2.144887595s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.689881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.544792Z","time spent":"2.145082209s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T06:50:47.689908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:47.37288Z","time spent":"316.93379ms","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:50:47.689945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.118574298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T06:50:47.689978Z","caller":"traceutil/trace.go:171","msg":"trace[844413326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5539; }","duration":"2.118602289s","start":"2026-05-05T06:50:45.571364Z","end":"2026-05-05T06:50:47.689967Z","steps":["trace[844413326] 'agreement among raft nodes before linearized reading' (duration: 2.118467496s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.689804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.142330293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:50:47.690003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.571329Z","time spent":"2.118668801s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T06:50:47.690016Z","caller":"traceutil/trace.go:171","msg":"trace[655687472] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5539; }","duration":"2.142617749s","start":"2026-05-05T06:50:45.547385Z","end":"2026-05-05T06:50:47.690003Z","steps":["trace[655687472] 'agreement among raft nodes before linearized reading' (duration: 2.142374513s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.690065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.547358Z","time spent":"2.142695871s","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-05-05T06:50:47.69009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.219599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:50:47.68998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434560131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T06:50:47.690179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.138787037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:1 size:6094"} {"level":"warn","ts":"2026-05-05T06:50:47.690173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451630484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T06:50:47.690193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.264933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:47.690207Z","caller":"traceutil/trace.go:171","msg":"trace[881115935] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:1; response_revision:5539; }","duration":"2.138831568s","start":"2026-05-05T06:50:45.551366Z","end":"2026-05-05T06:50:47.690198Z","steps":["trace[881115935] 'agreement among raft nodes before linearized reading' (duration: 2.138768217s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:47.69021Z","caller":"traceutil/trace.go:171","msg":"trace[1289615468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5539; }","duration":"1.434895059s","start":"2026-05-05T06:50:46.255303Z","end":"2026-05-05T06:50:47.690198Z","steps":["trace[1289615468] 'agreement among raft nodes before linearized reading' (duration: 1.43448604s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:47.690215Z","caller":"traceutil/trace.go:171","msg":"trace[936113627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5539; }","duration":"1.451723077s","start":"2026-05-05T06:50:46.238482Z","end":"2026-05-05T06:50:47.690205Z","steps":["trace[936113627] 'agreement among raft nodes before linearized reading' (duration: 1.451556223s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.690231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.551356Z","time spent":"2.138870959s","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6117,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-05-05T06:50:47.690222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.071108819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:50:47.690246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:46.238456Z","time spent":"1.451783177s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:50:47.69027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.953522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:47.690231Z","caller":"traceutil/trace.go:171","msg":"trace[1447554685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5539; }","duration":"487.339105ms","start":"2026-05-05T06:50:47.202882Z","end":"2026-05-05T06:50:47.690221Z","steps":["trace[1447554685] 'agreement among raft nodes before linearized reading' (duration: 487.260303ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:47.690301Z","caller":"traceutil/trace.go:171","msg":"trace[312538107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5539; }","duration":"467.044074ms","start":"2026-05-05T06:50:47.223249Z","end":"2026-05-05T06:50:47.690293Z","steps":["trace[312538107] 'agreement among raft nodes before linearized reading' (duration: 466.998863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.690301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259707099s","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:7"} {"level":"warn","ts":"2026-05-05T06:50:47.690307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307477195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:50:47.690307Z","caller":"traceutil/trace.go:171","msg":"trace[513004211] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5539; }","duration":"2.071181061s","start":"2026-05-05T06:50:45.619078Z","end":"2026-05-05T06:50:47.690259Z","steps":["trace[513004211] 'agreement among raft nodes before linearized reading' (duration: 2.071054908s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.690332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:47.223225Z","time spent":"467.100605ms","remote":"127.0.0.1:37070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:50:47.690247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:46.255226Z","time spent":"1.435011051s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T06:50:47.690348Z","caller":"traceutil/trace.go:171","msg":"trace[2062131352] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:5539; }","duration":"1.307580608s","start":"2026-05-05T06:50:46.382756Z","end":"2026-05-05T06:50:47.690337Z","steps":["trace[2062131352] 'agreement among raft nodes before linearized reading' (duration: 1.307519186s)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:47.690343Z","caller":"traceutil/trace.go:171","msg":"trace[1161991801] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5539; }","duration":"1.259821122s","start":"2026-05-05T06:50:46.430511Z","end":"2026-05-05T06:50:47.690332Z","steps":["trace[1161991801] 'agreement among raft nodes before linearized reading' (duration: 1.259685768s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.690377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:46.382722Z","time spent":"1.307647939s","remote":"127.0.0.1:44730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:50:47.690389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.619064Z","time spent":"2.071310404s","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:50:47.690394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:46.430469Z","time spent":"1.259912294s","remote":"127.0.0.1:55886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-05-05T06:50:47.690167Z","caller":"traceutil/trace.go:171","msg":"trace[1386865460] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5539; }","duration":"189.321562ms","start":"2026-05-05T06:50:47.500816Z","end":"2026-05-05T06:50:47.690138Z","steps":["trace[1386865460] 'agreement among raft nodes before linearized reading' (duration: 189.22644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.690305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:47.202865Z","time spent":"487.434117ms","remote":"127.0.0.1:37084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:50:47.690117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.124915251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T06:50:47.690625Z","caller":"traceutil/trace.go:171","msg":"trace[987937108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5539; }","duration":"2.125460274s","start":"2026-05-05T06:50:45.565154Z","end":"2026-05-05T06:50:47.690614Z","steps":["trace[987937108] 'agreement among raft nodes before linearized reading' (duration: 2.124917791s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.690662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.565134Z","time spent":"2.125517305s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:50:47.690053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.117598814s","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-05-05T06:50:47.690812Z","caller":"traceutil/trace.go:171","msg":"trace[981732696] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5539; }","duration":"2.118441674s","start":"2026-05-05T06:50:45.572355Z","end":"2026-05-05T06:50:47.690797Z","steps":["trace[981732696] 'agreement among raft nodes before linearized reading' (duration: 2.11743782s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.690869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:50:45.572344Z","time spent":"2.118511405s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-05T06:50:47.884811Z","caller":"traceutil/trace.go:171","msg":"trace[76709081] linearizableReadLoop","detail":"{readStateIndex:5755; appliedIndex:5753; }","duration":"133.219974ms","start":"2026-05-05T06:50:47.751565Z","end":"2026-05-05T06:50:47.884785Z","steps":["trace[76709081] 'read index received' (duration: 94.700772ms)","trace[76709081] 'applied index is now lower than readState.Index' (duration: 38.517952ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:47.885006Z","caller":"traceutil/trace.go:171","msg":"trace[128036044] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"138.88001ms","start":"2026-05-05T06:50:47.746063Z","end":"2026-05-05T06:50:47.884943Z","steps":["trace[128036044] 'process raft request' (duration: 138.659145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:50:47.885062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.472909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-db-sync-rkqqn\" ","response":"range_response_count:1 size:8553"} {"level":"info","ts":"2026-05-05T06:50:47.885109Z","caller":"traceutil/trace.go:171","msg":"trace[626157427] range","detail":"{range_begin:/registry/pods/openstack/keystone-db-sync-rkqqn; range_end:; response_count:1; response_revision:5551; }","duration":"133.566532ms","start":"2026-05-05T06:50:47.751531Z","end":"2026-05-05T06:50:47.885098Z","steps":["trace[626157427] 'agreement among raft nodes before linearized reading' (duration: 133.354567ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:50:47.885079Z","caller":"traceutil/trace.go:171","msg":"trace[58021711] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"161.536899ms","start":"2026-05-05T06:50:47.723501Z","end":"2026-05-05T06:50:47.885038Z","steps":["trace[58021711] 'process raft request' (duration: 122.818413ms)","trace[58021711] 'compare' (duration: 38.253195ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:50:51.54246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2919} {"level":"info","ts":"2026-05-05T06:50:51.616345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2919,"took":"72.694439ms","hash":545311856,"current-db-size-bytes":54333440,"current-db-size":"54 MB","current-db-size-in-use-bytes":29593600,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T06:50:51.616404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":545311856,"revision":2919,"compact-revision":-1} {"level":"info","ts":"2026-05-05T06:53:13.00106Z","caller":"traceutil/trace.go:171","msg":"trace[1587625453] linearizableReadLoop","detail":"{readStateIndex:6784; appliedIndex:6783; }","duration":"247.523224ms","start":"2026-05-05T06:53:12.753511Z","end":"2026-05-05T06:53:13.001034Z","steps":["trace[1587625453] 'read index received' (duration: 246.430759ms)","trace[1587625453] 'applied index is now lower than readState.Index' (duration: 1.091245ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:53:13.001419Z","caller":"traceutil/trace.go:171","msg":"trace[1063652603] transaction","detail":"{read_only:false; response_revision:6545; number_of_response:1; }","duration":"251.09829ms","start":"2026-05-05T06:53:12.750304Z","end":"2026-05-05T06:53:13.001403Z","steps":["trace[1063652603] 'process raft request' (duration: 249.690747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:53:13.001629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.212047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T06:53:13.001687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.299189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T06:53:13.001716Z","caller":"traceutil/trace.go:171","msg":"trace[2119828620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6545; }","duration":"208.34545ms","start":"2026-05-05T06:53:12.793353Z","end":"2026-05-05T06:53:13.001698Z","steps":["trace[2119828620] 'agreement among raft nodes before linearized reading' (duration: 208.125095ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:13.001758Z","caller":"traceutil/trace.go:171","msg":"trace[196428637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6545; }","duration":"191.391912ms","start":"2026-05-05T06:53:12.810348Z","end":"2026-05-05T06:53:13.00174Z","steps":["trace[196428637] 'agreement among raft nodes before linearized reading' (duration: 191.210208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:53:13.001679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.162619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-05T06:53:13.001869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.421902ms","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-05-05T06:53:13.001914Z","caller":"traceutil/trace.go:171","msg":"trace[160873665] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6545; }","duration":"208.508664ms","start":"2026-05-05T06:53:12.793389Z","end":"2026-05-05T06:53:13.001897Z","steps":["trace[160873665] 'agreement among raft nodes before linearized reading' (duration: 208.365031ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:13.00191Z","caller":"traceutil/trace.go:171","msg":"trace[59614707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6545; }","duration":"248.398435ms","start":"2026-05-05T06:53:12.753489Z","end":"2026-05-05T06:53:13.001887Z","steps":["trace[59614707] 'agreement among raft nodes before linearized reading' (duration: 247.979855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:53:13.001761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.541854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-05T06:53:13.001971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.606319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T06:53:13.002068Z","caller":"traceutil/trace.go:171","msg":"trace[1435681285] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6545; }","duration":"184.759552ms","start":"2026-05-05T06:53:12.817287Z","end":"2026-05-05T06:53:13.002046Z","steps":["trace[1435681285] 'agreement among raft nodes before linearized reading' (duration: 184.506576ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:13.002059Z","caller":"traceutil/trace.go:171","msg":"trace[1593231928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6545; }","duration":"193.854051ms","start":"2026-05-05T06:53:12.808181Z","end":"2026-05-05T06:53:13.002035Z","steps":["trace[1593231928] 'agreement among raft nodes before linearized reading' (duration: 193.464442ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:15.25235Z","caller":"traceutil/trace.go:171","msg":"trace[1753966447] transaction","detail":"{read_only:false; response_revision:6557; number_of_response:1; }","duration":"164.385621ms","start":"2026-05-05T06:53:15.087941Z","end":"2026-05-05T06:53:15.252326Z","steps":["trace[1753966447] 'process raft request' (duration: 98.194045ms)","trace[1753966447] 'compare' (duration: 65.851078ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:53:15.252579Z","caller":"traceutil/trace.go:171","msg":"trace[1184801211] transaction","detail":"{read_only:false; response_revision:6559; number_of_response:1; }","duration":"164.296289ms","start":"2026-05-05T06:53:15.088264Z","end":"2026-05-05T06:53:15.25256Z","steps":["trace[1184801211] 'process raft request' (duration: 164.054423ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:15.252386Z","caller":"traceutil/trace.go:171","msg":"trace[1215113892] transaction","detail":"{read_only:false; response_revision:6558; number_of_response:1; }","duration":"164.38255ms","start":"2026-05-05T06:53:15.087978Z","end":"2026-05-05T06:53:15.252361Z","steps":["trace[1215113892] 'process raft request' (duration: 164.234467ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:15.252636Z","caller":"traceutil/trace.go:171","msg":"trace[1602493037] transaction","detail":"{read_only:false; response_revision:6562; number_of_response:1; }","duration":"163.309615ms","start":"2026-05-05T06:53:15.089296Z","end":"2026-05-05T06:53:15.252606Z","steps":["trace[1602493037] 'process raft request' (duration: 163.248204ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:15.252744Z","caller":"traceutil/trace.go:171","msg":"trace[260378116] transaction","detail":"{read_only:false; response_revision:6560; number_of_response:1; }","duration":"164.201486ms","start":"2026-05-05T06:53:15.088534Z","end":"2026-05-05T06:53:15.252735Z","steps":["trace[260378116] 'process raft request' (duration: 163.908279ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:15.252756Z","caller":"traceutil/trace.go:171","msg":"trace[95958315] transaction","detail":"{read_only:false; response_revision:6561; number_of_response:1; }","duration":"163.574382ms","start":"2026-05-05T06:53:15.089169Z","end":"2026-05-05T06:53:15.252744Z","steps":["trace[95958315] 'process raft request' (duration: 163.334486ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:33.514761Z","caller":"traceutil/trace.go:171","msg":"trace[469318124] transaction","detail":"{read_only:false; response_revision:6709; number_of_response:1; }","duration":"108.819701ms","start":"2026-05-05T06:53:33.405924Z","end":"2026-05-05T06:53:33.514743Z","steps":["trace[469318124] 'process raft request' (duration: 108.78747ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:33.515174Z","caller":"traceutil/trace.go:171","msg":"trace[535720299] linearizableReadLoop","detail":"{readStateIndex:6955; appliedIndex:6954; }","duration":"110.962323ms","start":"2026-05-05T06:53:33.4042Z","end":"2026-05-05T06:53:33.515162Z","steps":["trace[535720299] 'read index received' (duration: 67.542037ms)","trace[535720299] 'applied index is now lower than readState.Index' (duration: 43.419006ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:53:33.515189Z","caller":"traceutil/trace.go:171","msg":"trace[1947371446] transaction","detail":"{read_only:false; response_revision:6708; number_of_response:1; }","duration":"111.180868ms","start":"2026-05-05T06:53:33.403985Z","end":"2026-05-05T06:53:33.515166Z","steps":["trace[1947371446] 'process raft request' (duration: 67.749842ms)","trace[1947371446] 'compare' (duration: 42.790551ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:53:33.515518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.300701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-05T06:53:33.51582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.561857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T06:53:33.515822Z","caller":"traceutil/trace.go:171","msg":"trace[443754132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6709; }","duration":"111.611678ms","start":"2026-05-05T06:53:33.40419Z","end":"2026-05-05T06:53:33.515801Z","steps":["trace[443754132] 'agreement among raft nodes before linearized reading' (duration: 111.014484ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:33.515875Z","caller":"traceutil/trace.go:171","msg":"trace[2039578147] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6709; }","duration":"111.63498ms","start":"2026-05-05T06:53:33.404225Z","end":"2026-05-05T06:53:33.51586Z","steps":["trace[2039578147] 'agreement among raft nodes before linearized reading' (duration: 111.501976ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:53:43.729446Z","caller":"traceutil/trace.go:171","msg":"trace[1724215207] linearizableReadLoop","detail":"{readStateIndex:7014; appliedIndex:7012; }","duration":"144.850919ms","start":"2026-05-05T06:53:43.584572Z","end":"2026-05-05T06:53:43.729423Z","steps":["trace[1724215207] 'read index received' (duration: 21.976889ms)","trace[1724215207] 'applied index is now lower than readState.Index' (duration: 122.87266ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:53:43.729816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.640763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T06:53:43.729874Z","caller":"traceutil/trace.go:171","msg":"trace[1343999057] transaction","detail":"{read_only:false; response_revision:6765; number_of_response:1; }","duration":"152.269537ms","start":"2026-05-05T06:53:43.577591Z","end":"2026-05-05T06:53:43.72986Z","steps":["trace[1343999057] 'process raft request' (duration: 110.947201ms)","trace[1343999057] 'compare' (duration: 40.792554ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:53:43.72988Z","caller":"traceutil/trace.go:171","msg":"trace[632951603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6765; }","duration":"139.734886ms","start":"2026-05-05T06:53:43.590129Z","end":"2026-05-05T06:53:43.729864Z","steps":["trace[632951603] 'agreement among raft nodes before linearized reading' (duration: 139.544582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:53:43.73011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.827952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T06:53:43.730142Z","caller":"traceutil/trace.go:171","msg":"trace[1214390797] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6765; }","duration":"108.902234ms","start":"2026-05-05T06:53:43.621231Z","end":"2026-05-05T06:53:43.730133Z","steps":["trace[1214390797] 'agreement among raft nodes before linearized reading' (duration: 108.833022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:53:43.729829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.237528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com\" ","response":"range_response_count:1 size:111517"} {"level":"info","ts":"2026-05-05T06:53:43.730202Z","caller":"traceutil/trace.go:171","msg":"trace[1803795419] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/rabbitmqclusters.rabbitmq.com; range_end:; response_count:1; response_revision:6765; }","duration":"145.632338ms","start":"2026-05-05T06:53:43.584553Z","end":"2026-05-05T06:53:43.730185Z","steps":["trace[1803795419] 'agreement among raft nodes before linearized reading' (duration: 145.125016ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:55:51.550153Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5569} {"level":"info","ts":"2026-05-05T06:55:51.623454Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5569,"took":"72.217638ms","hash":898897197,"current-db-size-bytes":54333440,"current-db-size":"54 MB","current-db-size-in-use-bytes":18542592,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T06:55:51.623526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":898897197,"revision":5569,"compact-revision":2919}