{"level":"warn","ts":"2026-08-05T21:36:59.962794Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T21:36:59.962881Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-05T21:36:59.962967Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T21:36:59.962978Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-05T21:36:59.963003Z","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-08-05T21:36:59.963644Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-08-05T21:36:59.963721Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-05T21:36:59.970228Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.282634ms"} {"level":"info","ts":"2026-08-05T21:36:59.980116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-08-05T21:36:59.981017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T21:36:59.981074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-08-05T21:36:59.981105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T21:36:59.98113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-08-05T21:36:59.987421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-08-05T21:36:59.998935Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T21:37:00.002504Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T21:37:00.004058Z","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-08-05T21:37:00.006285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T21:37:00.007319Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T21:37:00.007355Z","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-08-05T21:37:00.007463Z","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-08-05T21:37:00.007488Z","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-08-05T21:37:00.009407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-08-05T21:37:00.009924Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-08-05T21:37:00.010714Z","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-08-05T21:37:00.01092Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T21:37:00.010947Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T21:37:00.011049Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-05T21:37:00.011061Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-08-05T21:37:00.388458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T21:37:00.388526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T21:37:00.388554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-08-05T21:37:00.388596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-08-05T21:37:00.388607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-05T21:37:00.388618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-08-05T21:37:00.38863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-08-05T21:37:00.390768Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T21:37:00.391077Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:37:00.391245Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:37:00.391338Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:37:00.391992Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T21:37:00.392756Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T21:37:00.392096Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:37:00.39292Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:37:00.392964Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:37:00.394999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T21:37:00.39931Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-08-05T21:37:16.335552Z","caller":"traceutil/trace.go:171","msg":"trace[1989251059] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"365.826521ms","start":"2026-08-05T21:37:15.969691Z","end":"2026-08-05T21:37:16.335518Z","steps":["trace[1989251059] 'process raft request' (duration: 365.673625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:16.336269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:15.969674Z","time spent":"366.072369ms","remote":"127.0.0.1:48690","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-08-05T21:37:22.525746Z","caller":"traceutil/trace.go:171","msg":"trace[539728322] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"223.507139ms","start":"2026-08-05T21:37:22.302207Z","end":"2026-08-05T21:37:22.525714Z","steps":["trace[539728322] 'process raft request' (duration: 223.250701ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:22.526402Z","caller":"traceutil/trace.go:171","msg":"trace[2130820956] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"162.925881ms","start":"2026-08-05T21:37:22.362639Z","end":"2026-08-05T21:37:22.525565Z","steps":["trace[2130820956] 'read index received' (duration: 162.139445ms)","trace[2130820956] 'applied index is now lower than readState.Index' (duration: 785.695µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:22.526418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.703075ms","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-08-05T21:37:22.52649Z","caller":"traceutil/trace.go:171","msg":"trace[357041434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:381; }","duration":"163.85307ms","start":"2026-08-05T21:37:22.362622Z","end":"2026-08-05T21:37:22.526475Z","steps":["trace[357041434] 'agreement among raft nodes before linearized reading' (duration: 163.663484ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:23.50199Z","caller":"traceutil/trace.go:171","msg":"trace[496933313] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"131.055291ms","start":"2026-08-05T21:37:23.370904Z","end":"2026-08-05T21:37:23.501959Z","steps":["trace[496933313] 'process raft request' (duration: 128.384444ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:28.705294Z","caller":"traceutil/trace.go:171","msg":"trace[491226669] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"150.915092ms","start":"2026-08-05T21:37:28.554354Z","end":"2026-08-05T21:37:28.705269Z","steps":["trace[491226669] 'read index received' (duration: 139.658394ms)","trace[491226669] 'applied index is now lower than readState.Index' (duration: 11.255228ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:28.705414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.051327ms","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-08-05T21:37:28.705444Z","caller":"traceutil/trace.go:171","msg":"trace[1536900090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"151.125669ms","start":"2026-08-05T21:37:28.554309Z","end":"2026-08-05T21:37:28.705435Z","steps":["trace[1536900090] 'agreement among raft nodes before linearized reading' (duration: 151.046747ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:30.085888Z","caller":"traceutil/trace.go:171","msg":"trace[771199146] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"140.618709ms","start":"2026-08-05T21:37:29.945239Z","end":"2026-08-05T21:37:30.085858Z","steps":["trace[771199146] 'read index received' (duration: 140.452594ms)","trace[771199146] 'applied index is now lower than readState.Index' (duration: 164.995µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:30.085957Z","caller":"traceutil/trace.go:171","msg":"trace[1959597748] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"144.617212ms","start":"2026-08-05T21:37:29.941298Z","end":"2026-08-05T21:37:30.085915Z","steps":["trace[1959597748] 'process raft request' (duration: 144.409055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:30.086033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.769933ms","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-08-05T21:37:30.086086Z","caller":"traceutil/trace.go:171","msg":"trace[1947734559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"140.858825ms","start":"2026-08-05T21:37:29.945213Z","end":"2026-08-05T21:37:30.086072Z","steps":["trace[1947734559] 'agreement among raft nodes before linearized reading' (duration: 140.738802ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:32.303281Z","caller":"traceutil/trace.go:171","msg":"trace[210379825] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"129.369806ms","start":"2026-08-05T21:37:32.173887Z","end":"2026-08-05T21:37:32.303257Z","steps":["trace[210379825] 'read index received' (duration: 125.12516ms)","trace[210379825] 'applied index is now lower than readState.Index' (duration: 4.243626ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:32.303314Z","caller":"traceutil/trace.go:171","msg":"trace[712524549] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"169.664846ms","start":"2026-08-05T21:37:32.133621Z","end":"2026-08-05T21:37:32.303286Z","steps":["trace[712524549] 'process raft request' (duration: 165.395959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:32.303382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.479759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:37:32.303414Z","caller":"traceutil/trace.go:171","msg":"trace[664341765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"129.552461ms","start":"2026-08-05T21:37:32.173851Z","end":"2026-08-05T21:37:32.303404Z","steps":["trace[664341765] 'agreement among raft nodes before linearized reading' (duration: 129.485509ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:32.670389Z","caller":"traceutil/trace.go:171","msg":"trace[1337261867] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"104.886605ms","start":"2026-08-05T21:37:32.565478Z","end":"2026-08-05T21:37:32.670365Z","steps":["trace[1337261867] 'process raft request' (duration: 104.734931ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:38.700341Z","caller":"traceutil/trace.go:171","msg":"trace[416788044] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"171.415794ms","start":"2026-08-05T21:37:38.528907Z","end":"2026-08-05T21:37:38.700322Z","steps":["trace[416788044] 'process raft request' (duration: 171.191327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:38.834828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.024578ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T21:37:38.835746Z","caller":"traceutil/trace.go:171","msg":"trace[1006812486] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"113.360206ms","start":"2026-08-05T21:37:38.722135Z","end":"2026-08-05T21:37:38.835495Z","steps":["trace[1006812486] 'process raft request' (duration: 113.084528ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:38.843833Z","caller":"traceutil/trace.go:171","msg":"trace[1084559985] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"121.508136ms","start":"2026-08-05T21:37:38.7223Z","end":"2026-08-05T21:37:38.843809Z","steps":["trace[1084559985] 'process raft request' (duration: 121.222569ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:40.649174Z","caller":"traceutil/trace.go:171","msg":"trace[167048083] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"247.153595ms","start":"2026-08-05T21:37:40.402Z","end":"2026-08-05T21:37:40.649154Z","steps":["trace[167048083] 'read index received' (duration: 246.97762ms)","trace[167048083] 'applied index is now lower than readState.Index' (duration: 175.125µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:40.649304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.279869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:37:40.649342Z","caller":"traceutil/trace.go:171","msg":"trace[1120458218] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:486; }","duration":"247.373602ms","start":"2026-08-05T21:37:40.401954Z","end":"2026-08-05T21:37:40.649327Z","steps":["trace[1120458218] 'agreement among raft nodes before linearized reading' (duration: 247.29699ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:40.649329Z","caller":"traceutil/trace.go:171","msg":"trace[972010619] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"311.647492ms","start":"2026-08-05T21:37:40.337609Z","end":"2026-08-05T21:37:40.649257Z","steps":["trace[972010619] 'process raft request' (duration: 311.414066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:40.649664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:40.337588Z","time spent":"311.914899ms","remote":"127.0.0.1:48690","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-08-05T21:37:42.188686Z","caller":"traceutil/trace.go:171","msg":"trace[880360687] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"142.571469ms","start":"2026-08-05T21:37:42.046089Z","end":"2026-08-05T21:37:42.18866Z","steps":["trace[880360687] 'process raft request' (duration: 142.089666ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:42.334795Z","caller":"traceutil/trace.go:171","msg":"trace[945452985] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:581; }","duration":"161.255907ms","start":"2026-08-05T21:37:42.173517Z","end":"2026-08-05T21:37:42.334773Z","steps":["trace[945452985] 'read index received' (duration: 15.10006ms)","trace[945452985] 'applied index is now lower than readState.Index' (duration: 146.155157ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:42.335071Z","caller":"traceutil/trace.go:171","msg":"trace[869650797] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"223.035568ms","start":"2026-08-05T21:37:42.112007Z","end":"2026-08-05T21:37:42.335043Z","steps":["trace[869650797] 'process raft request' (duration: 202.203421ms)","trace[869650797] 'compare' (duration: 20.368825ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:42.33532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.788333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:37:42.335356Z","caller":"traceutil/trace.go:171","msg":"trace[1234661104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"161.868284ms","start":"2026-08-05T21:37:42.173478Z","end":"2026-08-05T21:37:42.335346Z","steps":["trace[1234661104] 'agreement among raft nodes before linearized reading' (duration: 161.771441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:42.335745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.130285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-05T21:37:42.335783Z","caller":"traceutil/trace.go:171","msg":"trace[1444847088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"157.192607ms","start":"2026-08-05T21:37:42.178581Z","end":"2026-08-05T21:37:42.335774Z","steps":["trace[1444847088] 'agreement among raft nodes before linearized reading' (duration: 157.083694ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:42.335827Z","caller":"traceutil/trace.go:171","msg":"trace[453477753] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"104.896235ms","start":"2026-08-05T21:37:42.23092Z","end":"2026-08-05T21:37:42.335816Z","steps":["trace[453477753] 'process raft request' (duration: 104.624167ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:42.770336Z","caller":"traceutil/trace.go:171","msg":"trace[1284232236] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"363.594953ms","start":"2026-08-05T21:37:42.406718Z","end":"2026-08-05T21:37:42.770312Z","steps":["trace[1284232236] 'read index received' (duration: 362.855104ms)","trace[1284232236] 'applied index is now lower than readState.Index' (duration: 738.709µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:42.770404Z","caller":"traceutil/trace.go:171","msg":"trace[1518201885] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"373.206264ms","start":"2026-08-05T21:37:42.397163Z","end":"2026-08-05T21:37:42.770369Z","steps":["trace[1518201885] 'process raft request' (duration: 372.435953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:42.770556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:42.397147Z","time spent":"373.325817ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:37:42.771311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.58805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-08-05T21:37:42.771363Z","caller":"traceutil/trace.go:171","msg":"trace[1066831820] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:561; }","duration":"364.668032ms","start":"2026-08-05T21:37:42.406681Z","end":"2026-08-05T21:37:42.771349Z","steps":["trace[1066831820] 'agreement among raft nodes before linearized reading' (duration: 363.750718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:42.771401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:42.406668Z","time spent":"364.716483ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80726,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-05T21:37:42.780161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.979594ms","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-08-05T21:37:42.780217Z","caller":"traceutil/trace.go:171","msg":"trace[2059098111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"127.045526ms","start":"2026-08-05T21:37:42.653158Z","end":"2026-08-05T21:37:42.780204Z","steps":["trace[2059098111] 'agreement among raft nodes before linearized reading' (duration: 126.938673ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:42.78061Z","caller":"traceutil/trace.go:171","msg":"trace[119977584] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"358.390101ms","start":"2026-08-05T21:37:42.422212Z","end":"2026-08-05T21:37:42.780602Z","steps":["trace[119977584] 'process raft request' (duration: 357.632891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:42.78068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:42.422182Z","time spent":"358.446753ms","remote":"127.0.0.1:48436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:37:47.091614Z","caller":"traceutil/trace.go:171","msg":"trace[641673579] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"193.428185ms","start":"2026-08-05T21:37:46.898161Z","end":"2026-08-05T21:37:47.091589Z","steps":["trace[641673579] 'read index received' (duration: 193.316992ms)","trace[641673579] 'applied index is now lower than readState.Index' (duration: 110.033µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:47.091802Z","caller":"traceutil/trace.go:171","msg":"trace[336292953] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"286.735607ms","start":"2026-08-05T21:37:46.805041Z","end":"2026-08-05T21:37:47.091776Z","steps":["trace[336292953] 'process raft request' (duration: 286.378528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:47.092093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.878937ms","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-08-05T21:37:47.092163Z","caller":"traceutil/trace.go:171","msg":"trace[1144404235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"194.040991ms","start":"2026-08-05T21:37:46.898103Z","end":"2026-08-05T21:37:47.092144Z","steps":["trace[1144404235] 'agreement among raft nodes before linearized reading' (duration: 193.66576ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:47.431464Z","caller":"traceutil/trace.go:171","msg":"trace[1188447031] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"339.712262ms","start":"2026-08-05T21:37:47.091733Z","end":"2026-08-05T21:37:47.431446Z","steps":["trace[1188447031] 'read index received' (duration: 339.23602ms)","trace[1188447031] 'applied index is now lower than readState.Index' (duration: 475.702µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:37:47.431548Z","caller":"traceutil/trace.go:171","msg":"trace[1036866954] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"418.487936ms","start":"2026-08-05T21:37:47.013054Z","end":"2026-08-05T21:37:47.431541Z","steps":["trace[1036866954] 'process raft request' (duration: 417.984063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:47.431626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:47.013034Z","time spent":"418.532266ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:37:47.431727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.510369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T21:37:47.431791Z","caller":"traceutil/trace.go:171","msg":"trace[306392672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"407.625201ms","start":"2026-08-05T21:37:47.024147Z","end":"2026-08-05T21:37:47.431773Z","steps":["trace[306392672] 'agreement among raft nodes before linearized reading' (duration: 407.451617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:47.43182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:47.024126Z","time spent":"407.688884ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T21:37:47.841544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.74683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:37:47.841704Z","caller":"traceutil/trace.go:171","msg":"trace[25682763] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"745.551997ms","start":"2026-08-05T21:37:47.096126Z","end":"2026-08-05T21:37:47.841678Z","steps":["trace[25682763] 'process raft request' (duration: 620.573921ms)","trace[25682763] 'compare' (duration: 124.268138ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:47.841825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:47.096104Z","time spent":"745.649439ms","remote":"127.0.0.1:48690","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-08-05T21:37:47.841832Z","caller":"traceutil/trace.go:171","msg":"trace[1020504093] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"410.299571ms","start":"2026-08-05T21:37:47.431514Z","end":"2026-08-05T21:37:47.841814Z","steps":["trace[1020504093] 'read index received' (duration: 285.196922ms)","trace[1020504093] 'applied index is now lower than readState.Index' (duration: 125.101209ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:47.841911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.974995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:37:47.841944Z","caller":"traceutil/trace.go:171","msg":"trace[727340667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"668.032687ms","start":"2026-08-05T21:37:47.173902Z","end":"2026-08-05T21:37:47.841935Z","steps":["trace[727340667] 'agreement among raft nodes before linearized reading' (duration: 667.971485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:47.841974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:47.173885Z","time spent":"668.081898ms","remote":"127.0.0.1:48396","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-08-05T21:37:47.841962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.043497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-82hdq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-05T21:37:47.842063Z","caller":"traceutil/trace.go:171","msg":"trace[1062712670] range","detail":"{range_begin:/registry/pods/kube-system/cilium-82hdq; range_end:; response_count:1; response_revision:593; }","duration":"511.19534ms","start":"2026-08-05T21:37:47.330847Z","end":"2026-08-05T21:37:47.842042Z","steps":["trace[1062712670] 'agreement among raft nodes before linearized reading' (duration: 510.977944ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:37:47.842064Z","caller":"traceutil/trace.go:171","msg":"trace[477742190] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"406.322268ms","start":"2026-08-05T21:37:47.435721Z","end":"2026-08-05T21:37:47.842043Z","steps":["trace[477742190] 'process raft request' (duration: 405.975669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:47.842121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:47.330827Z","time spent":"511.280422ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-82hdq\" "} {"level":"info","ts":"2026-08-05T21:37:47.842003Z","caller":"traceutil/trace.go:171","msg":"trace[617216755] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"363.303486ms","start":"2026-08-05T21:37:47.478681Z","end":"2026-08-05T21:37:47.841984Z","steps":["trace[617216755] 'process raft request' (duration: 363.259215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:47.842184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:47.435702Z","time spent":"406.40732ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:37:47.842245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:47.478656Z","time spent":"363.555983ms","remote":"127.0.0.1:48792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:37:48.864839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813762817644552,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:37:48.912297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.686912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:37:48.912705Z","caller":"traceutil/trace.go:171","msg":"trace[1150339927] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.051770867s","start":"2026-08-05T21:37:47.860871Z","end":"2026-08-05T21:37:48.912641Z","steps":["trace[1150339927] 'process raft request' (duration: 155.629683ms)","trace[1150339927] 'compare' (duration: 895.492127ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:48.912877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:47.860843Z","time spent":"1.051932431s","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:37:48.913556Z","caller":"traceutil/trace.go:171","msg":"trace[1791864052] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"676.775807ms","start":"2026-08-05T21:37:48.236758Z","end":"2026-08-05T21:37:48.913534Z","steps":["trace[1791864052] 'process raft request' (duration: 675.968016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:48.913704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:48.236737Z","time spent":"676.872829ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:37:49.013117Z","caller":"traceutil/trace.go:171","msg":"trace[92519909] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:621; }","duration":"648.998887ms","start":"2026-08-05T21:37:48.364086Z","end":"2026-08-05T21:37:49.013085Z","steps":["trace[92519909] 'read index received' (duration: 502.094833ms)","trace[92519909] 'applied index is now lower than readState.Index' (duration: 146.898833ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:37:49.013296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.212462ms","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-08-05T21:37:49.013366Z","caller":"traceutil/trace.go:171","msg":"trace[453777700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"649.333135ms","start":"2026-08-05T21:37:48.364016Z","end":"2026-08-05T21:37:49.013349Z","steps":["trace[453777700] 'agreement among raft nodes before linearized reading' (duration: 649.194052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:37:49.013405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:37:48.363992Z","time spent":"649.407617ms","remote":"127.0.0.1:48690","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":"info","ts":"2026-08-05T21:38:02.052381Z","caller":"traceutil/trace.go:171","msg":"trace[738718141] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"149.530462ms","start":"2026-08-05T21:38:01.902579Z","end":"2026-08-05T21:38:02.052109Z","steps":["trace[738718141] 'process raft request' (duration: 149.381219ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:38:02.053654Z","caller":"traceutil/trace.go:171","msg":"trace[789236973] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"147.77842ms","start":"2026-08-05T21:38:01.905846Z","end":"2026-08-05T21:38:02.053625Z","steps":["trace[789236973] 'process raft request' (duration: 147.645617ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:38:05.468159Z","caller":"traceutil/trace.go:171","msg":"trace[1961662651] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"299.801612ms","start":"2026-08-05T21:38:05.168329Z","end":"2026-08-05T21:38:05.46813Z","steps":["trace[1961662651] 'read index received' (duration: 299.70878ms)","trace[1961662651] 'applied index is now lower than readState.Index' (duration: 91.932µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:38:05.468271Z","caller":"traceutil/trace.go:171","msg":"trace[717526901] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"373.18648ms","start":"2026-08-05T21:38:05.095044Z","end":"2026-08-05T21:38:05.46823Z","steps":["trace[717526901] 'process raft request' (duration: 372.914594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:38:05.46848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:38:05.095027Z","time spent":"373.332193ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:38:05.468491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.044578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-05T21:38:05.468543Z","caller":"traceutil/trace.go:171","msg":"trace[958234360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"300.241731ms","start":"2026-08-05T21:38:05.168288Z","end":"2026-08-05T21:38:05.46853Z","steps":["trace[958234360] 'agreement among raft nodes before linearized reading' (duration: 299.981576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:38:05.468581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:38:05.168268Z","time spent":"300.303483ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T21:38:05.479098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.386982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:38:05.479181Z","caller":"traceutil/trace.go:171","msg":"trace[977122149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"303.531386ms","start":"2026-08-05T21:38:05.175632Z","end":"2026-08-05T21:38:05.479163Z","steps":["trace[977122149] 'agreement among raft nodes before linearized reading' (duration: 303.383042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:38:05.479238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:38:05.175609Z","time spent":"303.600738ms","remote":"127.0.0.1:48396","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-08-05T21:38:39.791309Z","caller":"traceutil/trace.go:171","msg":"trace[1848353399] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"158.116527ms","start":"2026-08-05T21:38:39.633168Z","end":"2026-08-05T21:38:39.791284Z","steps":["trace[1848353399] 'process raft request' (duration: 157.951364ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:38:39.96823Z","caller":"traceutil/trace.go:171","msg":"trace[469894479] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"334.05312ms","start":"2026-08-05T21:38:39.634154Z","end":"2026-08-05T21:38:39.968207Z","steps":["trace[469894479] 'process raft request' (duration: 288.125774ms)","trace[469894479] 'compare' (duration: 45.628821ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:38:39.968528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:38:39.634145Z","time spent":"334.180052ms","remote":"127.0.0.1:48690","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-08-05T21:38:39.968513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.541656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:38:39.96865Z","caller":"traceutil/trace.go:171","msg":"trace[626466866] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1186; }","duration":"186.758629ms","start":"2026-08-05T21:38:39.781851Z","end":"2026-08-05T21:38:39.96861Z","steps":["trace[626466866] 'agreement among raft nodes before linearized reading' (duration: 186.471814ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:38:39.968141Z","caller":"traceutil/trace.go:171","msg":"trace[276591342] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1224; }","duration":"186.21954ms","start":"2026-08-05T21:38:39.781882Z","end":"2026-08-05T21:38:39.968101Z","steps":["trace[276591342] 'read index received' (duration: 9.241008ms)","trace[276591342] 'applied index is now lower than readState.Index' (duration: 176.976322ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:38:54.905079Z","caller":"traceutil/trace.go:171","msg":"trace[329519540] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"240.817129ms","start":"2026-08-05T21:38:54.66423Z","end":"2026-08-05T21:38:54.905047Z","steps":["trace[329519540] 'process raft request' (duration: 240.616546ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:00.458779Z","caller":"traceutil/trace.go:171","msg":"trace[407953270] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"124.317705ms","start":"2026-08-05T21:39:00.334436Z","end":"2026-08-05T21:39:00.458754Z","steps":["trace[407953270] 'process raft request' (duration: 124.157032ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:00.45972Z","caller":"traceutil/trace.go:171","msg":"trace[1342726078] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"121.640621ms","start":"2026-08-05T21:39:00.337805Z","end":"2026-08-05T21:39:00.459446Z","steps":["trace[1342726078] 'process raft request' (duration: 121.472188ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:02.00672Z","caller":"traceutil/trace.go:171","msg":"trace[541465094] linearizableReadLoop","detail":"{readStateIndex:1485; appliedIndex:1484; }","duration":"108.593079ms","start":"2026-08-05T21:39:01.898107Z","end":"2026-08-05T21:39:02.0067Z","steps":["trace[541465094] 'read index received' (duration: 108.378296ms)","trace[541465094] 'applied index is now lower than readState.Index' (duration: 214.113µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:39:02.006774Z","caller":"traceutil/trace.go:171","msg":"trace[266258604] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"266.648269ms","start":"2026-08-05T21:39:01.740094Z","end":"2026-08-05T21:39:02.006742Z","steps":["trace[266258604] 'process raft request' (duration: 266.452626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:02.006908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.793133ms","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-08-05T21:39:02.006965Z","caller":"traceutil/trace.go:171","msg":"trace[1403399578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1437; }","duration":"108.903794ms","start":"2026-08-05T21:39:01.898051Z","end":"2026-08-05T21:39:02.006954Z","steps":["trace[1403399578] 'agreement among raft nodes before linearized reading' (duration: 108.724711ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:24.222498Z","caller":"traceutil/trace.go:171","msg":"trace[1001081307] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"320.495939ms","start":"2026-08-05T21:39:23.901969Z","end":"2026-08-05T21:39:24.222465Z","steps":["trace[1001081307] 'process raft request' (duration: 320.162704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:24.222694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:23.901953Z","time spent":"320.636301ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:39:24.800951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.475167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:39:24.801205Z","caller":"traceutil/trace.go:171","msg":"trace[1045775177] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"574.037541ms","start":"2026-08-05T21:39:24.227136Z","end":"2026-08-05T21:39:24.801173Z","steps":["trace[1045775177] 'process raft request' (duration: 393.26969ms)","trace[1045775177] 'compare' (duration: 180.295974ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:39:24.801389Z","caller":"traceutil/trace.go:171","msg":"trace[2047134674] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"571.432702ms","start":"2026-08-05T21:39:24.229943Z","end":"2026-08-05T21:39:24.801375Z","steps":["trace[2047134674] 'process raft request' (duration: 571.30321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:24.801402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:24.227112Z","time spent":"574.213294ms","remote":"127.0.0.1:48690","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-08-05T21:39:24.80139Z","caller":"traceutil/trace.go:171","msg":"trace[37673238] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"573.841248ms","start":"2026-08-05T21:39:24.227514Z","end":"2026-08-05T21:39:24.801355Z","steps":["trace[37673238] 'process raft request' (duration: 573.619855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:24.801467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:24.229929Z","time spent":"571.502123ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:39:24.801505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:24.227489Z","time spent":"573.95755ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:39:24.801566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.451034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ffa16134-0982-4f40-8dd4-8fcfd003dc0a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-05T21:39:24.801334Z","caller":"traceutil/trace.go:171","msg":"trace[349649362] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1889; }","duration":"324.2098ms","start":"2026-08-05T21:39:24.477109Z","end":"2026-08-05T21:39:24.801319Z","steps":["trace[349649362] 'read index received' (duration: 143.309087ms)","trace[349649362] 'applied index is now lower than readState.Index' (duration: 180.899613ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:39:24.801608Z","caller":"traceutil/trace.go:171","msg":"trace[1255689935] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ffa16134-0982-4f40-8dd4-8fcfd003dc0a; range_end:; response_count:1; response_revision:1832; }","duration":"324.521986ms","start":"2026-08-05T21:39:24.477075Z","end":"2026-08-05T21:39:24.801597Z","steps":["trace[1255689935] 'agreement among raft nodes before linearized reading' (duration: 324.386364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:24.801633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:24.477061Z","time spent":"324.567186ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ffa16134-0982-4f40-8dd4-8fcfd003dc0a\" "} {"level":"warn","ts":"2026-08-05T21:39:24.801746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.700009ms","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-08-05T21:39:24.801781Z","caller":"traceutil/trace.go:171","msg":"trace[1686982715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1832; }","duration":"231.770369ms","start":"2026-08-05T21:39:24.570002Z","end":"2026-08-05T21:39:24.801772Z","steps":["trace[1686982715] 'agreement among raft nodes before linearized reading' (duration: 231.644527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:24.802023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.690113ms","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-08-05T21:39:24.80205Z","caller":"traceutil/trace.go:171","msg":"trace[271348997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1832; }","duration":"220.755403ms","start":"2026-08-05T21:39:24.581288Z","end":"2026-08-05T21:39:24.802043Z","steps":["trace[271348997] 'agreement among raft nodes before linearized reading' (duration: 220.690802ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:25.042599Z","caller":"traceutil/trace.go:171","msg":"trace[363338572] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"235.646485ms","start":"2026-08-05T21:39:24.806929Z","end":"2026-08-05T21:39:25.042576Z","steps":["trace[363338572] 'process raft request' (duration: 235.512173ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:25.042622Z","caller":"traceutil/trace.go:171","msg":"trace[2146110539] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"234.588909ms","start":"2026-08-05T21:39:24.808014Z","end":"2026-08-05T21:39:25.042603Z","steps":["trace[2146110539] 'process raft request' (duration: 234.521488ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:25.042677Z","caller":"traceutil/trace.go:171","msg":"trace[1812762473] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1892; }","duration":"193.47746ms","start":"2026-08-05T21:39:24.849192Z","end":"2026-08-05T21:39:25.04267Z","steps":["trace[1812762473] 'read index received' (duration: 48.061693ms)","trace[1812762473] 'applied index is now lower than readState.Index' (duration: 145.414697ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:39:25.042882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.691713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ffa16134-0982-4f40-8dd4-8fcfd003dc0a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-05T21:39:25.042911Z","caller":"traceutil/trace.go:171","msg":"trace[1728839188] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ffa16134-0982-4f40-8dd4-8fcfd003dc0a; range_end:; response_count:1; response_revision:1835; }","duration":"193.773295ms","start":"2026-08-05T21:39:24.84913Z","end":"2026-08-05T21:39:25.042903Z","steps":["trace[1728839188] 'agreement among raft nodes before linearized reading' (duration: 193.563222ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:25.06477Z","caller":"traceutil/trace.go:171","msg":"trace[2052697279] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"161.955125ms","start":"2026-08-05T21:39:24.902776Z","end":"2026-08-05T21:39:25.064731Z","steps":["trace[2052697279] 'process raft request' (duration: 161.767672ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:25.064908Z","caller":"traceutil/trace.go:171","msg":"trace[723940861] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"134.213217ms","start":"2026-08-05T21:39:24.930675Z","end":"2026-08-05T21:39:25.064888Z","steps":["trace[723940861] 'process raft request' (duration: 133.995094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:25.06501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.303953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-08-05T21:39:25.065088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.287938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c90694947d482a\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-08-05T21:39:25.065157Z","caller":"traceutil/trace.go:171","msg":"trace[1231472622] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c90694947d482a; range_end:; response_count:1; response_revision:1837; }","duration":"114.43452ms","start":"2026-08-05T21:39:24.950709Z","end":"2026-08-05T21:39:25.065143Z","steps":["trace[1231472622] 'agreement among raft nodes before linearized reading' (duration: 114.199056ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:25.065067Z","caller":"traceutil/trace.go:171","msg":"trace[1589127439] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1837; }","duration":"214.391594ms","start":"2026-08-05T21:39:24.850661Z","end":"2026-08-05T21:39:25.065052Z","steps":["trace[1589127439] 'agreement among raft nodes before linearized reading' (duration: 214.209082ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:25.166108Z","caller":"traceutil/trace.go:171","msg":"trace[1283560311] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"117.073077ms","start":"2026-08-05T21:39:25.049007Z","end":"2026-08-05T21:39:25.16608Z","steps":["trace[1283560311] 'process raft request' (duration: 116.915535ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:25.185741Z","caller":"traceutil/trace.go:171","msg":"trace[1040352382] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1897; }","duration":"117.645365ms","start":"2026-08-05T21:39:25.068081Z","end":"2026-08-05T21:39:25.185727Z","steps":["trace[1040352382] 'read index received' (duration: 97.790677ms)","trace[1040352382] 'applied index is now lower than readState.Index' (duration: 19.854068ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:39:25.185811Z","caller":"traceutil/trace.go:171","msg":"trace[1920698312] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"118.397776ms","start":"2026-08-05T21:39:25.067396Z","end":"2026-08-05T21:39:25.185794Z","steps":["trace[1920698312] 'process raft request' (duration: 118.167332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:25.185918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.815307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-08-05T21:39:25.186125Z","caller":"traceutil/trace.go:171","msg":"trace[2041932272] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1840; }","duration":"118.038521ms","start":"2026-08-05T21:39:25.06805Z","end":"2026-08-05T21:39:25.186089Z","steps":["trace[2041932272] 'agreement among raft nodes before linearized reading' (duration: 117.734287ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:27.035401Z","caller":"traceutil/trace.go:171","msg":"trace[1157137775] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"131.274163ms","start":"2026-08-05T21:39:26.904099Z","end":"2026-08-05T21:39:27.035373Z","steps":["trace[1157137775] 'process raft request' (duration: 131.0656ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:32.094402Z","caller":"traceutil/trace.go:171","msg":"trace[1697161271] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"204.743493ms","start":"2026-08-05T21:39:31.889627Z","end":"2026-08-05T21:39:32.09437Z","steps":["trace[1697161271] 'process raft request' (duration: 203.489866ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:42.751623Z","caller":"traceutil/trace.go:171","msg":"trace[1577178266] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"131.866426ms","start":"2026-08-05T21:39:42.619725Z","end":"2026-08-05T21:39:42.751591Z","steps":["trace[1577178266] 'process raft request' (duration: 131.796315ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:42.751877Z","caller":"traceutil/trace.go:171","msg":"trace[164831024] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"133.564479ms","start":"2026-08-05T21:39:42.618067Z","end":"2026-08-05T21:39:42.751632Z","steps":["trace[164831024] 'process raft request' (duration: 133.312906ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:51.431968Z","caller":"traceutil/trace.go:171","msg":"trace[935523902] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"215.334614ms","start":"2026-08-05T21:39:51.21661Z","end":"2026-08-05T21:39:51.431944Z","steps":["trace[935523902] 'process raft request' (duration: 211.534152ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:51.431918Z","caller":"traceutil/trace.go:171","msg":"trace[480336897] linearizableReadLoop","detail":"{readStateIndex:2136; appliedIndex:2135; }","duration":"205.979192ms","start":"2026-08-05T21:39:51.225908Z","end":"2026-08-05T21:39:51.431887Z","steps":["trace[480336897] 'read index received' (duration: 202.19055ms)","trace[480336897] 'applied index is now lower than readState.Index' (duration: 3.786972ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:39:51.432527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.594741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-05T21:39:51.432783Z","caller":"traceutil/trace.go:171","msg":"trace[1520522180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2065; }","duration":"206.880375ms","start":"2026-08-05T21:39:51.225883Z","end":"2026-08-05T21:39:51.432763Z","steps":["trace[1520522180] 'agreement among raft nodes before linearized reading' (duration: 206.47682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:57.476742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.069684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:39:57.476864Z","caller":"traceutil/trace.go:171","msg":"trace[1573539588] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2170; }","duration":"396.792923ms","start":"2026-08-05T21:39:57.08005Z","end":"2026-08-05T21:39:57.476843Z","steps":["trace[1573539588] 'read index received' (duration: 66.100684ms)","trace[1573539588] 'applied index is now lower than readState.Index' (duration: 330.690749ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:39:57.477014Z","caller":"traceutil/trace.go:171","msg":"trace[353713196] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"419.874681ms","start":"2026-08-05T21:39:57.057074Z","end":"2026-08-05T21:39:57.476949Z","steps":["trace[353713196] 'process raft request' (duration: 151.500203ms)","trace[353713196] 'compare' (duration: 267.960133ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:39:57.477188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:57.057057Z","time spent":"420.044864ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:39:57.477405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.890662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T21:39:57.477444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.925816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-05T21:39:57.477469Z","caller":"traceutil/trace.go:171","msg":"trace[1118290742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2099; }","duration":"242.026354ms","start":"2026-08-05T21:39:57.235429Z","end":"2026-08-05T21:39:57.477456Z","steps":["trace[1118290742] 'agreement among raft nodes before linearized reading' (duration: 241.771251ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:57.477512Z","caller":"traceutil/trace.go:171","msg":"trace[248212594] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2099; }","duration":"345.028817ms","start":"2026-08-05T21:39:57.132462Z","end":"2026-08-05T21:39:57.477491Z","steps":["trace[248212594] 'agreement among raft nodes before linearized reading' (duration: 344.651992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:57.477577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:57.132446Z","time spent":"345.114828ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T21:39:57.477866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.813497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-05T21:39:57.477931Z","caller":"traceutil/trace.go:171","msg":"trace[1812286241] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2099; }","duration":"397.900038ms","start":"2026-08-05T21:39:57.080012Z","end":"2026-08-05T21:39:57.477912Z","steps":["trace[1812286241] 'agreement among raft nodes before linearized reading' (duration: 396.940405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:57.477975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:39:57.08Z","time spent":"397.963279ms","remote":"127.0.0.1:35010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-05T21:39:57.692063Z","caller":"traceutil/trace.go:171","msg":"trace[1447630583] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"208.957217ms","start":"2026-08-05T21:39:57.483082Z","end":"2026-08-05T21:39:57.692039Z","steps":["trace[1447630583] 'process raft request' (duration: 169.983899ms)","trace[1447630583] 'compare' (duration: 38.786116ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:39:57.710738Z","caller":"traceutil/trace.go:171","msg":"trace[1345823183] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"227.385652ms","start":"2026-08-05T21:39:57.483327Z","end":"2026-08-05T21:39:57.710713Z","steps":["trace[1345823183] 'process raft request' (duration: 227.175119ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:57.711102Z","caller":"traceutil/trace.go:171","msg":"trace[737114041] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"225.281234ms","start":"2026-08-05T21:39:57.485806Z","end":"2026-08-05T21:39:57.711087Z","steps":["trace[737114041] 'process raft request' (duration: 224.822867ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:57.711362Z","caller":"traceutil/trace.go:171","msg":"trace[841603463] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2172; }","duration":"170.124421ms","start":"2026-08-05T21:39:57.5412Z","end":"2026-08-05T21:39:57.711324Z","steps":["trace[841603463] 'read index received' (duration: 111.848495ms)","trace[841603463] 'applied index is now lower than readState.Index' (duration: 58.274736ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:39:57.711486Z","caller":"traceutil/trace.go:171","msg":"trace[1035889543] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"225.377125ms","start":"2026-08-05T21:39:57.48609Z","end":"2026-08-05T21:39:57.711467Z","steps":["trace[1035889543] 'process raft request' (duration: 224.929109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:57.711552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.309763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:39:57.711594Z","caller":"traceutil/trace.go:171","msg":"trace[1173460482] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2103; }","duration":"170.420224ms","start":"2026-08-05T21:39:57.541162Z","end":"2026-08-05T21:39:57.711583Z","steps":["trace[1173460482] 'agreement among raft nodes before linearized reading' (duration: 170.313763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:39:57.711744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.173941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-05T21:39:57.711787Z","caller":"traceutil/trace.go:171","msg":"trace[1153548291] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2103; }","duration":"128.263552ms","start":"2026-08-05T21:39:57.583512Z","end":"2026-08-05T21:39:57.711776Z","steps":["trace[1153548291] 'agreement among raft nodes before linearized reading' (duration: 128.11887ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:57.896036Z","caller":"traceutil/trace.go:171","msg":"trace[1934164236] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"173.476027ms","start":"2026-08-05T21:39:57.722378Z","end":"2026-08-05T21:39:57.895854Z","steps":["trace[1934164236] 'process raft request' (duration: 173.365294ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:39:57.896104Z","caller":"traceutil/trace.go:171","msg":"trace[1906716089] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"174.536692ms","start":"2026-08-05T21:39:57.721536Z","end":"2026-08-05T21:39:57.896072Z","steps":["trace[1906716089] 'process raft request' (duration: 146.136369ms)","trace[1906716089] 'compare' (duration: 27.705982ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:39:57.89672Z","caller":"traceutil/trace.go:171","msg":"trace[562862708] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"175.053389ms","start":"2026-08-05T21:39:57.721657Z","end":"2026-08-05T21:39:57.896711Z","steps":["trace[562862708] 'process raft request' (duration: 173.985394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:40:18.323249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.646127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:40:18.323531Z","caller":"traceutil/trace.go:171","msg":"trace[2017647835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2244; }","duration":"151.998292ms","start":"2026-08-05T21:40:18.171512Z","end":"2026-08-05T21:40:18.323511Z","steps":["trace[2017647835] 'range keys from in-memory index tree' (duration: 151.578196ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:40:22.226084Z","caller":"traceutil/trace.go:171","msg":"trace[1519751611] linearizableReadLoop","detail":"{readStateIndex:2352; appliedIndex:2351; }","duration":"119.649712ms","start":"2026-08-05T21:40:22.106411Z","end":"2026-08-05T21:40:22.22606Z","steps":["trace[1519751611] 'read index received' (duration: 114.473594ms)","trace[1519751611] 'applied index is now lower than readState.Index' (duration: 5.171488ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:40:22.226238Z","caller":"traceutil/trace.go:171","msg":"trace[67368919] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"121.443397ms","start":"2026-08-05T21:40:22.104755Z","end":"2026-08-05T21:40:22.226199Z","steps":["trace[67368919] 'process raft request' (duration: 116.219018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:40:22.226433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.989288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-05T21:40:22.226952Z","caller":"traceutil/trace.go:171","msg":"trace[1913292120] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2268; }","duration":"120.528135ms","start":"2026-08-05T21:40:22.106394Z","end":"2026-08-05T21:40:22.226922Z","steps":["trace[1913292120] 'agreement among raft nodes before linearized reading' (duration: 119.788095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:40:49.873837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.166459ms","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-08-05T21:40:49.873892Z","caller":"traceutil/trace.go:171","msg":"trace[396201641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2560; }","duration":"490.244581ms","start":"2026-08-05T21:40:49.383634Z","end":"2026-08-05T21:40:49.873879Z","steps":["trace[396201641] 'range keys from in-memory index tree' (duration: 489.783074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:40:49.87387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.502933ms","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-08-05T21:40:49.873917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:40:49.383624Z","time spent":"490.288293ms","remote":"127.0.0.1:48690","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":"info","ts":"2026-08-05T21:40:49.873939Z","caller":"traceutil/trace.go:171","msg":"trace[2133394057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2560; }","duration":"147.610028ms","start":"2026-08-05T21:40:49.726312Z","end":"2026-08-05T21:40:49.873922Z","steps":["trace[2133394057] 'range keys from in-memory index tree' (duration: 147.326027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:40:54.664275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.681021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-05T21:40:54.664363Z","caller":"traceutil/trace.go:171","msg":"trace[1778198102] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2603; }","duration":"102.787485ms","start":"2026-08-05T21:40:54.561556Z","end":"2026-08-05T21:40:54.664344Z","steps":["trace[1778198102] 'range keys from in-memory index tree' (duration: 102.079398ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:40:56.136964Z","caller":"traceutil/trace.go:171","msg":"trace[1168974728] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"109.317612ms","start":"2026-08-05T21:40:56.027615Z","end":"2026-08-05T21:40:56.136933Z","steps":["trace[1168974728] 'process raft request' (duration: 108.641127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:40:56.969948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.092774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:40:56.970262Z","caller":"traceutil/trace.go:171","msg":"trace[215814173] transaction","detail":"{read_only:false; response_revision:2616; number_of_response:1; }","duration":"175.031125ms","start":"2026-08-05T21:40:56.795202Z","end":"2026-08-05T21:40:56.970233Z","steps":["trace[215814173] 'process raft request' (duration: 35.563927ms)","trace[215814173] 'compare' (duration: 138.912128ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:40:56.970195Z","caller":"traceutil/trace.go:171","msg":"trace[1454845580] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"174.726684ms","start":"2026-08-05T21:40:56.795452Z","end":"2026-08-05T21:40:56.970179Z","steps":["trace[1454845580] 'process raft request' (duration: 174.588499ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:41:17.392848Z","caller":"traceutil/trace.go:171","msg":"trace[1745182725] linearizableReadLoop","detail":"{readStateIndex:2858; appliedIndex:2857; }","duration":"177.186078ms","start":"2026-08-05T21:41:17.215632Z","end":"2026-08-05T21:41:17.392818Z","steps":["trace[1745182725] 'read index received' (duration: 177.012532ms)","trace[1745182725] 'applied index is now lower than readState.Index' (duration: 172.146µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:41:17.392893Z","caller":"traceutil/trace.go:171","msg":"trace[1784843774] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"177.745406ms","start":"2026-08-05T21:41:17.215122Z","end":"2026-08-05T21:41:17.392867Z","steps":["trace[1784843774] 'process raft request' (duration: 177.54859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:17.39322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.56ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1249"} {"level":"info","ts":"2026-08-05T21:41:17.393286Z","caller":"traceutil/trace.go:171","msg":"trace[517669474] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2749; }","duration":"177.653984ms","start":"2026-08-05T21:41:17.215615Z","end":"2026-08-05T21:41:17.393269Z","steps":["trace[517669474] 'agreement among raft nodes before linearized reading' (duration: 177.328623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:17.849262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.655786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:41:17.84942Z","caller":"traceutil/trace.go:171","msg":"trace[503351937] linearizableReadLoop","detail":"{readStateIndex:2859; appliedIndex:2858; }","duration":"456.479084ms","start":"2026-08-05T21:41:17.392915Z","end":"2026-08-05T21:41:17.849394Z","steps":["trace[503351937] 'read index received' (duration: 227.595321ms)","trace[503351937] 'applied index is now lower than readState.Index' (duration: 228.881613ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:41:17.849457Z","caller":"traceutil/trace.go:171","msg":"trace[1486935673] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"503.956017ms","start":"2026-08-05T21:41:17.345465Z","end":"2026-08-05T21:41:17.849421Z","steps":["trace[1486935673] 'process raft request' (duration: 275.036022ms)","trace[1486935673] 'compare' (duration: 228.278073ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:41:17.849605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:41:17.345444Z","time spent":"504.081721ms","remote":"127.0.0.1:48690","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-08-05T21:41:17.849666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.958177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-05T21:41:17.849706Z","caller":"traceutil/trace.go:171","msg":"trace[2096706526] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2750; }","duration":"600.001509ms","start":"2026-08-05T21:41:17.249691Z","end":"2026-08-05T21:41:17.849693Z","steps":["trace[2096706526] 'agreement among raft nodes before linearized reading' (duration: 599.827693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:17.849739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:41:17.24962Z","time spent":"600.111182ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T21:41:17.849915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.031637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:5908"} {"level":"info","ts":"2026-08-05T21:41:17.849949Z","caller":"traceutil/trace.go:171","msg":"trace[216999119] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2750; }","duration":"453.093299ms","start":"2026-08-05T21:41:17.396847Z","end":"2026-08-05T21:41:17.84994Z","steps":["trace[216999119] 'agreement among raft nodes before linearized reading' (duration: 452.974064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:17.849959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.468069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-08-05T21:41:17.84998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:41:17.396834Z","time spent":"453.139721ms","remote":"127.0.0.1:48890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":5931,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"info","ts":"2026-08-05T21:41:17.850011Z","caller":"traceutil/trace.go:171","msg":"trace[604062668] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2750; }","duration":"611.538671ms","start":"2026-08-05T21:41:17.238456Z","end":"2026-08-05T21:41:17.849995Z","steps":["trace[604062668] 'agreement among raft nodes before linearized reading' (duration: 611.407656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:17.850046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:41:17.238444Z","time spent":"611.593813ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T21:41:17.850128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.099984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c906abdca6f464\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-05T21:41:17.850165Z","caller":"traceutil/trace.go:171","msg":"trace[1391906964] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c906abdca6f464; range_end:; response_count:1; response_revision:2750; }","duration":"409.172087ms","start":"2026-08-05T21:41:17.440983Z","end":"2026-08-05T21:41:17.850155Z","steps":["trace[1391906964] 'agreement among raft nodes before linearized reading' (duration: 409.075654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:17.850192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:41:17.440942Z","time spent":"409.244229ms","remote":"127.0.0.1:48480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c906abdca6f464\" "} {"level":"info","ts":"2026-08-05T21:41:18.143795Z","caller":"traceutil/trace.go:171","msg":"trace[1553928819] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"274.245753ms","start":"2026-08-05T21:41:17.869524Z","end":"2026-08-05T21:41:18.143769Z","steps":["trace[1553928819] 'process raft request' (duration: 273.879741ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:41:18.144402Z","caller":"traceutil/trace.go:171","msg":"trace[628687724] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"271.755009ms","start":"2026-08-05T21:41:17.872633Z","end":"2026-08-05T21:41:18.144388Z","steps":["trace[628687724] 'process raft request' (duration: 271.49129ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:41:18.1447Z","caller":"traceutil/trace.go:171","msg":"trace[1376778506] linearizableReadLoop","detail":"{readStateIndex:2863; appliedIndex:2860; }","duration":"133.523746ms","start":"2026-08-05T21:41:18.011117Z","end":"2026-08-05T21:41:18.144641Z","steps":["trace[1376778506] 'read index received' (duration: 132.052165ms)","trace[1376778506] 'applied index is now lower than readState.Index' (duration: 1.470551ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:41:18.144717Z","caller":"traceutil/trace.go:171","msg":"trace[1204669705] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"271.321815ms","start":"2026-08-05T21:41:17.873357Z","end":"2026-08-05T21:41:18.144679Z","steps":["trace[1204669705] 'process raft request' (duration: 270.910041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:18.144985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.262638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-05T21:41:18.145037Z","caller":"traceutil/trace.go:171","msg":"trace[1759065122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2753; }","duration":"133.365461ms","start":"2026-08-05T21:41:18.01166Z","end":"2026-08-05T21:41:18.145025Z","steps":["trace[1759065122] 'agreement among raft nodes before linearized reading' (duration: 133.217826ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:41:18.145253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.132026ms","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-08-05T21:41:18.145332Z","caller":"traceutil/trace.go:171","msg":"trace[1831770841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2753; }","duration":"134.2677ms","start":"2026-08-05T21:41:18.011052Z","end":"2026-08-05T21:41:18.14532Z","steps":["trace[1831770841] 'agreement among raft nodes before linearized reading' (duration: 133.815665ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:10.891334Z","caller":"traceutil/trace.go:171","msg":"trace[104143359] transaction","detail":"{read_only:false; response_revision:3199; number_of_response:1; }","duration":"160.872591ms","start":"2026-08-05T21:42:10.730436Z","end":"2026-08-05T21:42:10.891308Z","steps":["trace[104143359] 'process raft request' (duration: 137.016955ms)","trace[104143359] 'compare' (duration: 23.737983ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:42:11.215039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.539759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-08-05T21:42:11.215104Z","caller":"traceutil/trace.go:171","msg":"trace[196057559] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret; range_end:; response_count:1; response_revision:3203; }","duration":"112.652252ms","start":"2026-08-05T21:42:11.102436Z","end":"2026-08-05T21:42:11.215089Z","steps":["trace[196057559] 'range keys from in-memory index tree' (duration: 112.406886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:13.030544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813762817650525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:42:13.53151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813762817650525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T21:42:13.809884Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.312825026s","expected-duration":"1s"} {"level":"info","ts":"2026-08-05T21:42:13.861007Z","caller":"traceutil/trace.go:171","msg":"trace[2089045616] linearizableReadLoop","detail":"{readStateIndex:3346; appliedIndex:3344; }","duration":"1.330712142s","start":"2026-08-05T21:42:12.530273Z","end":"2026-08-05T21:42:13.860985Z","steps":["trace[2089045616] 'read index received' (duration: 1.279868175s)","trace[2089045616] 'applied index is now lower than readState.Index' (duration: 50.843007ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:42:13.861964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331652928s","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-08-05T21:42:13.86195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331641878s","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-08-05T21:42:13.862004Z","caller":"traceutil/trace.go:171","msg":"trace[1860483892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3212; }","duration":"1.33173029s","start":"2026-08-05T21:42:12.530265Z","end":"2026-08-05T21:42:13.861995Z","steps":["trace[1860483892] 'agreement among raft nodes before linearized reading' (duration: 1.331602787s)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:13.861984Z","caller":"traceutil/trace.go:171","msg":"trace[208694406] transaction","detail":"{read_only:false; response_revision:3212; number_of_response:1; }","duration":"816.066296ms","start":"2026-08-05T21:42:13.045886Z","end":"2026-08-05T21:42:13.861953Z","steps":["trace[208694406] 'process raft request' (duration: 815.015037ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:13.862014Z","caller":"traceutil/trace.go:171","msg":"trace[622125622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3212; }","duration":"1.331771411s","start":"2026-08-05T21:42:12.530232Z","end":"2026-08-05T21:42:13.862003Z","steps":["trace[622125622] 'agreement among raft nodes before linearized reading' (duration: 1.331568876s)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:13.861961Z","caller":"traceutil/trace.go:171","msg":"trace[1640392205] transaction","detail":"{read_only:false; response_revision:3211; number_of_response:1; }","duration":"1.365007269s","start":"2026-08-05T21:42:12.496916Z","end":"2026-08-05T21:42:13.861924Z","steps":["trace[1640392205] 'process raft request' (duration: 1.313284919s)","trace[1640392205] 'compare' (duration: 49.669054ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:42:13.86203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:12.530224Z","time spent":"1.331801082s","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T21:42:13.862045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:12.530211Z","time spent":"1.331828072s","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T21:42:13.862116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.472978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:42:13.862107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.090981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:42:13.862115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:13.04587Z","time spent":"816.1743ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:42:13.862128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:12.496887Z","time spent":"1.365168574s","remote":"127.0.0.1:48690","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-08-05T21:42:13.862191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.337563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-08-05T21:42:13.862136Z","caller":"traceutil/trace.go:171","msg":"trace[2048209541] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:3212; }","duration":"890.53923ms","start":"2026-08-05T21:42:12.97159Z","end":"2026-08-05T21:42:13.862129Z","steps":["trace[2048209541] 'agreement among raft nodes before linearized reading' (duration: 890.506099ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:13.862212Z","caller":"traceutil/trace.go:171","msg":"trace[1390238883] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3212; }","duration":"951.395065ms","start":"2026-08-05T21:42:12.910811Z","end":"2026-08-05T21:42:13.862206Z","steps":["trace[1390238883] 'agreement among raft nodes before linearized reading' (duration: 951.338253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:13.862229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:12.910794Z","time spent":"951.431185ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T21:42:13.862239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:12.971568Z","time spent":"890.650433ms","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:42:13.862178Z","caller":"traceutil/trace.go:171","msg":"trace[1219982574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3212; }","duration":"687.184343ms","start":"2026-08-05T21:42:13.174972Z","end":"2026-08-05T21:42:13.862156Z","steps":["trace[1219982574] 'agreement among raft nodes before linearized reading' (duration: 687.068029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:13.862338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:13.174958Z","time spent":"687.375358ms","remote":"127.0.0.1:48396","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-08-05T21:42:13.862349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.963954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T21:42:13.862345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33106468s","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-08-05T21:42:13.862373Z","caller":"traceutil/trace.go:171","msg":"trace[1426618496] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3212; }","duration":"108.007065ms","start":"2026-08-05T21:42:13.754358Z","end":"2026-08-05T21:42:13.862365Z","steps":["trace[1426618496] 'agreement among raft nodes before linearized reading' (duration: 107.970344ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:13.862386Z","caller":"traceutil/trace.go:171","msg":"trace[509434346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3212; }","duration":"1.331123582s","start":"2026-08-05T21:42:12.53125Z","end":"2026-08-05T21:42:13.862373Z","steps":["trace[509434346] 'agreement among raft nodes before linearized reading' (duration: 1.330998869s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:13.862419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:12.531213Z","time spent":"1.331197124s","remote":"127.0.0.1:48690","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-08-05T21:42:13.862314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.393847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T21:42:13.862423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.323869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-05T21:42:13.862453Z","caller":"traceutil/trace.go:171","msg":"trace[1731725517] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:3212; }","duration":"392.557861ms","start":"2026-08-05T21:42:13.469886Z","end":"2026-08-05T21:42:13.862444Z","steps":["trace[1731725517] 'agreement among raft nodes before linearized reading' (duration: 392.402987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:13.862438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138508794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-08-05T21:42:13.862476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:13.469873Z","time spent":"392.595932ms","remote":"127.0.0.1:35858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:42:13.862467Z","caller":"traceutil/trace.go:171","msg":"trace[1306720116] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:3212; }","duration":"489.387382ms","start":"2026-08-05T21:42:13.373068Z","end":"2026-08-05T21:42:13.862455Z","steps":["trace[1306720116] 'agreement among raft nodes before linearized reading' (duration: 489.267018ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:13.862495Z","caller":"traceutil/trace.go:171","msg":"trace[576829858] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3212; }","duration":"1.138610626s","start":"2026-08-05T21:42:12.723867Z","end":"2026-08-05T21:42:13.862478Z","steps":["trace[576829858] 'agreement among raft nodes before linearized reading' (duration: 1.138458492s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:13.862506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:13.373057Z","time spent":"489.441303ms","remote":"127.0.0.1:48424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"warn","ts":"2026-08-05T21:42:13.86254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:12.723844Z","time spent":"1.138684318s","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T21:42:13.862554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.547433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-05T21:42:13.8626Z","caller":"traceutil/trace.go:171","msg":"trace[932721753] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3212; }","duration":"508.626905ms","start":"2026-08-05T21:42:13.35396Z","end":"2026-08-05T21:42:13.862586Z","steps":["trace[932721753] 'agreement among raft nodes before linearized reading' (duration: 508.492262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:13.862639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:13.35394Z","time spent":"508.689566ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-08-05T21:42:54.458968Z","caller":"traceutil/trace.go:171","msg":"trace[1304480431] linearizableReadLoop","detail":"{readStateIndex:3695; appliedIndex:3694; }","duration":"111.140528ms","start":"2026-08-05T21:42:54.34781Z","end":"2026-08-05T21:42:54.458951Z","steps":["trace[1304480431] 'read index received' (duration: 31.31µs)","trace[1304480431] 'applied index is now lower than readState.Index' (duration: 111.108398ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:42:54.458977Z","caller":"traceutil/trace.go:171","msg":"trace[1278643363] transaction","detail":"{read_only:false; response_revision:3539; number_of_response:1; }","duration":"111.398715ms","start":"2026-08-05T21:42:54.347555Z","end":"2026-08-05T21:42:54.458953Z","steps":["trace[1278643363] 'process raft request' (duration: 87.084614ms)","trace[1278643363] 'compare' (duration: 23.753317ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:42:54.459418Z","caller":"traceutil/trace.go:171","msg":"trace[833432386] transaction","detail":"{read_only:false; response_revision:3540; number_of_response:1; }","duration":"111.026645ms","start":"2026-08-05T21:42:54.348369Z","end":"2026-08-05T21:42:54.459396Z","steps":["trace[833432386] 'process raft request' (duration: 110.536484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:54.459456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.549498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:551"} {"level":"warn","ts":"2026-08-05T21:42:54.45945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.61449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:42:54.45949Z","caller":"traceutil/trace.go:171","msg":"trace[780957473] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3540; }","duration":"111.603359ms","start":"2026-08-05T21:42:54.347874Z","end":"2026-08-05T21:42:54.459478Z","steps":["trace[780957473] 'agreement among raft nodes before linearized reading' (duration: 111.479736ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:54.459523Z","caller":"traceutil/trace.go:171","msg":"trace[1607295628] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheuses.monitoring.coreos.com; range_end:; response_count:0; response_revision:3540; }","duration":"111.710982ms","start":"2026-08-05T21:42:54.347795Z","end":"2026-08-05T21:42:54.459506Z","steps":["trace[1607295628] 'agreement among raft nodes before linearized reading' (duration: 111.259011ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:54.713994Z","caller":"traceutil/trace.go:171","msg":"trace[373796748] linearizableReadLoop","detail":"{readStateIndex:3698; appliedIndex:3697; }","duration":"105.207727ms","start":"2026-08-05T21:42:54.608763Z","end":"2026-08-05T21:42:54.713971Z","steps":["trace[373796748] 'read index received' (duration: 104.759266ms)","trace[373796748] 'applied index is now lower than readState.Index' (duration: 447.381µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:42:54.714163Z","caller":"traceutil/trace.go:171","msg":"trace[725103330] transaction","detail":"{read_only:false; response_revision:3542; number_of_response:1; }","duration":"134.023525ms","start":"2026-08-05T21:42:54.580104Z","end":"2026-08-05T21:42:54.714128Z","steps":["trace[725103330] 'process raft request' (duration: 133.479223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:54.714593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.802741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-05T21:42:54.714686Z","caller":"traceutil/trace.go:171","msg":"trace[54310533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3542; }","duration":"105.931424ms","start":"2026-08-05T21:42:54.608735Z","end":"2026-08-05T21:42:54.714666Z","steps":["trace[54310533] 'agreement among raft nodes before linearized reading' (duration: 105.363821ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:54.72626Z","caller":"traceutil/trace.go:171","msg":"trace[658880605] transaction","detail":"{read_only:false; response_revision:3543; number_of_response:1; }","duration":"104.084299ms","start":"2026-08-05T21:42:54.622164Z","end":"2026-08-05T21:42:54.726248Z","steps":["trace[658880605] 'process raft request' (duration: 103.955216ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:22.386818Z","caller":"traceutil/trace.go:171","msg":"trace[1851542649] linearizableReadLoop","detail":"{readStateIndex:3921; appliedIndex:3920; }","duration":"109.353816ms","start":"2026-08-05T21:43:22.277435Z","end":"2026-08-05T21:43:22.386789Z","steps":["trace[1851542649] 'read index received' (duration: 109.163261ms)","trace[1851542649] 'applied index is now lower than readState.Index' (duration: 189.735µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:43:22.386891Z","caller":"traceutil/trace.go:171","msg":"trace[1072943799] transaction","detail":"{read_only:false; response_revision:3745; number_of_response:1; }","duration":"163.647049ms","start":"2026-08-05T21:43:22.223216Z","end":"2026-08-05T21:43:22.386863Z","steps":["trace[1072943799] 'process raft request' (duration: 163.431815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:22.38705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.593401ms","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-08-05T21:43:22.387115Z","caller":"traceutil/trace.go:171","msg":"trace[1282140347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3745; }","duration":"109.698213ms","start":"2026-08-05T21:43:22.277407Z","end":"2026-08-05T21:43:22.387105Z","steps":["trace[1282140347] 'agreement among raft nodes before linearized reading' (duration: 109.518869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:22.387154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.330095ms","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-08-05T21:43:22.387209Z","caller":"traceutil/trace.go:171","msg":"trace[1848464434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3745; }","duration":"109.431087ms","start":"2026-08-05T21:43:22.277763Z","end":"2026-08-05T21:43:22.387194Z","steps":["trace[1848464434] 'agreement among raft nodes before linearized reading' (duration: 109.208243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:22.387186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.871804ms","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-08-05T21:43:22.387388Z","caller":"traceutil/trace.go:171","msg":"trace[132154215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3745; }","duration":"104.090028ms","start":"2026-08-05T21:43:22.283281Z","end":"2026-08-05T21:43:22.387371Z","steps":["trace[132154215] 'agreement among raft nodes before linearized reading' (duration: 103.72707ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:40.20296Z","caller":"traceutil/trace.go:171","msg":"trace[24673938] transaction","detail":"{read_only:false; response_revision:4283; number_of_response:1; }","duration":"132.867034ms","start":"2026-08-05T21:43:40.070061Z","end":"2026-08-05T21:43:40.202928Z","steps":["trace[24673938] 'process raft request' (duration: 125.090768ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:51.579025Z","caller":"traceutil/trace.go:171","msg":"trace[52001586] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"118.770713ms","start":"2026-08-05T21:43:51.460232Z","end":"2026-08-05T21:43:51.579003Z","steps":["trace[52001586] 'process raft request' (duration: 118.6279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:52.104926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.547549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-05T21:43:52.105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.320283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:43:52.105028Z","caller":"traceutil/trace.go:171","msg":"trace[62888603] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4547; }","duration":"421.699492ms","start":"2026-08-05T21:43:51.683301Z","end":"2026-08-05T21:43:52.105001Z","steps":["trace[62888603] 'count revisions from in-memory index tree' (duration: 421.370466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:52.105085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:43:51.683283Z","time spent":"421.787504ms","remote":"127.0.0.1:48674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:43:52.105086Z","caller":"traceutil/trace.go:171","msg":"trace[2076538361] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4547; }","duration":"297.408285ms","start":"2026-08-05T21:43:51.807649Z","end":"2026-08-05T21:43:52.105057Z","steps":["trace[2076538361] 'count revisions from in-memory index tree' (duration: 297.217841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:52.105097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.254518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:43:52.105129Z","caller":"traceutil/trace.go:171","msg":"trace[228146862] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4547; }","duration":"402.291429ms","start":"2026-08-05T21:43:51.70283Z","end":"2026-08-05T21:43:52.105121Z","steps":["trace[228146862] 'count revisions from in-memory index tree' (duration: 402.196127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:52.105151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:43:51.702784Z","time spent":"402.36046ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-08-05T21:43:52.912396Z","caller":"traceutil/trace.go:171","msg":"trace[1837094407] linearizableReadLoop","detail":"{readStateIndex:4751; appliedIndex:4750; }","duration":"229.153773ms","start":"2026-08-05T21:43:52.683218Z","end":"2026-08-05T21:43:52.912372Z","steps":["trace[1837094407] 'read index received' (duration: 154.035908ms)","trace[1837094407] 'applied index is now lower than readState.Index' (duration: 75.116605ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:43:52.912665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.434679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:43:52.912723Z","caller":"traceutil/trace.go:171","msg":"trace[1679495794] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4553; }","duration":"229.552261ms","start":"2026-08-05T21:43:52.68316Z","end":"2026-08-05T21:43:52.912712Z","steps":["trace[1679495794] 'agreement among raft nodes before linearized reading' (duration: 229.445969ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:43:52.912864Z","caller":"traceutil/trace.go:171","msg":"trace[798092502] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"266.682719ms","start":"2026-08-05T21:43:52.646152Z","end":"2026-08-05T21:43:52.912835Z","steps":["trace[798092502] 'process raft request' (duration: 191.153036ms)","trace[798092502] 'compare' (duration: 74.632194ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:43:53.270965Z","caller":"traceutil/trace.go:171","msg":"trace[888170616] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"140.940552ms","start":"2026-08-05T21:43:53.129991Z","end":"2026-08-05T21:43:53.270932Z","steps":["trace[888170616] 'process raft request' (duration: 80.122715ms)","trace[888170616] 'compare' (duration: 60.214923ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:43:53.271148Z","caller":"traceutil/trace.go:171","msg":"trace[226681824] linearizableReadLoop","detail":"{readStateIndex:4753; appliedIndex:4752; }","duration":"139.402939ms","start":"2026-08-05T21:43:53.131716Z","end":"2026-08-05T21:43:53.271119Z","steps":["trace[226681824] 'read index received' (duration: 78.408429ms)","trace[226681824] 'applied index is now lower than readState.Index' (duration: 60.99047ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:43:53.271384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.653415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-08-05T21:43:53.271436Z","caller":"traceutil/trace.go:171","msg":"trace[1810469130] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4555; }","duration":"139.770058ms","start":"2026-08-05T21:43:53.131654Z","end":"2026-08-05T21:43:53.271424Z","steps":["trace[1810469130] 'agreement among raft nodes before linearized reading' (duration: 139.558713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:53.271688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.736456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-05T21:43:53.271798Z","caller":"traceutil/trace.go:171","msg":"trace[1792715595] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4555; }","duration":"107.9082ms","start":"2026-08-05T21:43:53.163874Z","end":"2026-08-05T21:43:53.271782Z","steps":["trace[1792715595] 'agreement among raft nodes before linearized reading' (duration: 107.635164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.564768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.253415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:44:05.564802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.667331ms","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-08-05T21:44:05.564855Z","caller":"traceutil/trace.go:171","msg":"trace[188702853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4732; }","duration":"390.391858ms","start":"2026-08-05T21:44:05.174445Z","end":"2026-08-05T21:44:05.564837Z","steps":["trace[188702853] 'range keys from in-memory index tree' (duration: 390.163874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.564888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.841163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"warn","ts":"2026-08-05T21:44:05.564901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:05.174426Z","time spent":"390.46226ms","remote":"127.0.0.1:48396","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-08-05T21:44:05.564888Z","caller":"traceutil/trace.go:171","msg":"trace[59577114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4732; }","duration":"872.783763ms","start":"2026-08-05T21:44:04.692078Z","end":"2026-08-05T21:44:05.564862Z","steps":["trace[59577114] 'range keys from in-memory index tree' (duration: 872.509237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.564938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:05.187365Z","time spent":"377.568941ms","remote":"127.0.0.1:56392","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-05T21:44:05.564953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:04.692057Z","time spent":"872.882625ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T21:44:05.565064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.980935ms","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-08-05T21:44:05.565173Z","caller":"traceutil/trace.go:171","msg":"trace[103934299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4732; }","duration":"858.123837ms","start":"2026-08-05T21:44:04.707031Z","end":"2026-08-05T21:44:05.565155Z","steps":["trace[103934299] 'range keys from in-memory index tree' (duration: 857.680248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.565267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:04.707001Z","time spent":"858.24835ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T21:44:05.565401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.446039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"warn","ts":"2026-08-05T21:44:05.56539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.642234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-08-05T21:44:05.56561Z","caller":"traceutil/trace.go:171","msg":"trace[958743777] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4732; }","duration":"322.716934ms","start":"2026-08-05T21:44:05.242884Z","end":"2026-08-05T21:44:05.565601Z","steps":["trace[958743777] 'range keys from in-memory index tree' (duration: 322.324837ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.564939Z","caller":"traceutil/trace.go:171","msg":"trace[176917881] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4732; }","duration":"293.891273ms","start":"2026-08-05T21:44:05.271026Z","end":"2026-08-05T21:44:05.564917Z","steps":["trace[176917881] 'range keys from in-memory index tree' (duration: 293.760651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.565628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.037948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"warn","ts":"2026-08-05T21:44:05.564727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.094896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-08-05T21:44:05.565811Z","caller":"traceutil/trace.go:171","msg":"trace[476298304] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4732; }","duration":"242.613791ms","start":"2026-08-05T21:44:05.323178Z","end":"2026-08-05T21:44:05.565792Z","steps":["trace[476298304] 'range keys from in-memory index tree' (duration: 241.837434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.565273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.10187ms","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-08-05T21:44:05.564866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.763534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:44:05.565916Z","caller":"traceutil/trace.go:171","msg":"trace[1267616003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4732; }","duration":"242.810874ms","start":"2026-08-05T21:44:05.323087Z","end":"2026-08-05T21:44:05.565898Z","steps":["trace[1267616003] 'range keys from in-memory index tree' (duration: 241.859074ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.565944Z","caller":"traceutil/trace.go:171","msg":"trace[1481276381] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4732; }","duration":"378.843166ms","start":"2026-08-05T21:44:05.187066Z","end":"2026-08-05T21:44:05.565909Z","steps":["trace[1481276381] 'count revisions from in-memory index tree' (duration: 377.699173ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.565637Z","caller":"traceutil/trace.go:171","msg":"trace[1301273734] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4732; }","duration":"528.92553ms","start":"2026-08-05T21:44:05.036694Z","end":"2026-08-05T21:44:05.56562Z","steps":["trace[1301273734] 'range keys from in-memory index tree' (duration: 528.461571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.566013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:05.187049Z","time spent":"378.924588ms","remote":"127.0.0.1:48884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":16,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-08-05T21:44:05.566047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:05.036668Z","time spent":"529.366249ms","remote":"127.0.0.1:39318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3221,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-05T21:44:05.566122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.986476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-08-05T21:44:05.564767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.771782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-08-05T21:44:05.564778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.620859ms","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-08-05T21:44:05.566166Z","caller":"traceutil/trace.go:171","msg":"trace[227654952] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4732; }","duration":"163.064748ms","start":"2026-08-05T21:44:05.403089Z","end":"2026-08-05T21:44:05.566154Z","steps":["trace[227654952] 'range keys from in-memory index tree' (duration: 162.883624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.565653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:05.242861Z","time spent":"322.784396ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10538,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-08-05T21:44:05.565849Z","caller":"traceutil/trace.go:171","msg":"trace[859482598] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4732; }","duration":"855.25477ms","start":"2026-08-05T21:44:04.710574Z","end":"2026-08-05T21:44:05.565828Z","steps":["trace[859482598] 'range keys from in-memory index tree' (duration: 853.883702ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.5662Z","caller":"traceutil/trace.go:171","msg":"trace[479391774] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4732; }","duration":"233.240191ms","start":"2026-08-05T21:44:05.332939Z","end":"2026-08-05T21:44:05.566179Z","steps":["trace[479391774] 'range keys from in-memory index tree' (duration: 231.633309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.566269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:04.710564Z","time spent":"855.691489ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-08-05T21:44:05.5662Z","caller":"traceutil/trace.go:171","msg":"trace[771162757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4732; }","duration":"302.088989ms","start":"2026-08-05T21:44:05.264099Z","end":"2026-08-05T21:44:05.566188Z","steps":["trace[771162757] 'range keys from in-memory index tree' (duration: 300.452615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.566435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:05.264086Z","time spent":"302.334333ms","remote":"127.0.0.1:48690","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-08-05T21:44:05.566394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.971695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-05T21:44:05.566599Z","caller":"traceutil/trace.go:171","msg":"trace[1612881625] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4732; }","duration":"162.193559ms","start":"2026-08-05T21:44:05.40439Z","end":"2026-08-05T21:44:05.566584Z","steps":["trace[1612881625] 'range keys from in-memory index tree' (duration: 161.868583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.566685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.377474ms","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-08-05T21:44:05.566738Z","caller":"traceutil/trace.go:171","msg":"trace[59051475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4732; }","duration":"607.467116ms","start":"2026-08-05T21:44:04.959259Z","end":"2026-08-05T21:44:05.566726Z","steps":["trace[59051475] 'range keys from in-memory index tree' (duration: 605.234221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.56677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:04.959205Z","time spent":"607.557258ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T21:44:05.722476Z","caller":"traceutil/trace.go:171","msg":"trace[1725939868] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"148.074823ms","start":"2026-08-05T21:44:05.574379Z","end":"2026-08-05T21:44:05.722454Z","steps":["trace[1725939868] 'process raft request' (duration: 148.004722ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.722495Z","caller":"traceutil/trace.go:171","msg":"trace[1442840261] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"148.101284ms","start":"2026-08-05T21:44:05.574365Z","end":"2026-08-05T21:44:05.722466Z","steps":["trace[1442840261] 'process raft request' (duration: 147.87627ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.722834Z","caller":"traceutil/trace.go:171","msg":"trace[1593840705] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"148.38491ms","start":"2026-08-05T21:44:05.574432Z","end":"2026-08-05T21:44:05.722817Z","steps":["trace[1593840705] 'process raft request' (duration: 147.986951ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.722912Z","caller":"traceutil/trace.go:171","msg":"trace[672620730] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"146.479553ms","start":"2026-08-05T21:44:05.576399Z","end":"2026-08-05T21:44:05.722878Z","steps":["trace[672620730] 'process raft request' (duration: 146.393451ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.723101Z","caller":"traceutil/trace.go:171","msg":"trace[1337698319] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"148.156896ms","start":"2026-08-05T21:44:05.574933Z","end":"2026-08-05T21:44:05.72309Z","steps":["trace[1337698319] 'process raft request' (duration: 147.743908ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.7232Z","caller":"traceutil/trace.go:171","msg":"trace[1225833736] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"144.87325ms","start":"2026-08-05T21:44:05.578317Z","end":"2026-08-05T21:44:05.72319Z","steps":["trace[1225833736] 'process raft request' (duration: 144.718977ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.723329Z","caller":"traceutil/trace.go:171","msg":"trace[1445316110] linearizableReadLoop","detail":"{readStateIndex:4944; appliedIndex:4941; }","duration":"148.727778ms","start":"2026-08-05T21:44:05.574406Z","end":"2026-08-05T21:44:05.723134Z","steps":["trace[1445316110] 'read index received' (duration: 38.22278ms)","trace[1445316110] 'applied index is now lower than readState.Index' (duration: 110.504128ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:05.723369Z","caller":"traceutil/trace.go:171","msg":"trace[149337683] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"146.550684ms","start":"2026-08-05T21:44:05.576808Z","end":"2026-08-05T21:44:05.723359Z","steps":["trace[149337683] 'process raft request' (duration: 146.027874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.725215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.715227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1459"} {"level":"info","ts":"2026-08-05T21:44:05.725255Z","caller":"traceutil/trace.go:171","msg":"trace[1562646087] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4740; }","duration":"149.778878ms","start":"2026-08-05T21:44:05.575462Z","end":"2026-08-05T21:44:05.725241Z","steps":["trace[1562646087] 'agreement among raft nodes before linearized reading' (duration: 149.669026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.723599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.178126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-08-05T21:44:05.725571Z","caller":"traceutil/trace.go:171","msg":"trace[1255341038] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4740; }","duration":"151.132296ms","start":"2026-08-05T21:44:05.574398Z","end":"2026-08-05T21:44:05.72553Z","steps":["trace[1255341038] 'agreement among raft nodes before linearized reading' (duration: 149.085205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.725947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.197267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-08-05T21:44:05.725986Z","caller":"traceutil/trace.go:171","msg":"trace[2003294828] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4740; }","duration":"150.250969ms","start":"2026-08-05T21:44:05.575726Z","end":"2026-08-05T21:44:05.725977Z","steps":["trace[2003294828] 'agreement among raft nodes before linearized reading' (duration: 150.145027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.723658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.296179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-08-05T21:44:05.726221Z","caller":"traceutil/trace.go:171","msg":"trace[1162554999] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4740; }","duration":"151.881941ms","start":"2026-08-05T21:44:05.574328Z","end":"2026-08-05T21:44:05.726209Z","steps":["trace[1162554999] 'agreement among raft nodes before linearized reading' (duration: 149.213747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.723736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.439031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:1267"} {"level":"info","ts":"2026-08-05T21:44:05.726535Z","caller":"traceutil/trace.go:171","msg":"trace[309410486] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:4740; }","duration":"152.229137ms","start":"2026-08-05T21:44:05.57428Z","end":"2026-08-05T21:44:05.726509Z","steps":["trace[309410486] 'agreement among raft nodes before linearized reading' (duration: 149.3958ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.89743Z","caller":"traceutil/trace.go:171","msg":"trace[1023431772] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"165.142758ms","start":"2026-08-05T21:44:05.732225Z","end":"2026-08-05T21:44:05.897368Z","steps":["trace[1023431772] 'process raft request' (duration: 164.862293ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.897621Z","caller":"traceutil/trace.go:171","msg":"trace[1835311830] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"164.549047ms","start":"2026-08-05T21:44:05.733045Z","end":"2026-08-05T21:44:05.897594Z","steps":["trace[1835311830] 'process raft request' (duration: 164.467155ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.897691Z","caller":"traceutil/trace.go:171","msg":"trace[1352310442] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"164.877823ms","start":"2026-08-05T21:44:05.732796Z","end":"2026-08-05T21:44:05.897673Z","steps":["trace[1352310442] 'process raft request' (duration: 164.573947ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.897818Z","caller":"traceutil/trace.go:171","msg":"trace[1000579605] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"164.911294ms","start":"2026-08-05T21:44:05.732897Z","end":"2026-08-05T21:44:05.897808Z","steps":["trace[1000579605] 'process raft request' (duration: 164.568597ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.897939Z","caller":"traceutil/trace.go:171","msg":"trace[681177887] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"165.324512ms","start":"2026-08-05T21:44:05.732605Z","end":"2026-08-05T21:44:05.89793Z","steps":["trace[681177887] 'process raft request' (duration: 164.7296ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.898128Z","caller":"traceutil/trace.go:171","msg":"trace[124419373] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"164.060596ms","start":"2026-08-05T21:44:05.734028Z","end":"2026-08-05T21:44:05.898088Z","steps":["trace[124419373] 'process raft request' (duration: 163.524656ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.898193Z","caller":"traceutil/trace.go:171","msg":"trace[1202062855] linearizableReadLoop","detail":"{readStateIndex:4952; appliedIndex:4949; }","duration":"165.642738ms","start":"2026-08-05T21:44:05.732539Z","end":"2026-08-05T21:44:05.898182Z","steps":["trace[1202062855] 'read index received' (duration: 25.963853ms)","trace[1202062855] 'applied index is now lower than readState.Index' (duration: 139.678035ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:05.897499Z","caller":"traceutil/trace.go:171","msg":"trace[45215603] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"164.893024ms","start":"2026-08-05T21:44:05.732498Z","end":"2026-08-05T21:44:05.897391Z","steps":["trace[45215603] 'process raft request' (duration: 164.772661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.898635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.074387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18c906d2f16a9c3f\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-08-05T21:44:05.898704Z","caller":"traceutil/trace.go:171","msg":"trace[641570960] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18c906d2f16a9c3f; range_end:; response_count:1; response_revision:4749; }","duration":"166.177579ms","start":"2026-08-05T21:44:05.732511Z","end":"2026-08-05T21:44:05.898688Z","steps":["trace[641570960] 'agreement among raft nodes before linearized reading' (duration: 165.950565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.898946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.597367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9761"} {"level":"info","ts":"2026-08-05T21:44:05.898985Z","caller":"traceutil/trace.go:171","msg":"trace[45614267] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4749; }","duration":"164.663548ms","start":"2026-08-05T21:44:05.734313Z","end":"2026-08-05T21:44:05.898976Z","steps":["trace[45614267] 'agreement among raft nodes before linearized reading' (duration: 164.543456ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.898435Z","caller":"traceutil/trace.go:171","msg":"trace[1849049826] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"164.099097ms","start":"2026-08-05T21:44:05.734321Z","end":"2026-08-05T21:44:05.89842Z","steps":["trace[1849049826] 'process raft request' (duration: 163.677109ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:06.175394Z","caller":"traceutil/trace.go:171","msg":"trace[149485825] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"214.694891ms","start":"2026-08-05T21:44:05.960668Z","end":"2026-08-05T21:44:06.175363Z","steps":["trace[149485825] 'process raft request' (duration: 166.94944ms)","trace[149485825] 'compare' (duration: 47.516596ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:29.459105Z","caller":"traceutil/trace.go:171","msg":"trace[867093614] linearizableReadLoop","detail":"{readStateIndex:5169; appliedIndex:5168; }","duration":"100.54049ms","start":"2026-08-05T21:44:29.358542Z","end":"2026-08-05T21:44:29.459082Z","steps":["trace[867093614] 'read index received' (duration: 36.244049ms)","trace[867093614] 'applied index is now lower than readState.Index' (duration: 64.295541ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:29.459373Z","caller":"traceutil/trace.go:171","msg":"trace[1898877281] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"102.253192ms","start":"2026-08-05T21:44:29.357029Z","end":"2026-08-05T21:44:29.459282Z","steps":["trace[1898877281] 'process raft request' (duration: 37.86ms)","trace[1898877281] 'compare' (duration: 64.040276ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:44:29.4595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.892436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-05T21:44:29.459573Z","caller":"traceutil/trace.go:171","msg":"trace[1802490920] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4950; }","duration":"101.055689ms","start":"2026-08-05T21:44:29.358504Z","end":"2026-08-05T21:44:29.45956Z","steps":["trace[1802490920] 'agreement among raft nodes before linearized reading' (duration: 100.765043ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:35.126666Z","caller":"traceutil/trace.go:171","msg":"trace[2015965896] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5083; }","duration":"288.848561ms","start":"2026-08-05T21:44:34.837802Z","end":"2026-08-05T21:44:35.12665Z","steps":["trace[2015965896] 'process raft request' (duration: 270.190065ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:06.766726Z","caller":"traceutil/trace.go:171","msg":"trace[713820847] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"105.557054ms","start":"2026-08-05T21:45:06.661149Z","end":"2026-08-05T21:45:06.766706Z","steps":["trace[713820847] 'process raft request' (duration: 69.099309ms)","trace[713820847] 'compare' (duration: 36.321883ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:06.779916Z","caller":"traceutil/trace.go:171","msg":"trace[395241370] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"116.293991ms","start":"2026-08-05T21:45:06.663583Z","end":"2026-08-05T21:45:06.779877Z","steps":["trace[395241370] 'process raft request' (duration: 115.657579ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:15.991876Z","caller":"traceutil/trace.go:171","msg":"trace[813625176] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"178.042358ms","start":"2026-08-05T21:45:15.8138Z","end":"2026-08-05T21:45:15.991842Z","steps":["trace[813625176] 'process raft request' (duration: 177.845315ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:16.502597Z","caller":"traceutil/trace.go:171","msg":"trace[1291767988] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"134.404029ms","start":"2026-08-05T21:45:16.368167Z","end":"2026-08-05T21:45:16.502571Z","steps":["trace[1291767988] 'process raft request' (duration: 134.087374ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:26.014586Z","caller":"traceutil/trace.go:171","msg":"trace[1947786773] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"131.210206ms","start":"2026-08-05T21:45:25.883345Z","end":"2026-08-05T21:45:26.014556Z","steps":["trace[1947786773] 'process raft request' (duration: 78.167151ms)","trace[1947786773] 'compare' (duration: 52.880452ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:27.960182Z","caller":"traceutil/trace.go:171","msg":"trace[1046956736] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"116.420358ms","start":"2026-08-05T21:45:27.843737Z","end":"2026-08-05T21:45:27.960158Z","steps":["trace[1046956736] 'process raft request' (duration: 116.266515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:29.546392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.695408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:45:29.546609Z","caller":"traceutil/trace.go:171","msg":"trace[1839920915] transaction","detail":"{read_only:false; response_revision:5550; number_of_response:1; }","duration":"246.369285ms","start":"2026-08-05T21:45:29.300218Z","end":"2026-08-05T21:45:29.546587Z","steps":["trace[1839920915] 'process raft request' (duration: 81.374572ms)","trace[1839920915] 'compare' (duration: 164.464234ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:29.546864Z","caller":"traceutil/trace.go:171","msg":"trace[1968461404] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"244.043264ms","start":"2026-08-05T21:45:29.30278Z","end":"2026-08-05T21:45:29.546823Z","steps":["trace[1968461404] 'process raft request' (duration: 243.975292ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:29.546981Z","caller":"traceutil/trace.go:171","msg":"trace[1328455889] transaction","detail":"{read_only:false; response_revision:5551; number_of_response:1; }","duration":"246.348825ms","start":"2026-08-05T21:45:29.300576Z","end":"2026-08-05T21:45:29.546924Z","steps":["trace[1328455889] 'process raft request' (duration: 245.928268ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:31.031977Z","caller":"traceutil/trace.go:171","msg":"trace[449978706] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"202.239799ms","start":"2026-08-05T21:45:30.829713Z","end":"2026-08-05T21:45:31.031952Z","steps":["trace[449978706] 'process raft request' (duration: 202.086066ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:31.398743Z","caller":"traceutil/trace.go:171","msg":"trace[796102279] linearizableReadLoop","detail":"{readStateIndex:5810; appliedIndex:5807; }","duration":"443.972169ms","start":"2026-08-05T21:45:30.954743Z","end":"2026-08-05T21:45:31.398715Z","steps":["trace[796102279] 'read index received' (duration: 77.101724ms)","trace[796102279] 'applied index is now lower than readState.Index' (duration: 366.869425ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:45:31.398996Z","caller":"traceutil/trace.go:171","msg":"trace[1091031329] transaction","detail":"{read_only:false; response_revision:5565; number_of_response:1; }","duration":"560.714645ms","start":"2026-08-05T21:45:30.838271Z","end":"2026-08-05T21:45:31.398986Z","steps":["trace[1091031329] 'process raft request' (duration: 559.726378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.399121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:30.838245Z","time spent":"560.803437ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:45:31.399331Z","caller":"traceutil/trace.go:171","msg":"trace[1938415241] transaction","detail":"{read_only:false; response_revision:5566; number_of_response:1; }","duration":"534.664624ms","start":"2026-08-05T21:45:30.864653Z","end":"2026-08-05T21:45:31.399318Z","steps":["trace[1938415241] 'process raft request' (duration: 533.982742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.3993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.530819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:45:31.399399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:30.864627Z","time spent":"534.739455ms","remote":"127.0.0.1:48658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4965,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:45:31.399431Z","caller":"traceutil/trace.go:171","msg":"trace[1729485341] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:5566; }","duration":"444.720932ms","start":"2026-08-05T21:45:30.954684Z","end":"2026-08-05T21:45:31.399405Z","steps":["trace[1729485341] 'agreement among raft nodes before linearized reading' (duration: 444.493449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.399504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:30.954662Z","time spent":"444.822794ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-08-05T21:45:31.399594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.051539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:45:31.399499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.100048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-rabbit-init-zpkbz\" ","response":"range_response_count:1 size:6713"} {"level":"info","ts":"2026-08-05T21:45:31.399793Z","caller":"traceutil/trace.go:171","msg":"trace[694106673] range","detail":"{range_begin:/registry/pods/openstack/keystone-rabbit-init-zpkbz; range_end:; response_count:1; response_revision:5566; }","duration":"363.443066ms","start":"2026-08-05T21:45:31.036324Z","end":"2026-08-05T21:45:31.399767Z","steps":["trace[694106673] 'agreement among raft nodes before linearized reading' (duration: 362.998217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:45:31.399881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:45:31.036308Z","time spent":"363.537737ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":6736,"request content":"key:\"/registry/pods/openstack/keystone-rabbit-init-zpkbz\" "} {"level":"info","ts":"2026-08-05T21:45:31.400565Z","caller":"traceutil/trace.go:171","msg":"trace[1089299036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5566; }","duration":"225.13947ms","start":"2026-08-05T21:45:31.174488Z","end":"2026-08-05T21:45:31.399627Z","steps":["trace[1089299036] 'agreement among raft nodes before linearized reading' (duration: 225.049549ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:32.123633Z","caller":"traceutil/trace.go:171","msg":"trace[1553225445] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"169.230249ms","start":"2026-08-05T21:45:31.954373Z","end":"2026-08-05T21:45:32.123603Z","steps":["trace[1553225445] 'process raft request' (duration: 169.063836ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:45.797012Z","caller":"traceutil/trace.go:171","msg":"trace[427581301] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"104.01565ms","start":"2026-08-05T21:45:45.692956Z","end":"2026-08-05T21:45:45.796972Z","steps":["trace[427581301] 'process raft request' (duration: 70.453326ms)","trace[427581301] 'compare' (duration: 33.267719ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:46:38.830018Z","caller":"traceutil/trace.go:171","msg":"trace[580333150] transaction","detail":"{read_only:false; response_revision:6035; number_of_response:1; }","duration":"103.935258ms","start":"2026-08-05T21:46:38.726056Z","end":"2026-08-05T21:46:38.829991Z","steps":["trace[580333150] 'process raft request' (duration: 90.24327ms)","trace[580333150] 'compare' (duration: 13.582166ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:46:48.623031Z","caller":"traceutil/trace.go:171","msg":"trace[1643092973] transaction","detail":"{read_only:false; response_revision:6096; number_of_response:1; }","duration":"109.288635ms","start":"2026-08-05T21:46:48.513681Z","end":"2026-08-05T21:46:48.622969Z","steps":["trace[1643092973] 'process raft request' (duration: 96.605205ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:48.624192Z","caller":"traceutil/trace.go:171","msg":"trace[981958633] transaction","detail":"{read_only:false; response_revision:6097; number_of_response:1; }","duration":"110.415993ms","start":"2026-08-05T21:46:48.513751Z","end":"2026-08-05T21:46:48.624167Z","steps":["trace[981958633] 'process raft request' (duration: 101.75196ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:49.020702Z","caller":"traceutil/trace.go:171","msg":"trace[1338891814] transaction","detail":"{read_only:false; response_revision:6105; number_of_response:1; }","duration":"131.117545ms","start":"2026-08-05T21:46:48.889551Z","end":"2026-08-05T21:46:49.020669Z","steps":["trace[1338891814] 'process raft request' (duration: 97.968178ms)","trace[1338891814] 'compare' (duration: 32.992885ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:46:49.078845Z","caller":"traceutil/trace.go:171","msg":"trace[1696954042] transaction","detail":"{read_only:false; response_revision:6106; number_of_response:1; }","duration":"156.91495ms","start":"2026-08-05T21:46:48.921901Z","end":"2026-08-05T21:46:49.078816Z","steps":["trace[1696954042] 'process raft request' (duration: 156.774107ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:49.43729Z","caller":"traceutil/trace.go:171","msg":"trace[1547445791] transaction","detail":"{read_only:false; response_revision:6108; number_of_response:1; }","duration":"119.98724ms","start":"2026-08-05T21:46:49.317274Z","end":"2026-08-05T21:46:49.437261Z","steps":["trace[1547445791] 'process raft request' (duration: 119.764187ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:50.871288Z","caller":"traceutil/trace.go:171","msg":"trace[100371345] transaction","detail":"{read_only:false; response_revision:6127; number_of_response:1; }","duration":"124.895399ms","start":"2026-08-05T21:46:50.746365Z","end":"2026-08-05T21:46:50.871261Z","steps":["trace[100371345] 'process raft request' (duration: 115.355802ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:50.871432Z","caller":"traceutil/trace.go:171","msg":"trace[743179683] transaction","detail":"{read_only:false; response_revision:6128; number_of_response:1; }","duration":"121.25085ms","start":"2026-08-05T21:46:50.75011Z","end":"2026-08-05T21:46:50.871361Z","steps":["trace[743179683] 'process raft request' (duration: 121.100648ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:00.539021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3122} {"level":"info","ts":"2026-08-05T21:47:00.64294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3122,"took":"102.49659ms","hash":2796077382,"current-db-size-bytes":58748928,"current-db-size":"59 MB","current-db-size-in-use-bytes":31109120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T21:47:00.64301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2796077382,"revision":3122,"compact-revision":-1} {"level":"info","ts":"2026-08-05T21:47:11.907043Z","caller":"traceutil/trace.go:171","msg":"trace[2128394878] transaction","detail":"{read_only:false; response_revision:6316; number_of_response:1; }","duration":"134.5114ms","start":"2026-08-05T21:47:11.772468Z","end":"2026-08-05T21:47:11.906979Z","steps":["trace[2128394878] 'process raft request' (duration: 134.336357ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:12.926109Z","caller":"traceutil/trace.go:171","msg":"trace[1260785804] linearizableReadLoop","detail":"{readStateIndex:6606; appliedIndex:6605; }","duration":"107.241605ms","start":"2026-08-05T21:47:12.818812Z","end":"2026-08-05T21:47:12.926054Z","steps":["trace[1260785804] 'read index received' (duration: 107.042212ms)","trace[1260785804] 'applied index is now lower than readState.Index' (duration: 198.873µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:12.926289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.121117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:47:12.92632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.49744ms","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-08-05T21:47:12.926339Z","caller":"traceutil/trace.go:171","msg":"trace[213879896] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:6317; }","duration":"106.202948ms","start":"2026-08-05T21:47:12.820126Z","end":"2026-08-05T21:47:12.926329Z","steps":["trace[213879896] 'agreement among raft nodes before linearized reading' (duration: 106.091616ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:12.92638Z","caller":"traceutil/trace.go:171","msg":"trace[1327509788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6317; }","duration":"107.620502ms","start":"2026-08-05T21:47:12.818741Z","end":"2026-08-05T21:47:12.926361Z","steps":["trace[1327509788] 'agreement among raft nodes before linearized reading' (duration: 107.391797ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:12.92665Z","caller":"traceutil/trace.go:171","msg":"trace[676994677] transaction","detail":"{read_only:false; response_revision:6317; number_of_response:1; }","duration":"149.290668ms","start":"2026-08-05T21:47:12.776782Z","end":"2026-08-05T21:47:12.926073Z","steps":["trace[676994677] 'process raft request' (duration: 149.163256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:12.926666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.724882ms","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-08-05T21:47:12.926898Z","caller":"traceutil/trace.go:171","msg":"trace[2121360140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6317; }","duration":"102.948545ms","start":"2026-08-05T21:47:12.823881Z","end":"2026-08-05T21:47:12.926829Z","steps":["trace[2121360140] 'agreement among raft nodes before linearized reading' (duration: 102.492628ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:13.400715Z","caller":"traceutil/trace.go:171","msg":"trace[1245755554] transaction","detail":"{read_only:false; response_revision:6318; number_of_response:1; }","duration":"470.271108ms","start":"2026-08-05T21:47:12.930421Z","end":"2026-08-05T21:47:13.400692Z","steps":["trace[1245755554] 'process raft request' (duration: 470.142406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:13.40086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:12.930405Z","time spent":"470.3846ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:13.412886Z","caller":"traceutil/trace.go:171","msg":"trace[1554259964] linearizableReadLoop","detail":"{readStateIndex:6608; appliedIndex:6606; }","duration":"474.530207ms","start":"2026-08-05T21:47:12.938318Z","end":"2026-08-05T21:47:13.412849Z","steps":["trace[1554259964] 'read index received' (duration: 462.299698ms)","trace[1554259964] 'applied index is now lower than readState.Index' (duration: 12.229159ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:13.412993Z","caller":"traceutil/trace.go:171","msg":"trace[244171108] transaction","detail":"{read_only:false; response_revision:6319; number_of_response:1; }","duration":"480.642176ms","start":"2026-08-05T21:47:12.932321Z","end":"2026-08-05T21:47:13.412963Z","steps":["trace[244171108] 'process raft request' (duration: 480.30987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:13.413104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:12.932295Z","time spent":"480.747028ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:13.413281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.489283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-08-05T21:47:13.413302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.940033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-05T21:47:13.41334Z","caller":"traceutil/trace.go:171","msg":"trace[1912827198] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6319; }","duration":"472.559804ms","start":"2026-08-05T21:47:12.940764Z","end":"2026-08-05T21:47:13.413324Z","steps":["trace[1912827198] 'agreement among raft nodes before linearized reading' (duration: 472.382981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:13.413379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:12.940758Z","time spent":"472.613285ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T21:47:13.413472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.686524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-08-05T21:47:13.413521Z","caller":"traceutil/trace.go:171","msg":"trace[1493948337] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6319; }","duration":"411.771566ms","start":"2026-08-05T21:47:13.001736Z","end":"2026-08-05T21:47:13.413507Z","steps":["trace[1493948337] 'agreement among raft nodes before linearized reading' (duration: 411.653954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:13.413567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:13.001715Z","time spent":"411.842617ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-08-05T21:47:13.413836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.193957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:47:13.413296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.035152ms","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-08-05T21:47:13.413874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.248202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-05T21:47:13.413945Z","caller":"traceutil/trace.go:171","msg":"trace[2001482962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6319; }","duration":"285.538344ms","start":"2026-08-05T21:47:13.128378Z","end":"2026-08-05T21:47:13.413916Z","steps":["trace[2001482962] 'agreement among raft nodes before linearized reading' (duration: 285.151237ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:13.41395Z","caller":"traceutil/trace.go:171","msg":"trace[653485047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6319; }","duration":"275.717554ms","start":"2026-08-05T21:47:13.138213Z","end":"2026-08-05T21:47:13.413931Z","steps":["trace[653485047] 'agreement among raft nodes before linearized reading' (duration: 274.950571ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:13.414049Z","caller":"traceutil/trace.go:171","msg":"trace[1293314128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6319; }","duration":"410.398885ms","start":"2026-08-05T21:47:13.003575Z","end":"2026-08-05T21:47:13.413974Z","steps":["trace[1293314128] 'agreement among raft nodes before linearized reading' (duration: 409.823954ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:13.414105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:13.003563Z","time spent":"410.534947ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-05T21:47:13.413537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.327387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:13.414984Z","caller":"traceutil/trace.go:171","msg":"trace[1259820811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6319; }","duration":"241.793991ms","start":"2026-08-05T21:47:13.173171Z","end":"2026-08-05T21:47:13.414965Z","steps":["trace[1259820811] 'agreement among raft nodes before linearized reading' (duration: 240.298217ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:13.413364Z","caller":"traceutil/trace.go:171","msg":"trace[143511141] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6319; }","duration":"475.062386ms","start":"2026-08-05T21:47:12.938281Z","end":"2026-08-05T21:47:13.413343Z","steps":["trace[143511141] 'agreement among raft nodes before linearized reading' (duration: 474.758771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:13.415441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:12.938268Z","time spent":"477.15186ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-05T21:47:18.121167Z","caller":"traceutil/trace.go:171","msg":"trace[605568263] linearizableReadLoop","detail":"{readStateIndex:6715; appliedIndex:6714; }","duration":"102.750066ms","start":"2026-08-05T21:47:18.018391Z","end":"2026-08-05T21:47:18.121141Z","steps":["trace[605568263] 'read index received' (duration: 102.609314ms)","trace[605568263] 'applied index is now lower than readState.Index' (duration: 139.542µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:18.121361Z","caller":"traceutil/trace.go:171","msg":"trace[1085481018] transaction","detail":"{read_only:false; response_revision:6423; number_of_response:1; }","duration":"108.888686ms","start":"2026-08-05T21:47:18.012424Z","end":"2026-08-05T21:47:18.121313Z","steps":["trace[1085481018] 'process raft request' (duration: 108.50988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:18.122215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.738123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360973"} {"level":"info","ts":"2026-08-05T21:47:18.122281Z","caller":"traceutil/trace.go:171","msg":"trace[1702592964] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:6423; }","duration":"103.905185ms","start":"2026-08-05T21:47:18.018361Z","end":"2026-08-05T21:47:18.122266Z","steps":["trace[1702592964] 'agreement among raft nodes before linearized reading' (duration: 102.882139ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:19.745683Z","caller":"traceutil/trace.go:171","msg":"trace[775957511] linearizableReadLoop","detail":"{readStateIndex:6746; appliedIndex:6742; }","duration":"232.977055ms","start":"2026-08-05T21:47:19.512679Z","end":"2026-08-05T21:47:19.745656Z","steps":["trace[775957511] 'read index received' (duration: 12.883478ms)","trace[775957511] 'applied index is now lower than readState.Index' (duration: 220.092357ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:19.74595Z","caller":"traceutil/trace.go:171","msg":"trace[1375643773] transaction","detail":"{read_only:false; response_revision:6452; number_of_response:1; }","duration":"243.616949ms","start":"2026-08-05T21:47:19.502305Z","end":"2026-08-05T21:47:19.745922Z","steps":["trace[1375643773] 'process raft request' (duration: 243.204412ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:19.745999Z","caller":"traceutil/trace.go:171","msg":"trace[651101627] transaction","detail":"{read_only:false; response_revision:6453; number_of_response:1; }","duration":"243.531377ms","start":"2026-08-05T21:47:19.502419Z","end":"2026-08-05T21:47:19.745951Z","steps":["trace[651101627] 'process raft request' (duration: 243.174932ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:19.745744Z","caller":"traceutil/trace.go:171","msg":"trace[1373468241] transaction","detail":"{read_only:false; response_revision:6451; number_of_response:1; }","duration":"243.666179ms","start":"2026-08-05T21:47:19.502028Z","end":"2026-08-05T21:47:19.745694Z","steps":["trace[1373468241] 'process raft request' (duration: 169.644839ms)","trace[1373468241] 'compare' (duration: 73.694745ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:19.746362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.615676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-db-init-jhbl8\" ","response":"range_response_count:1 size:6149"} {"level":"info","ts":"2026-08-05T21:47:19.746554Z","caller":"traceutil/trace.go:171","msg":"trace[656379732] range","detail":"{range_begin:/registry/pods/openstack/horizon-db-init-jhbl8; range_end:; response_count:1; response_revision:6453; }","duration":"233.84902ms","start":"2026-08-05T21:47:19.512646Z","end":"2026-08-05T21:47:19.746495Z","steps":["trace[656379732] 'agreement among raft nodes before linearized reading' (duration: 233.478004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:19.746645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.682847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-08-05T21:47:19.746293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.024824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:19.746718Z","caller":"traceutil/trace.go:171","msg":"trace[1590041385] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6453; }","duration":"204.8546ms","start":"2026-08-05T21:47:19.541845Z","end":"2026-08-05T21:47:19.7467Z","steps":["trace[1590041385] 'agreement among raft nodes before linearized reading' (duration: 204.410913ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:19.746756Z","caller":"traceutil/trace.go:171","msg":"trace[256454239] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:6453; }","duration":"144.531482ms","start":"2026-08-05T21:47:19.602212Z","end":"2026-08-05T21:47:19.746743Z","steps":["trace[256454239] 'agreement among raft nodes before linearized reading' (duration: 144.017654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:19.747252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.03468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-05T21:47:19.747318Z","caller":"traceutil/trace.go:171","msg":"trace[962448374] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6453; }","duration":"211.170742ms","start":"2026-08-05T21:47:19.53613Z","end":"2026-08-05T21:47:19.747301Z","steps":["trace[962448374] 'agreement among raft nodes before linearized reading' (duration: 210.089494ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:19.971615Z","caller":"traceutil/trace.go:171","msg":"trace[536187241] linearizableReadLoop","detail":"{readStateIndex:6747; appliedIndex:6746; }","duration":"217.420884ms","start":"2026-08-05T21:47:19.75417Z","end":"2026-08-05T21:47:19.97159Z","steps":["trace[536187241] 'read index received' (duration: 185.555208ms)","trace[536187241] 'applied index is now lower than readState.Index' (duration: 31.864726ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:19.971712Z","caller":"traceutil/trace.go:171","msg":"trace[644866780] transaction","detail":"{read_only:false; response_revision:6454; number_of_response:1; }","duration":"218.4178ms","start":"2026-08-05T21:47:19.753258Z","end":"2026-08-05T21:47:19.971676Z","steps":["trace[644866780] 'process raft request' (duration: 186.294639ms)","trace[644866780] 'compare' (duration: 31.926677ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:19.971888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.697149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-08-05T21:47:19.971965Z","caller":"traceutil/trace.go:171","msg":"trace[2014582891] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6454; }","duration":"217.79554ms","start":"2026-08-05T21:47:19.754152Z","end":"2026-08-05T21:47:19.971948Z","steps":["trace[2014582891] 'agreement among raft nodes before linearized reading' (duration: 217.540506ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:19.990867Z","caller":"traceutil/trace.go:171","msg":"trace[159232730] transaction","detail":"{read_only:false; response_revision:6455; number_of_response:1; }","duration":"235.799262ms","start":"2026-08-05T21:47:19.755047Z","end":"2026-08-05T21:47:19.990847Z","steps":["trace[159232730] 'process raft request' (duration: 235.642159ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:19.991107Z","caller":"traceutil/trace.go:171","msg":"trace[227671617] transaction","detail":"{read_only:false; response_revision:6456; number_of_response:1; }","duration":"235.220432ms","start":"2026-08-05T21:47:19.755828Z","end":"2026-08-05T21:47:19.991049Z","steps":["trace[227671617] 'process raft request' (duration: 234.975538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:19.997967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.667074ms","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-08-05T21:47:19.998044Z","caller":"traceutil/trace.go:171","msg":"trace[2104922990] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:6457; }","duration":"123.789366ms","start":"2026-08-05T21:47:19.874234Z","end":"2026-08-05T21:47:19.998023Z","steps":["trace[2104922990] 'agreement among raft nodes before linearized reading' (duration: 123.675404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:20.36314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.049067ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:20.363297Z","caller":"traceutil/trace.go:171","msg":"trace[1352472554] transaction","detail":"{read_only:false; response_revision:6459; number_of_response:1; }","duration":"360.875566ms","start":"2026-08-05T21:47:20.002379Z","end":"2026-08-05T21:47:20.363255Z","steps":["trace[1352472554] 'process raft request' (duration: 300.073582ms)","trace[1352472554] 'compare' (duration: 60.603811ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:20.36342Z","caller":"traceutil/trace.go:171","msg":"trace[404993001] transaction","detail":"{read_only:false; response_revision:6460; number_of_response:1; }","duration":"356.884781ms","start":"2026-08-05T21:47:20.006479Z","end":"2026-08-05T21:47:20.363363Z","steps":["trace[404993001] 'process raft request' (duration: 356.712348ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:20.363278Z","caller":"traceutil/trace.go:171","msg":"trace[1976158896] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:6458; }","duration":"122.193479ms","start":"2026-08-05T21:47:20.241067Z","end":"2026-08-05T21:47:20.36326Z","steps":["trace[1976158896] 'range keys from in-memory index tree' (duration: 122.033347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:20.363496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:20.00236Z","time spent":"361.044519ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:20.363627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:20.00647Z","time spent":"357.039543ms","remote":"127.0.0.1:48608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9584,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:47:20.392048Z","caller":"traceutil/trace.go:171","msg":"trace[603899688] linearizableReadLoop","detail":"{readStateIndex:6754; appliedIndex:6751; }","duration":"217.641745ms","start":"2026-08-05T21:47:20.174361Z","end":"2026-08-05T21:47:20.392003Z","steps":["trace[603899688] 'read index received' (duration: 128.117735ms)","trace[603899688] 'applied index is now lower than readState.Index' (duration: 89.52271ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:20.392158Z","caller":"traceutil/trace.go:171","msg":"trace[1689639431] transaction","detail":"{read_only:false; response_revision:6461; number_of_response:1; }","duration":"351.977312ms","start":"2026-08-05T21:47:20.040148Z","end":"2026-08-05T21:47:20.392125Z","steps":["trace[1689639431] 'process raft request' (duration: 351.710437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:20.392321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:20.04013Z","time spent":"352.109874ms","remote":"127.0.0.1:48890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:20.392568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.180585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:20.392655Z","caller":"traceutil/trace.go:171","msg":"trace[1399266166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6461; }","duration":"218.318056ms","start":"2026-08-05T21:47:20.174319Z","end":"2026-08-05T21:47:20.392637Z","steps":["trace[1399266166] 'agreement among raft nodes before linearized reading' (duration: 217.839539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:20.644325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.032413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-08-05T21:47:20.644401Z","caller":"traceutil/trace.go:171","msg":"trace[1008020299] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6464; }","duration":"181.185726ms","start":"2026-08-05T21:47:20.463198Z","end":"2026-08-05T21:47:20.644384Z","steps":["trace[1008020299] 'range keys from in-memory index tree' (duration: 180.863301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:22.275832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.238802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:22.276181Z","caller":"traceutil/trace.go:171","msg":"trace[1605628439] transaction","detail":"{read_only:false; response_revision:6472; number_of_response:1; }","duration":"520.661257ms","start":"2026-08-05T21:47:21.755484Z","end":"2026-08-05T21:47:22.276145Z","steps":["trace[1605628439] 'process raft request' (duration: 220.992478ms)","trace[1605628439] 'compare' (duration: 298.905337ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:22.276234Z","caller":"traceutil/trace.go:171","msg":"trace[711682353] transaction","detail":"{read_only:false; response_revision:6473; number_of_response:1; }","duration":"520.322882ms","start":"2026-08-05T21:47:21.755883Z","end":"2026-08-05T21:47:22.276206Z","steps":["trace[711682353] 'process raft request' (duration: 520.121849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:22.27633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.520486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:22.276397Z","caller":"traceutil/trace.go:171","msg":"trace[933966333] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:6474; }","duration":"328.626518ms","start":"2026-08-05T21:47:21.947754Z","end":"2026-08-05T21:47:22.276381Z","steps":["trace[933966333] 'agreement among raft nodes before linearized reading' (duration: 328.490036ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:22.27643Z","caller":"traceutil/trace.go:171","msg":"trace[2013491536] transaction","detail":"{read_only:false; response_revision:6474; number_of_response:1; }","duration":"519.59405ms","start":"2026-08-05T21:47:21.756784Z","end":"2026-08-05T21:47:22.276378Z","steps":["trace[2013491536] 'process raft request' (duration: 519.331636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:22.276459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:21.94773Z","time spent":"328.712249ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-08-05T21:47:22.276533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:21.756767Z","time spent":"519.696791ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:22.276488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:21.755867Z","time spent":"520.440444ms","remote":"127.0.0.1:48690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:47:22.276532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:47:21.755465Z","time spent":"520.81647ms","remote":"127.0.0.1:48690","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-08-05T21:47:22.276207Z","caller":"traceutil/trace.go:171","msg":"trace[2078869404] linearizableReadLoop","detail":"{readStateIndex:6768; appliedIndex:6765; }","duration":"328.377934ms","start":"2026-08-05T21:47:21.947807Z","end":"2026-08-05T21:47:22.276184Z","steps":["trace[2078869404] 'read index received' (duration: 28.684764ms)","trace[2078869404] 'applied index is now lower than readState.Index' (duration: 299.69199ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:22.278215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.353987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-05T21:47:22.278266Z","caller":"traceutil/trace.go:171","msg":"trace[49832631] transaction","detail":"{read_only:false; response_revision:6475; number_of_response:1; }","duration":"275.200443ms","start":"2026-08-05T21:47:22.003053Z","end":"2026-08-05T21:47:22.278254Z","steps":["trace[49832631] 'process raft request' (duration: 274.887908ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:22.278289Z","caller":"traceutil/trace.go:171","msg":"trace[1734713176] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6476; }","duration":"238.458178ms","start":"2026-08-05T21:47:22.039812Z","end":"2026-08-05T21:47:22.27827Z","steps":["trace[1734713176] 'agreement among raft nodes before linearized reading' (duration: 238.317296ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:22.278211Z","caller":"traceutil/trace.go:171","msg":"trace[747520336] transaction","detail":"{read_only:false; response_revision:6476; number_of_response:1; }","duration":"104.48073ms","start":"2026-08-05T21:47:22.173704Z","end":"2026-08-05T21:47:22.278185Z","steps":["trace[747520336] 'process raft request' (duration: 104.349908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:22.27844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.916899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:22.278499Z","caller":"traceutil/trace.go:171","msg":"trace[1804092286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6476; }","duration":"104.978279ms","start":"2026-08-05T21:47:22.173507Z","end":"2026-08-05T21:47:22.278485Z","steps":["trace[1804092286] 'agreement among raft nodes before linearized reading' (duration: 104.870928ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:30.60473Z","caller":"traceutil/trace.go:171","msg":"trace[877592962] transaction","detail":"{read_only:false; response_revision:6551; number_of_response:1; }","duration":"126.935516ms","start":"2026-08-05T21:47:30.477686Z","end":"2026-08-05T21:47:30.604622Z","steps":["trace[877592962] 'process raft request' (duration: 126.710812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:31.087849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.624879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:47:31.087989Z","caller":"traceutil/trace.go:171","msg":"trace[1400019765] transaction","detail":"{read_only:false; response_revision:6555; number_of_response:1; }","duration":"199.599927ms","start":"2026-08-05T21:47:30.888345Z","end":"2026-08-05T21:47:31.087944Z","steps":["trace[1400019765] 'process raft request' (duration: 82.823225ms)","trace[1400019765] 'compare' (duration: 116.518198ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:33.820454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.453941ms","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-08-05T21:47:33.82053Z","caller":"traceutil/trace.go:171","msg":"trace[1794478340] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:6571; }","duration":"104.577643ms","start":"2026-08-05T21:47:33.715932Z","end":"2026-08-05T21:47:33.82051Z","steps":["trace[1794478340] 'range keys from in-memory index tree' (duration: 104.280238ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:34.0818Z","caller":"traceutil/trace.go:171","msg":"trace[1408934695] transaction","detail":"{read_only:false; response_revision:6572; number_of_response:1; }","duration":"256.216632ms","start":"2026-08-05T21:47:33.825559Z","end":"2026-08-05T21:47:34.081776Z","steps":["trace[1408934695] 'process raft request' (duration: 256.04845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:34.295601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.712761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:47:34.295799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.761089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:34.295877Z","caller":"traceutil/trace.go:171","msg":"trace[1446228016] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:6572; }","duration":"172.891201ms","start":"2026-08-05T21:47:34.12297Z","end":"2026-08-05T21:47:34.295861Z","steps":["trace[1446228016] 'count revisions from in-memory index tree' (duration: 172.679218ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:34.29572Z","caller":"traceutil/trace.go:171","msg":"trace[200039419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6572; }","duration":"120.889425ms","start":"2026-08-05T21:47:34.174801Z","end":"2026-08-05T21:47:34.29569Z","steps":["trace[200039419] 'range keys from in-memory index tree' (duration: 120.59868ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:34.769042Z","caller":"traceutil/trace.go:171","msg":"trace[1626420220] transaction","detail":"{read_only:false; response_revision:6574; number_of_response:1; }","duration":"268.55712ms","start":"2026-08-05T21:47:34.500463Z","end":"2026-08-05T21:47:34.76902Z","steps":["trace[1626420220] 'process raft request' (duration: 268.380567ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:34.78307Z","caller":"traceutil/trace.go:171","msg":"trace[206214175] linearizableReadLoop","detail":"{readStateIndex:6875; appliedIndex:6872; }","duration":"236.718218ms","start":"2026-08-05T21:47:34.54633Z","end":"2026-08-05T21:47:34.783049Z","steps":["trace[206214175] 'read index received' (duration: 222.579331ms)","trace[206214175] 'applied index is now lower than readState.Index' (duration: 14.138077ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:47:34.783365Z","caller":"traceutil/trace.go:171","msg":"trace[1461531512] transaction","detail":"{read_only:false; response_revision:6575; number_of_response:1; }","duration":"281.672011ms","start":"2026-08-05T21:47:34.501673Z","end":"2026-08-05T21:47:34.783345Z","steps":["trace[1461531512] 'process raft request' (duration: 281.210853ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:34.783511Z","caller":"traceutil/trace.go:171","msg":"trace[1138387286] transaction","detail":"{read_only:false; response_revision:6576; number_of_response:1; }","duration":"281.486398ms","start":"2026-08-05T21:47:34.502018Z","end":"2026-08-05T21:47:34.783505Z","steps":["trace[1138387286] 'process raft request' (duration: 280.995791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:47:34.783655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.316577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:47:34.78368Z","caller":"traceutil/trace.go:171","msg":"trace[816959462] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:6576; }","duration":"237.395239ms","start":"2026-08-05T21:47:34.546277Z","end":"2026-08-05T21:47:34.783673Z","steps":["trace[816959462] 'agreement among raft nodes before linearized reading' (duration: 237.324978ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:36.342715Z","caller":"traceutil/trace.go:171","msg":"trace[31169773] linearizableReadLoop","detail":"{readStateIndex:6884; appliedIndex:6883; }","duration":"120.880032ms","start":"2026-08-05T21:47:36.221819Z","end":"2026-08-05T21:47:36.342699Z","steps":["trace[31169773] 'read index received' (duration: 120.808461ms)","trace[31169773] 'applied index is now lower than readState.Index' (duration: 70.921µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:36.342811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.982665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:47:36.342829Z","caller":"traceutil/trace.go:171","msg":"trace[1135640294] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:6585; }","duration":"121.046206ms","start":"2026-08-05T21:47:36.221777Z","end":"2026-08-05T21:47:36.342823Z","steps":["trace[1135640294] 'agreement among raft nodes before linearized reading' (duration: 120.984864ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:36.34312Z","caller":"traceutil/trace.go:171","msg":"trace[359253248] transaction","detail":"{read_only:false; response_revision:6585; number_of_response:1; }","duration":"155.471889ms","start":"2026-08-05T21:47:36.187642Z","end":"2026-08-05T21:47:36.343114Z","steps":["trace[359253248] 'process raft request' (duration: 154.94793ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:39.755273Z","caller":"traceutil/trace.go:171","msg":"trace[1003291576] transaction","detail":"{read_only:false; response_revision:6612; number_of_response:1; }","duration":"104.414196ms","start":"2026-08-05T21:47:39.650798Z","end":"2026-08-05T21:47:39.755212Z","steps":["trace[1003291576] 'process raft request' (duration: 104.031009ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:40.645688Z","caller":"traceutil/trace.go:171","msg":"trace[1818650760] transaction","detail":"{read_only:false; response_revision:6615; number_of_response:1; }","duration":"118.827327ms","start":"2026-08-05T21:47:40.526842Z","end":"2026-08-05T21:47:40.645669Z","steps":["trace[1818650760] 'process raft request' (duration: 118.698744ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:41.021965Z","caller":"traceutil/trace.go:171","msg":"trace[1500731812] linearizableReadLoop","detail":"{readStateIndex:6918; appliedIndex:6916; }","duration":"173.607845ms","start":"2026-08-05T21:47:40.848343Z","end":"2026-08-05T21:47:41.02195Z","steps":["trace[1500731812] 'read index received' (duration: 60.163825ms)","trace[1500731812] 'applied index is now lower than readState.Index' (duration: 113.44337ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:41.02225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.888479ms","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-08-05T21:47:41.022303Z","caller":"traceutil/trace.go:171","msg":"trace[1551078052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6617; }","duration":"173.97001ms","start":"2026-08-05T21:47:40.848322Z","end":"2026-08-05T21:47:41.022292Z","steps":["trace[1551078052] 'agreement among raft nodes before linearized reading' (duration: 173.734247ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:47:41.022365Z","caller":"traceutil/trace.go:171","msg":"trace[472225819] transaction","detail":"{read_only:false; response_revision:6617; number_of_response:1; }","duration":"178.458423ms","start":"2026-08-05T21:47:40.843889Z","end":"2026-08-05T21:47:41.022347Z","steps":["trace[472225819] 'process raft request' (duration: 159.349946ms)","trace[472225819] 'compare' (duration: 18.63932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:47:41.022262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.597134ms","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-08-05T21:47:41.022494Z","caller":"traceutil/trace.go:171","msg":"trace[1235488297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6617; }","duration":"173.852348ms","start":"2026-08-05T21:47:40.84863Z","end":"2026-08-05T21:47:41.022482Z","steps":["trace[1235488297] 'agreement among raft nodes before linearized reading' (duration: 173.539163ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:48:02.339765Z","caller":"traceutil/trace.go:171","msg":"trace[1457129150] linearizableReadLoop","detail":"{readStateIndex:7122; appliedIndex:7121; }","duration":"168.135142ms","start":"2026-08-05T21:48:02.171614Z","end":"2026-08-05T21:48:02.339749Z","steps":["trace[1457129150] 'read index received' (duration: 167.98528ms)","trace[1457129150] 'applied index is now lower than readState.Index' (duration: 149.072µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:48:02.340252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.623049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:48:02.343524Z","caller":"traceutil/trace.go:171","msg":"trace[1679235819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6810; }","duration":"171.914722ms","start":"2026-08-05T21:48:02.171588Z","end":"2026-08-05T21:48:02.343502Z","steps":["trace[1679235819] 'agreement among raft nodes before linearized reading' (duration: 168.61891ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:48:02.340094Z","caller":"traceutil/trace.go:171","msg":"trace[1753141810] transaction","detail":"{read_only:false; response_revision:6810; number_of_response:1; }","duration":"178.807582ms","start":"2026-08-05T21:48:02.161272Z","end":"2026-08-05T21:48:02.34008Z","steps":["trace[1753141810] 'process raft request' (duration: 178.385376ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:48:02.514238Z","caller":"traceutil/trace.go:171","msg":"trace[1921504857] transaction","detail":"{read_only:false; response_revision:6811; number_of_response:1; }","duration":"127.308913ms","start":"2026-08-05T21:48:02.386906Z","end":"2026-08-05T21:48:02.514215Z","steps":["trace[1921504857] 'process raft request' (duration: 127.001619ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:48:02.933858Z","caller":"traceutil/trace.go:171","msg":"trace[1259103150] linearizableReadLoop","detail":"{readStateIndex:7125; appliedIndex:7124; }","duration":"166.71535ms","start":"2026-08-05T21:48:02.767124Z","end":"2026-08-05T21:48:02.933839Z","steps":["trace[1259103150] 'read index received' (duration: 166.554218ms)","trace[1259103150] 'applied index is now lower than readState.Index' (duration: 160.472µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:48:02.934181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.043405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-05T21:48:02.934208Z","caller":"traceutil/trace.go:171","msg":"trace[820814902] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:6812; }","duration":"167.104446ms","start":"2026-08-05T21:48:02.767097Z","end":"2026-08-05T21:48:02.934202Z","steps":["trace[820814902] 'agreement among raft nodes before linearized reading' (duration: 166.808611ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:48:02.93441Z","caller":"traceutil/trace.go:171","msg":"trace[1949862609] transaction","detail":"{read_only:false; response_revision:6812; number_of_response:1; }","duration":"184.251468ms","start":"2026-08-05T21:48:02.750152Z","end":"2026-08-05T21:48:02.934403Z","steps":["trace[1949862609] 'process raft request' (duration: 183.587588ms)"],"step_count":1}