{"level":"warn","ts":"2026-08-28T02:12:36.31363Z","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-28T02:12:36.31372Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.129:2380","--initial-cluster=instance=https://162.253.55.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.129: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-28T02:12:36.313786Z","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-28T02:12:36.313801Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.129:2380"]} {"level":"info","ts":"2026-08-28T02:12:36.313824Z","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-28T02:12:36.314564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"]} {"level":"info","ts":"2026-08-28T02:12:36.314649Z","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://162.253.55.129:2380"],"listen-peer-urls":["https://162.253.55.129:2380"],"advertise-client-urls":["https://162.253.55.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.129: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-28T02:12:36.321117Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.800561ms"} {"level":"info","ts":"2026-08-28T02:12:36.328123Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d232699cb6c87299","cluster-id":"f2b9115890c3fc7f"} {"level":"info","ts":"2026-08-28T02:12:36.328455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T02:12:36.328484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became follower at term 0"} {"level":"info","ts":"2026-08-28T02:12:36.328494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d232699cb6c87299 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T02:12:36.328501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became follower at term 1"} {"level":"info","ts":"2026-08-28T02:12:36.328553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=(15146284618602803865)"} {"level":"warn","ts":"2026-08-28T02:12:36.342421Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T02:12:36.345908Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T02:12:36.347704Z","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-28T02:12:36.34999Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d232699cb6c87299","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T02:12:36.350227Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d232699cb6c87299","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T02:12:36.350377Z","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-28T02:12:36.350475Z","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-28T02:12:36.350503Z","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-28T02:12:36.352794Z","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-28T02:12:36.352877Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.129:2380"} {"level":"info","ts":"2026-08-28T02:12:36.352893Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.129:2380"} {"level":"info","ts":"2026-08-28T02:12:36.353152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 switched to configuration voters=(15146284618602803865)"} {"level":"info","ts":"2026-08-28T02:12:36.353302Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f2b9115890c3fc7f","local-member-id":"d232699cb6c87299","added-peer-id":"d232699cb6c87299","added-peer-peer-urls":["https://162.253.55.129:2380"]} {"level":"info","ts":"2026-08-28T02:12:36.354141Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d232699cb6c87299","initial-advertise-peer-urls":["https://162.253.55.129:2380"],"listen-peer-urls":["https://162.253.55.129:2380"],"advertise-client-urls":["https://162.253.55.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T02:12:36.354183Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T02:12:36.728769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T02:12:36.728823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T02:12:36.728852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 received MsgPreVoteResp from d232699cb6c87299 at term 1"} {"level":"info","ts":"2026-08-28T02:12:36.72887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became candidate at term 2"} {"level":"info","ts":"2026-08-28T02:12:36.728884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 received MsgVoteResp from d232699cb6c87299 at term 2"} {"level":"info","ts":"2026-08-28T02:12:36.728902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d232699cb6c87299 became leader at term 2"} {"level":"info","ts":"2026-08-28T02:12:36.728918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d232699cb6c87299 elected leader d232699cb6c87299 at term 2"} {"level":"info","ts":"2026-08-28T02:12:36.72991Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T02:12:36.730844Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d232699cb6c87299","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.129:2379]}","request-path":"/0/members/d232699cb6c87299/attributes","cluster-id":"f2b9115890c3fc7f","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T02:12:36.730884Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T02:12:36.731595Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T02:12:36.731956Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T02:12:36.731983Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T02:12:36.73235Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f2b9115890c3fc7f","local-member-id":"d232699cb6c87299","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T02:12:36.732479Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T02:12:36.732538Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T02:12:36.73468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-28T02:12:36.735177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.129:2379"} {"level":"warn","ts":"2026-08-28T02:12:46.031597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.614703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:12:46.031708Z","caller":"traceutil/trace.go:171","msg":"trace[1011521317] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"460.237324ms","start":"2026-08-28T02:12:45.571448Z","end":"2026-08-28T02:12:46.031686Z","steps":["trace[1011521317] 'read index received' (duration: 182.909361ms)","trace[1011521317] 'applied index is now lower than readState.Index' (duration: 277.326843ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:12:46.031788Z","caller":"traceutil/trace.go:171","msg":"trace[571405011] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"489.483635ms","start":"2026-08-28T02:12:45.542291Z","end":"2026-08-28T02:12:46.031774Z","steps":["trace[571405011] 'process raft request' (duration: 212.146172ms)","trace[571405011] 'compare' (duration: 276.459501ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:12:46.03186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:12:45.542274Z","time spent":"489.554306ms","remote":"127.0.0.1:52520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T02:12:46.032097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.651769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-08-28T02:12:46.032131Z","caller":"traceutil/trace.go:171","msg":"trace[1846825248] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:361; }","duration":"460.732159ms","start":"2026-08-28T02:12:45.571389Z","end":"2026-08-28T02:12:46.032121Z","steps":["trace[1846825248] 'agreement among raft nodes before linearized reading' (duration: 460.665048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:12:46.032157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:12:45.571368Z","time spent":"460.78112ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-08-28T02:12:46.032311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.059925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-08-28T02:12:46.032341Z","caller":"traceutil/trace.go:171","msg":"trace[550752127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:361; }","duration":"411.109746ms","start":"2026-08-28T02:12:45.621223Z","end":"2026-08-28T02:12:46.032333Z","steps":["trace[550752127] 'agreement among raft nodes before linearized reading' (duration: 411.059175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:12:46.032363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:12:45.621213Z","time spent":"411.145206ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-08-28T02:12:46.032484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.935887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-08-28T02:12:46.032514Z","caller":"traceutil/trace.go:171","msg":"trace[2145755859] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:361; }","duration":"310.984099ms","start":"2026-08-28T02:12:45.721522Z","end":"2026-08-28T02:12:46.032506Z","steps":["trace[2145755859] 'agreement among raft nodes before linearized reading' (duration: 310.936488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:12:46.032513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.070652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-08-28T02:12:46.032568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.341098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-28T02:12:46.032594Z","caller":"traceutil/trace.go:171","msg":"trace[1490346151] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:361; }","duration":"360.386939ms","start":"2026-08-28T02:12:45.6722Z","end":"2026-08-28T02:12:46.032587Z","steps":["trace[1490346151] 'agreement among raft nodes before linearized reading' (duration: 360.349068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:12:46.032613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:12:45.672189Z","time spent":"360.41989ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-08-28T02:12:46.032678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.818717ms","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-28T02:12:46.03271Z","caller":"traceutil/trace.go:171","msg":"trace[238669403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:361; }","duration":"128.877678ms","start":"2026-08-28T02:12:45.903824Z","end":"2026-08-28T02:12:46.032702Z","steps":["trace[238669403] 'agreement among raft nodes before linearized reading' (duration: 128.823167ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:12:46.032566Z","caller":"traceutil/trace.go:171","msg":"trace[1474558275] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:361; }","duration":"261.154152ms","start":"2026-08-28T02:12:45.771397Z","end":"2026-08-28T02:12:46.032552Z","steps":["trace[1474558275] 'agreement among raft nodes before linearized reading' (duration: 261.064261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:12:46.032535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:12:45.721511Z","time spent":"311.018629ms","remote":"127.0.0.1:52546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-08-28T02:12:46.343461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.433328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:12:46.344215Z","caller":"traceutil/trace.go:171","msg":"trace[1384630667] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"289.047734ms","start":"2026-08-28T02:12:46.055145Z","end":"2026-08-28T02:12:46.344192Z","steps":["trace[1384630667] 'process raft request' (duration: 288.892222ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:12:46.344651Z","caller":"traceutil/trace.go:171","msg":"trace[823647630] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"289.57963ms","start":"2026-08-28T02:12:46.055053Z","end":"2026-08-28T02:12:46.344633Z","steps":["trace[823647630] 'process raft request' (duration: 288.852261ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:12:46.344749Z","caller":"traceutil/trace.go:171","msg":"trace[1525357035] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"304.815415ms","start":"2026-08-28T02:12:46.039815Z","end":"2026-08-28T02:12:46.344631Z","steps":["trace[1525357035] 'process raft request' (duration: 303.827063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:12:46.344987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:12:46.039795Z","time spent":"305.061038ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T02:12:46.345054Z","caller":"traceutil/trace.go:171","msg":"trace[1427688915] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"289.935774ms","start":"2026-08-28T02:12:46.055072Z","end":"2026-08-28T02:12:46.345008Z","steps":["trace[1427688915] 'process raft request' (duration: 288.899671ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:12:46.345051Z","caller":"traceutil/trace.go:171","msg":"trace[1743548132] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"298.842722ms","start":"2026-08-28T02:12:46.04612Z","end":"2026-08-28T02:12:46.344963Z","steps":["trace[1743548132] 'process raft request' (duration: 297.707908ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:12:46.345288Z","caller":"traceutil/trace.go:171","msg":"trace[1933854975] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"307.582669ms","start":"2026-08-28T02:12:46.037687Z","end":"2026-08-28T02:12:46.345269Z","steps":["trace[1933854975] 'process raft request' (duration: 144.267958ms)","trace[1933854975] 'compare' (duration: 159.554505ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:12:46.34537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:12:46.037661Z","time spent":"307.67372ms","remote":"127.0.0.1:52414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T02:13:19.738114Z","caller":"traceutil/trace.go:171","msg":"trace[337499117] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"236.178519ms","start":"2026-08-28T02:13:19.501903Z","end":"2026-08-28T02:13:19.738082Z","steps":["trace[337499117] 'process raft request' (duration: 235.459033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:13:20.033948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.333122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:13:20.034121Z","caller":"traceutil/trace.go:171","msg":"trace[277201261] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"272.220216ms","start":"2026-08-28T02:13:19.761878Z","end":"2026-08-28T02:13:20.034098Z","steps":["trace[277201261] 'read index received' (duration: 138.177657ms)","trace[277201261] 'applied index is now lower than readState.Index' (duration: 134.040619ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:13:20.034162Z","caller":"traceutil/trace.go:171","msg":"trace[1787029079] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"509.0576ms","start":"2026-08-28T02:13:19.525056Z","end":"2026-08-28T02:13:20.034114Z","steps":["trace[1787029079] 'process raft request' (duration: 375.012962ms)","trace[1787029079] 'compare' (duration: 133.080829ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:13:20.034331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.461108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T02:13:20.034378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:13:19.52503Z","time spent":"509.229762ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T02:13:20.034376Z","caller":"traceutil/trace.go:171","msg":"trace[2105556448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"272.563469ms","start":"2026-08-28T02:13:19.761799Z","end":"2026-08-28T02:13:20.034363Z","steps":["trace[2105556448] 'agreement among raft nodes before linearized reading' (duration: 272.461528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:13:20.034527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.481648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:13:20.034622Z","caller":"traceutil/trace.go:171","msg":"trace[745810058] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:623; }","duration":"221.614689ms","start":"2026-08-28T02:13:19.812979Z","end":"2026-08-28T02:13:20.034594Z","steps":["trace[745810058] 'agreement among raft nodes before linearized reading' (duration: 221.397337ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:14:06.76244Z","caller":"traceutil/trace.go:171","msg":"trace[1552509009] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"233.31792ms","start":"2026-08-28T02:14:06.529098Z","end":"2026-08-28T02:14:06.762416Z","steps":["trace[1552509009] 'process raft request' (duration: 233.176154ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:14:29.603294Z","caller":"traceutil/trace.go:171","msg":"trace[431710050] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"161.307192ms","start":"2026-08-28T02:14:29.441959Z","end":"2026-08-28T02:14:29.603266Z","steps":["trace[431710050] 'process raft request' (duration: 68.007812ms)","trace[431710050] 'compare' (duration: 93.186256ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:14:56.560457Z","caller":"traceutil/trace.go:171","msg":"trace[2120968790] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"288.845783ms","start":"2026-08-28T02:14:56.271576Z","end":"2026-08-28T02:14:56.560422Z","steps":["trace[2120968790] 'process raft request' (duration: 288.622948ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:14:56.920998Z","caller":"traceutil/trace.go:171","msg":"trace[1914752133] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"106.109625ms","start":"2026-08-28T02:14:56.814848Z","end":"2026-08-28T02:14:56.920957Z","steps":["trace[1914752133] 'process raft request' (duration: 105.88666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:15:35.738061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.715791ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-28T02:15:36.049802Z","caller":"traceutil/trace.go:171","msg":"trace[1611707637] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"129.958191ms","start":"2026-08-28T02:15:35.919717Z","end":"2026-08-28T02:15:36.049675Z","steps":["trace[1611707637] 'process raft request' (duration: 129.579143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:15:36.3924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.296972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:15:36.392559Z","caller":"traceutil/trace.go:171","msg":"trace[543583501] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"234.564378ms","start":"2026-08-28T02:15:36.157939Z","end":"2026-08-28T02:15:36.392504Z","steps":["trace[543583501] 'process raft request' (duration: 131.058392ms)","trace[543583501] 'compare' (duration: 101.761312ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:17:49.895393Z","caller":"traceutil/trace.go:171","msg":"trace[1340511541] linearizableReadLoop","detail":"{readStateIndex:3062; appliedIndex:3061; }","duration":"136.725826ms","start":"2026-08-28T02:17:49.758641Z","end":"2026-08-28T02:17:49.895367Z","steps":["trace[1340511541] 'read index received' (duration: 135.925106ms)","trace[1340511541] 'applied index is now lower than readState.Index' (duration: 799.88µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:17:49.895531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.882029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:17:49.89557Z","caller":"traceutil/trace.go:171","msg":"trace[183691490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2955; }","duration":"136.96385ms","start":"2026-08-28T02:17:49.758596Z","end":"2026-08-28T02:17:49.89556Z","steps":["trace[183691490] 'agreement among raft nodes before linearized reading' (duration: 136.869389ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:17:49.895559Z","caller":"traceutil/trace.go:171","msg":"trace[1556253805] transaction","detail":"{read_only:false; response_revision:2955; number_of_response:1; }","duration":"188.542055ms","start":"2026-08-28T02:17:49.706963Z","end":"2026-08-28T02:17:49.895505Z","steps":["trace[1556253805] 'process raft request' (duration: 187.662792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:17:55.452777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.677679ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-28T02:17:55.452878Z","caller":"traceutil/trace.go:171","msg":"trace[331840135] linearizableReadLoop","detail":"{readStateIndex:3091; appliedIndex:3090; }","duration":"195.164175ms","start":"2026-08-28T02:17:55.257695Z","end":"2026-08-28T02:17:55.452859Z","steps":["trace[331840135] 'read index received' (duration: 7.285344ms)","trace[331840135] 'applied index is now lower than readState.Index' (duration: 187.877051ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:17:55.45333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.622342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T02:17:55.45339Z","caller":"traceutil/trace.go:171","msg":"trace[1856520774] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2982; }","duration":"195.735433ms","start":"2026-08-28T02:17:55.25764Z","end":"2026-08-28T02:17:55.453376Z","steps":["trace[1856520774] 'agreement among raft nodes before linearized reading' (duration: 195.61228ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:29.288704Z","caller":"traceutil/trace.go:171","msg":"trace[208349894] linearizableReadLoop","detail":"{readStateIndex:3275; appliedIndex:3274; }","duration":"203.288857ms","start":"2026-08-28T02:18:29.085392Z","end":"2026-08-28T02:18:29.288681Z","steps":["trace[208349894] 'read index received' (duration: 203.143099ms)","trace[208349894] 'applied index is now lower than readState.Index' (duration: 144.688µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:29.288779Z","caller":"traceutil/trace.go:171","msg":"trace[957807104] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"218.060219ms","start":"2026-08-28T02:18:29.070696Z","end":"2026-08-28T02:18:29.288756Z","steps":["trace[957807104] 'process raft request' (duration: 217.845369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:29.288933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.523169ms","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-28T02:18:29.288977Z","caller":"traceutil/trace.go:171","msg":"trace[1883745564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3159; }","duration":"203.612862ms","start":"2026-08-28T02:18:29.08535Z","end":"2026-08-28T02:18:29.288963Z","steps":["trace[1883745564] 'agreement among raft nodes before linearized reading' (duration: 203.449345ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:29.330822Z","caller":"traceutil/trace.go:171","msg":"trace[1749307699] transaction","detail":"{read_only:false; response_revision:3160; number_of_response:1; }","duration":"135.680125ms","start":"2026-08-28T02:18:29.19511Z","end":"2026-08-28T02:18:29.33079Z","steps":["trace[1749307699] 'process raft request' (duration: 135.484656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:29.330892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.624272ms","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-28T02:18:29.33096Z","caller":"traceutil/trace.go:171","msg":"trace[1725611768] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:3161; }","duration":"231.753328ms","start":"2026-08-28T02:18:29.099187Z","end":"2026-08-28T02:18:29.33094Z","steps":["trace[1725611768] 'agreement among raft nodes before linearized reading' (duration: 231.592391ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:30.060649Z","caller":"traceutil/trace.go:171","msg":"trace[108378089] transaction","detail":"{read_only:false; response_revision:3164; number_of_response:1; }","duration":"162.172431ms","start":"2026-08-28T02:18:29.898449Z","end":"2026-08-28T02:18:30.060621Z","steps":["trace[108378089] 'process raft request' (duration: 161.974992ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:30.22206Z","caller":"traceutil/trace.go:171","msg":"trace[1804394868] linearizableReadLoop","detail":"{readStateIndex:3281; appliedIndex:3280; }","duration":"126.180711ms","start":"2026-08-28T02:18:30.095851Z","end":"2026-08-28T02:18:30.222031Z","steps":["trace[1804394868] 'read index received' (duration: 125.980512ms)","trace[1804394868] 'applied index is now lower than readState.Index' (duration: 198.969µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:30.222207Z","caller":"traceutil/trace.go:171","msg":"trace[1029129080] transaction","detail":"{read_only:false; response_revision:3165; number_of_response:1; }","duration":"128.976501ms","start":"2026-08-28T02:18:30.093219Z","end":"2026-08-28T02:18:30.222195Z","steps":["trace[1029129080] 'process raft request' (duration: 128.636995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:30.222598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.736256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-08-28T02:18:30.222655Z","caller":"traceutil/trace.go:171","msg":"trace[1939168441] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3165; }","duration":"126.844021ms","start":"2026-08-28T02:18:30.095796Z","end":"2026-08-28T02:18:30.22264Z","steps":["trace[1939168441] 'agreement among raft nodes before linearized reading' (duration: 126.655693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:30.458437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.979541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-28T02:18:30.458513Z","caller":"traceutil/trace.go:171","msg":"trace[369111741] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:3167; }","duration":"178.090597ms","start":"2026-08-28T02:18:30.280405Z","end":"2026-08-28T02:18:30.458495Z","steps":["trace[369111741] 'range keys from in-memory index tree' (duration: 177.907758ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:30.458639Z","caller":"traceutil/trace.go:171","msg":"trace[922664041] linearizableReadLoop","detail":"{readStateIndex:3285; appliedIndex:3284; }","duration":"167.602499ms","start":"2026-08-28T02:18:30.291009Z","end":"2026-08-28T02:18:30.458611Z","steps":["trace[922664041] 'read index received' (duration: 80.126879ms)","trace[922664041] 'applied index is now lower than readState.Index' (duration: 87.47397ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:30.458769Z","caller":"traceutil/trace.go:171","msg":"trace[412801929] transaction","detail":"{read_only:false; response_revision:3168; number_of_response:1; }","duration":"175.843572ms","start":"2026-08-28T02:18:30.282907Z","end":"2026-08-28T02:18:30.45875Z","steps":["trace[412801929] 'process raft request' (duration: 88.292479ms)","trace[412801929] 'compare' (duration: 87.060141ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:30.459193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.145184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-08-28T02:18:30.459392Z","caller":"traceutil/trace.go:171","msg":"trace[837081590] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3168; }","duration":"168.327932ms","start":"2026-08-28T02:18:30.290977Z","end":"2026-08-28T02:18:30.459304Z","steps":["trace[837081590] 'agreement among raft nodes before linearized reading' (duration: 168.052469ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:30.619322Z","caller":"traceutil/trace.go:171","msg":"trace[81909675] transaction","detail":"{read_only:false; response_revision:3171; number_of_response:1; }","duration":"152.644143ms","start":"2026-08-28T02:18:30.466661Z","end":"2026-08-28T02:18:30.619305Z","steps":["trace[81909675] 'process raft request' (duration: 152.562079ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:30.619321Z","caller":"traceutil/trace.go:171","msg":"trace[452665724] transaction","detail":"{read_only:false; response_revision:3170; number_of_response:1; }","duration":"155.280556ms","start":"2026-08-28T02:18:30.464016Z","end":"2026-08-28T02:18:30.619297Z","steps":["trace[452665724] 'process raft request' (duration: 100.538369ms)","trace[452665724] 'compare' (duration: 54.540107ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:31.636108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.462175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:31.636208Z","caller":"traceutil/trace.go:171","msg":"trace[484704920] linearizableReadLoop","detail":"{readStateIndex:3291; appliedIndex:3290; }","duration":"251.664792ms","start":"2026-08-28T02:18:31.384526Z","end":"2026-08-28T02:18:31.636191Z","steps":["trace[484704920] 'read index received' (duration: 81.948114ms)","trace[484704920] 'applied index is now lower than readState.Index' (duration: 169.715688ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:31.636284Z","caller":"traceutil/trace.go:171","msg":"trace[1206433729] transaction","detail":"{read_only:false; response_revision:3174; number_of_response:1; }","duration":"254.477271ms","start":"2026-08-28T02:18:31.381774Z","end":"2026-08-28T02:18:31.636251Z","steps":["trace[1206433729] 'process raft request' (duration: 84.783065ms)","trace[1206433729] 'compare' (duration: 169.292797ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:31.636341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.821198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18cfd686c36cf3aa\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-08-28T02:18:31.63643Z","caller":"traceutil/trace.go:171","msg":"trace[2092847789] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18cfd686c36cf3aa; range_end:; response_count:1; response_revision:3174; }","duration":"251.8735ms","start":"2026-08-28T02:18:31.384487Z","end":"2026-08-28T02:18:31.636361Z","steps":["trace[2092847789] 'agreement among raft nodes before linearized reading' (duration: 251.744745ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:32.33807Z","caller":"traceutil/trace.go:171","msg":"trace[1877961788] transaction","detail":"{read_only:false; response_revision:3177; number_of_response:1; }","duration":"110.446017ms","start":"2026-08-28T02:18:32.227593Z","end":"2026-08-28T02:18:32.338039Z","steps":["trace[1877961788] 'process raft request' (duration: 71.900001ms)","trace[1877961788] 'compare' (duration: 38.302215ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:32.703229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.096052ms","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-28T02:18:32.703312Z","caller":"traceutil/trace.go:171","msg":"trace[1726472849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3178; }","duration":"229.210467ms","start":"2026-08-28T02:18:32.474081Z","end":"2026-08-28T02:18:32.703291Z","steps":["trace[1726472849] 'range keys from in-memory index tree' (duration: 228.941215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:32.703271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.086398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-28T02:18:32.703759Z","caller":"traceutil/trace.go:171","msg":"trace[1905926904] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3178; }","duration":"240.585833ms","start":"2026-08-28T02:18:32.463147Z","end":"2026-08-28T02:18:32.703733Z","steps":["trace[1905926904] 'range keys from in-memory index tree' (duration: 239.975953ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:34.510192Z","caller":"traceutil/trace.go:171","msg":"trace[432559117] linearizableReadLoop","detail":"{readStateIndex:3304; appliedIndex:3303; }","duration":"154.138389ms","start":"2026-08-28T02:18:34.356027Z","end":"2026-08-28T02:18:34.510165Z","steps":["trace[432559117] 'read index received' (duration: 153.93289ms)","trace[432559117] 'applied index is now lower than readState.Index' (duration: 204.349µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:34.510476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.424153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-28T02:18:34.510589Z","caller":"traceutil/trace.go:171","msg":"trace[2060364946] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3187; }","duration":"154.57649ms","start":"2026-08-28T02:18:34.355988Z","end":"2026-08-28T02:18:34.510565Z","steps":["trace[2060364946] 'agreement among raft nodes before linearized reading' (duration: 154.284355ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:34.510324Z","caller":"traceutil/trace.go:171","msg":"trace[25814851] transaction","detail":"{read_only:false; response_revision:3187; number_of_response:1; }","duration":"167.795851ms","start":"2026-08-28T02:18:34.342452Z","end":"2026-08-28T02:18:34.510248Z","steps":["trace[25814851] 'process raft request' (duration: 167.565661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:35.017203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.645696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:35.017388Z","caller":"traceutil/trace.go:171","msg":"trace[1308219810] transaction","detail":"{read_only:false; response_revision:3191; number_of_response:1; }","duration":"207.970621ms","start":"2026-08-28T02:18:34.809386Z","end":"2026-08-28T02:18:35.017357Z","steps":["trace[1308219810] 'process raft request' (duration: 50.091134ms)","trace[1308219810] 'compare' (duration: 157.368774ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:36.90714Z","caller":"traceutil/trace.go:171","msg":"trace[1418594979] linearizableReadLoop","detail":"{readStateIndex:3315; appliedIndex:3314; }","duration":"353.359745ms","start":"2026-08-28T02:18:36.553757Z","end":"2026-08-28T02:18:36.907117Z","steps":["trace[1418594979] 'read index received' (duration: 353.288911ms)","trace[1418594979] 'applied index is now lower than readState.Index' (duration: 69.584µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:36.907245Z","caller":"traceutil/trace.go:171","msg":"trace[1079361998] transaction","detail":"{read_only:false; response_revision:3197; number_of_response:1; }","duration":"390.884408ms","start":"2026-08-28T02:18:36.516347Z","end":"2026-08-28T02:18:36.907231Z","steps":["trace[1079361998] 'process raft request' (duration: 390.620526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:36.907356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:36.516323Z","time spent":"390.951732ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T02:18:36.907771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.994762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-08-28T02:18:36.90793Z","caller":"traceutil/trace.go:171","msg":"trace[957005950] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3197; }","duration":"354.193782ms","start":"2026-08-28T02:18:36.553707Z","end":"2026-08-28T02:18:36.907901Z","steps":["trace[957005950] 'agreement among raft nodes before linearized reading' (duration: 353.597855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:36.908334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:36.553689Z","time spent":"354.343879ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-28T02:18:37.005622Z","caller":"traceutil/trace.go:171","msg":"trace[1721236649] transaction","detail":"{read_only:false; response_revision:3198; number_of_response:1; }","duration":"234.379772ms","start":"2026-08-28T02:18:36.771198Z","end":"2026-08-28T02:18:37.005578Z","steps":["trace[1721236649] 'process raft request' (duration: 234.208704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:37.200959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.668829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-28T02:18:37.200955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.894451ms","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-28T02:18:37.201074Z","caller":"traceutil/trace.go:171","msg":"trace[597783195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3198; }","duration":"431.044248ms","start":"2026-08-28T02:18:36.770008Z","end":"2026-08-28T02:18:37.201052Z","steps":["trace[597783195] 'agreement among raft nodes before linearized reading' (duration: 235.566986ms)","trace[597783195] 'range keys from in-memory index tree' (duration: 195.271183ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:37.201129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:36.769964Z","time spent":"431.151463ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-28T02:18:37.201078Z","caller":"traceutil/trace.go:171","msg":"trace[635366230] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3198; }","duration":"383.835177ms","start":"2026-08-28T02:18:36.817213Z","end":"2026-08-28T02:18:37.201048Z","steps":["trace[635366230] 'agreement among raft nodes before linearized reading' (duration: 188.388756ms)","trace[635366230] 'range keys from in-memory index tree' (duration: 195.208551ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:37.201482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:36.817201Z","time spent":"384.262205ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-28T02:18:37.200966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.546732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:37.201714Z","caller":"traceutil/trace.go:171","msg":"trace[1492969752] linearizableReadLoop","detail":"{readStateIndex:3317; appliedIndex:3316; }","duration":"180.537981ms","start":"2026-08-28T02:18:37.02116Z","end":"2026-08-28T02:18:37.201698Z","steps":["trace[1492969752] 'read index received' (duration: 3.134901ms)","trace[1492969752] 'applied index is now lower than readState.Index' (duration: 177.40109ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:37.201728Z","caller":"traceutil/trace.go:171","msg":"trace[951923077] transaction","detail":"{read_only:false; response_revision:3199; number_of_response:1; }","duration":"289.470235ms","start":"2026-08-28T02:18:36.912237Z","end":"2026-08-28T02:18:37.201708Z","steps":["trace[951923077] 'process raft request' (duration: 112.115617ms)","trace[951923077] 'compare' (duration: 176.322912ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:37.201853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.693647ms","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-28T02:18:37.201886Z","caller":"traceutil/trace.go:171","msg":"trace[1151798385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3199; }","duration":"180.761061ms","start":"2026-08-28T02:18:37.021116Z","end":"2026-08-28T02:18:37.201877Z","steps":["trace[1151798385] 'agreement among raft nodes before linearized reading' (duration: 180.629725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:37.738531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.949362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:37.738912Z","caller":"traceutil/trace.go:171","msg":"trace[572950419] transaction","detail":"{read_only:false; response_revision:3202; number_of_response:1; }","duration":"531.842494ms","start":"2026-08-28T02:18:37.207051Z","end":"2026-08-28T02:18:37.738893Z","steps":["trace[572950419] 'process raft request' (duration: 531.673067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:37.739016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:37.207038Z","time spent":"531.922717ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T02:18:37.738949Z","caller":"traceutil/trace.go:171","msg":"trace[317211605] transaction","detail":"{read_only:false; response_revision:3201; number_of_response:1; }","duration":"533.091799ms","start":"2026-08-28T02:18:37.205822Z","end":"2026-08-28T02:18:37.738913Z","steps":["trace[317211605] 'process raft request' (duration: 116.88975ms)","trace[317211605] 'compare' (duration: 414.67764ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:37.739249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:37.205813Z","time spent":"533.355771ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T02:18:37.740186Z","caller":"traceutil/trace.go:171","msg":"trace[347400035] linearizableReadLoop","detail":"{readStateIndex:3321; appliedIndex:3320; }","duration":"198.796381ms","start":"2026-08-28T02:18:37.541372Z","end":"2026-08-28T02:18:37.740168Z","steps":["trace[347400035] 'read index received' (duration: 198.645324ms)","trace[347400035] 'applied index is now lower than readState.Index' (duration: 150.037µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:37.74032Z","caller":"traceutil/trace.go:171","msg":"trace[1723432475] transaction","detail":"{read_only:false; response_revision:3203; number_of_response:1; }","duration":"212.223063ms","start":"2026-08-28T02:18:37.528081Z","end":"2026-08-28T02:18:37.740304Z","steps":["trace[1723432475] 'process raft request' (duration: 211.989193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:37.740603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.234652ms","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-28T02:18:37.74066Z","caller":"traceutil/trace.go:171","msg":"trace[237305026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3203; }","duration":"199.322905ms","start":"2026-08-28T02:18:37.541323Z","end":"2026-08-28T02:18:37.740646Z","steps":["trace[237305026] 'agreement among raft nodes before linearized reading' (duration: 199.191399ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:38.130264Z","caller":"traceutil/trace.go:171","msg":"trace[1389004746] linearizableReadLoop","detail":"{readStateIndex:3323; appliedIndex:3321; }","duration":"372.010006ms","start":"2026-08-28T02:18:37.758232Z","end":"2026-08-28T02:18:38.130242Z","steps":["trace[1389004746] 'read index received' (duration: 289.580961ms)","trace[1389004746] 'applied index is now lower than readState.Index' (duration: 82.427755ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:38.130309Z","caller":"traceutil/trace.go:171","msg":"trace[1372217943] transaction","detail":"{read_only:false; response_revision:3204; number_of_response:1; }","duration":"385.648819ms","start":"2026-08-28T02:18:37.744637Z","end":"2026-08-28T02:18:38.130286Z","steps":["trace[1372217943] 'process raft request' (duration: 303.084828ms)","trace[1372217943] 'compare' (duration: 82.349092ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:38.130431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:37.744617Z","time spent":"385.747954ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T02:18:38.130433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.186994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:18:38.130477Z","caller":"traceutil/trace.go:171","msg":"trace[1081705924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3205; }","duration":"372.260527ms","start":"2026-08-28T02:18:37.758203Z","end":"2026-08-28T02:18:38.130464Z","steps":["trace[1081705924] 'agreement among raft nodes before linearized reading' (duration: 372.164543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:38.130513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:37.758191Z","time spent":"372.312329ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-28T02:18:38.130423Z","caller":"traceutil/trace.go:171","msg":"trace[1393171099] transaction","detail":"{read_only:false; response_revision:3205; number_of_response:1; }","duration":"385.311654ms","start":"2026-08-28T02:18:37.745063Z","end":"2026-08-28T02:18:38.130375Z","steps":["trace[1393171099] 'process raft request' (duration: 385.119996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:38.130915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:37.745049Z","time spent":"385.695241ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T02:18:38.626146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.719827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:38.62624Z","caller":"traceutil/trace.go:171","msg":"trace[2034615116] linearizableReadLoop","detail":"{readStateIndex:3324; appliedIndex:3323; }","duration":"232.965315ms","start":"2026-08-28T02:18:38.393256Z","end":"2026-08-28T02:18:38.626222Z","steps":["trace[2034615116] 'read index received' (duration: 36.032µs)","trace[2034615116] 'applied index is now lower than readState.Index' (duration: 232.928253ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:38.626317Z","caller":"traceutil/trace.go:171","msg":"trace[1533546064] transaction","detail":"{read_only:false; response_revision:3206; number_of_response:1; }","duration":"419.219652ms","start":"2026-08-28T02:18:38.207086Z","end":"2026-08-28T02:18:38.626305Z","steps":["trace[1533546064] 'process raft request' (duration: 94.268205ms)","trace[1533546064] 'compare' (duration: 324.597121ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:38.626377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:38.207059Z","time spent":"419.287974ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T02:18:38.626525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.316152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113169"} {"level":"warn","ts":"2026-08-28T02:18:38.62652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.247607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-28T02:18:38.626612Z","caller":"traceutil/trace.go:171","msg":"trace[901980950] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:3206; }","duration":"231.434086ms","start":"2026-08-28T02:18:38.395155Z","end":"2026-08-28T02:18:38.62659Z","steps":["trace[901980950] 'agreement among raft nodes before linearized reading' (duration: 231.174995ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:38.626765Z","caller":"traceutil/trace.go:171","msg":"trace[734351134] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:3206; }","duration":"233.488229ms","start":"2026-08-28T02:18:38.393204Z","end":"2026-08-28T02:18:38.626692Z","steps":["trace[734351134] 'agreement among raft nodes before linearized reading' (duration: 233.123783ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:39.35285Z","caller":"traceutil/trace.go:171","msg":"trace[1560016509] linearizableReadLoop","detail":"{readStateIndex:3326; appliedIndex:3325; }","duration":"124.08418ms","start":"2026-08-28T02:18:39.228746Z","end":"2026-08-28T02:18:39.35283Z","steps":["trace[1560016509] 'read index received' (duration: 123.86887ms)","trace[1560016509] 'applied index is now lower than readState.Index' (duration: 214.43µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:39.352894Z","caller":"traceutil/trace.go:171","msg":"trace[755968465] transaction","detail":"{read_only:false; response_revision:3208; number_of_response:1; }","duration":"143.503674ms","start":"2026-08-28T02:18:39.209368Z","end":"2026-08-28T02:18:39.352871Z","steps":["trace[755968465] 'process raft request' (duration: 143.315595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:39.353091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.33423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-28T02:18:39.353133Z","caller":"traceutil/trace.go:171","msg":"trace[687051563] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3208; }","duration":"124.422825ms","start":"2026-08-28T02:18:39.228698Z","end":"2026-08-28T02:18:39.35312Z","steps":["trace[687051563] 'agreement among raft nodes before linearized reading' (duration: 124.246787ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:39.532921Z","caller":"traceutil/trace.go:171","msg":"trace[624251735] transaction","detail":"{read_only:false; response_revision:3209; number_of_response:1; }","duration":"175.514677ms","start":"2026-08-28T02:18:39.357362Z","end":"2026-08-28T02:18:39.532876Z","steps":["trace[624251735] 'process raft request' (duration: 150.198541ms)","trace[624251735] 'compare' (duration: 25.155699ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:39.572055Z","caller":"traceutil/trace.go:171","msg":"trace[1029887467] transaction","detail":"{read_only:false; response_revision:3210; number_of_response:1; }","duration":"201.272484ms","start":"2026-08-28T02:18:39.370744Z","end":"2026-08-28T02:18:39.572016Z","steps":["trace[1029887467] 'process raft request' (duration: 201.155669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:40.011109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.639034ms","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-28T02:18:40.011279Z","caller":"traceutil/trace.go:171","msg":"trace[1011233619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3210; }","duration":"267.866824ms","start":"2026-08-28T02:18:39.743393Z","end":"2026-08-28T02:18:40.01126Z","steps":["trace[1011233619] 'range keys from in-memory index tree' (duration: 267.421403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:40.011359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.898842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:40.011428Z","caller":"traceutil/trace.go:171","msg":"trace[1815621926] linearizableReadLoop","detail":"{readStateIndex:3329; appliedIndex:3328; }","duration":"253.089026ms","start":"2026-08-28T02:18:39.758324Z","end":"2026-08-28T02:18:40.011413Z","steps":["trace[1815621926] 'read index received' (duration: 46.009756ms)","trace[1815621926] 'applied index is now lower than readState.Index' (duration: 207.07825ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:40.011494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.167169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:18:40.011539Z","caller":"traceutil/trace.go:171","msg":"trace[108222339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3211; }","duration":"253.231502ms","start":"2026-08-28T02:18:39.758299Z","end":"2026-08-28T02:18:40.011531Z","steps":["trace[108222339] 'agreement among raft nodes before linearized reading' (duration: 253.152479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:40.011142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.721468ms","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-28T02:18:40.011984Z","caller":"traceutil/trace.go:171","msg":"trace[1366572260] transaction","detail":"{read_only:false; response_revision:3211; number_of_response:1; }","duration":"266.9173ms","start":"2026-08-28T02:18:39.745054Z","end":"2026-08-28T02:18:40.011971Z","steps":["trace[1366572260] 'process raft request' (duration: 59.350379ms)","trace[1366572260] 'compare' (duration: 206.554727ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:40.012019Z","caller":"traceutil/trace.go:171","msg":"trace[1655475310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3210; }","duration":"268.646339ms","start":"2026-08-28T02:18:39.743355Z","end":"2026-08-28T02:18:40.012001Z","steps":["trace[1655475310] 'range keys from in-memory index tree' (duration: 267.512057ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:40.405537Z","caller":"traceutil/trace.go:171","msg":"trace[345263530] transaction","detail":"{read_only:false; response_revision:3214; number_of_response:1; }","duration":"214.661022ms","start":"2026-08-28T02:18:40.190856Z","end":"2026-08-28T02:18:40.405517Z","steps":["trace[345263530] 'process raft request' (duration: 214.303857ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:40.405659Z","caller":"traceutil/trace.go:171","msg":"trace[600706107] transaction","detail":"{read_only:false; response_revision:3215; number_of_response:1; }","duration":"213.473289ms","start":"2026-08-28T02:18:40.192162Z","end":"2026-08-28T02:18:40.405635Z","steps":["trace[600706107] 'process raft request' (duration: 213.310021ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:40.484398Z","caller":"traceutil/trace.go:171","msg":"trace[1026541665] transaction","detail":"{read_only:false; response_revision:3216; number_of_response:1; }","duration":"202.738084ms","start":"2026-08-28T02:18:40.28164Z","end":"2026-08-28T02:18:40.484378Z","steps":["trace[1026541665] 'process raft request' (duration: 202.629739ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:40.874626Z","caller":"traceutil/trace.go:171","msg":"trace[520485225] linearizableReadLoop","detail":"{readStateIndex:3337; appliedIndex:3336; }","duration":"116.867252ms","start":"2026-08-28T02:18:40.757734Z","end":"2026-08-28T02:18:40.874601Z","steps":["trace[520485225] 'read index received' (duration: 29.857841ms)","trace[520485225] 'applied index is now lower than readState.Index' (duration: 87.008271ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:40.874751Z","caller":"traceutil/trace.go:171","msg":"trace[1857203124] transaction","detail":"{read_only:false; response_revision:3218; number_of_response:1; }","duration":"136.876909ms","start":"2026-08-28T02:18:40.737838Z","end":"2026-08-28T02:18:40.874715Z","steps":["trace[1857203124] 'process raft request' (duration: 49.752493ms)","trace[1857203124] 'compare' (duration: 86.856465ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:40.874932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.181547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T02:18:40.874987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.427263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:18:40.87502Z","caller":"traceutil/trace.go:171","msg":"trace[1333272525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3218; }","duration":"116.498626ms","start":"2026-08-28T02:18:40.758511Z","end":"2026-08-28T02:18:40.87501Z","steps":["trace[1333272525] 'agreement among raft nodes before linearized reading' (duration: 116.419863ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:40.875Z","caller":"traceutil/trace.go:171","msg":"trace[1350719837] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3218; }","duration":"117.298121ms","start":"2026-08-28T02:18:40.757683Z","end":"2026-08-28T02:18:40.874981Z","steps":["trace[1350719837] 'agreement among raft nodes before linearized reading' (duration: 117.0486ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:41.091959Z","caller":"traceutil/trace.go:171","msg":"trace[1438204813] linearizableReadLoop","detail":"{readStateIndex:3338; appliedIndex:3337; }","duration":"112.633806ms","start":"2026-08-28T02:18:40.979304Z","end":"2026-08-28T02:18:41.091938Z","steps":["trace[1438204813] 'read index received' (duration: 53.988943ms)","trace[1438204813] 'applied index is now lower than readState.Index' (duration: 58.643893ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:41.092119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.789603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T02:18:41.092157Z","caller":"traceutil/trace.go:171","msg":"trace[841578768] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3219; }","duration":"112.848075ms","start":"2026-08-28T02:18:40.979298Z","end":"2026-08-28T02:18:41.092147Z","steps":["trace[841578768] 'agreement among raft nodes before linearized reading' (duration: 112.72512ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:41.092086Z","caller":"traceutil/trace.go:171","msg":"trace[986962673] transaction","detail":"{read_only:false; response_revision:3219; number_of_response:1; }","duration":"129.873459ms","start":"2026-08-28T02:18:40.962162Z","end":"2026-08-28T02:18:41.092035Z","steps":["trace[986962673] 'process raft request' (duration: 71.217465ms)","trace[986962673] 'compare' (duration: 58.448935ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:41.522318Z","caller":"traceutil/trace.go:171","msg":"trace[1874231377] linearizableReadLoop","detail":"{readStateIndex:3339; appliedIndex:3338; }","duration":"138.26747ms","start":"2026-08-28T02:18:41.38403Z","end":"2026-08-28T02:18:41.522298Z","steps":["trace[1874231377] 'read index received' (duration: 138.193197ms)","trace[1874231377] 'applied index is now lower than readState.Index' (duration: 73.263µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:41.522391Z","caller":"traceutil/trace.go:171","msg":"trace[1226539891] transaction","detail":"{read_only:false; response_revision:3220; number_of_response:1; }","duration":"160.033369ms","start":"2026-08-28T02:18:41.36233Z","end":"2026-08-28T02:18:41.522364Z","steps":["trace[1226539891] 'process raft request' (duration: 159.84215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:41.522476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.440939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18cfd686c36cf3aa\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-08-28T02:18:41.522512Z","caller":"traceutil/trace.go:171","msg":"trace[747233602] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18cfd686c36cf3aa; range_end:; response_count:1; response_revision:3220; }","duration":"138.531323ms","start":"2026-08-28T02:18:41.383971Z","end":"2026-08-28T02:18:41.522503Z","steps":["trace[747233602] 'agreement among raft nodes before linearized reading' (duration: 138.407296ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:41.793752Z","caller":"traceutil/trace.go:171","msg":"trace[171202438] linearizableReadLoop","detail":"{readStateIndex:3340; appliedIndex:3339; }","duration":"256.604312ms","start":"2026-08-28T02:18:41.537122Z","end":"2026-08-28T02:18:41.793726Z","steps":["trace[171202438] 'read index received' (duration: 163.900399ms)","trace[171202438] 'applied index is now lower than readState.Index' (duration: 92.702753ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:41.793784Z","caller":"traceutil/trace.go:171","msg":"trace[395723751] transaction","detail":"{read_only:false; response_revision:3221; number_of_response:1; }","duration":"268.971377ms","start":"2026-08-28T02:18:41.524781Z","end":"2026-08-28T02:18:41.793752Z","steps":["trace[395723751] 'process raft request' (duration: 176.218782ms)","trace[395723751] 'compare' (duration: 92.604239ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:41.793964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.819012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-28T02:18:41.794285Z","caller":"traceutil/trace.go:171","msg":"trace[123877572] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3221; }","duration":"257.2141ms","start":"2026-08-28T02:18:41.537056Z","end":"2026-08-28T02:18:41.79427Z","steps":["trace[123877572] 'agreement among raft nodes before linearized reading' (duration: 256.763459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:42.234279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.557686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:42.234381Z","caller":"traceutil/trace.go:171","msg":"trace[1971253360] linearizableReadLoop","detail":"{readStateIndex:3343; appliedIndex:3342; }","duration":"160.906234ms","start":"2026-08-28T02:18:42.073459Z","end":"2026-08-28T02:18:42.234365Z","steps":["trace[1971253360] 'read index received' (duration: 45.139508ms)","trace[1971253360] 'applied index is now lower than readState.Index' (duration: 115.765596ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:42.234442Z","caller":"traceutil/trace.go:171","msg":"trace[1167172714] transaction","detail":"{read_only:false; response_revision:3224; number_of_response:1; }","duration":"173.549917ms","start":"2026-08-28T02:18:42.06086Z","end":"2026-08-28T02:18:42.234409Z","steps":["trace[1167172714] 'process raft request' (duration: 57.782152ms)","trace[1167172714] 'compare' (duration: 115.445781ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:42.234568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.920305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"warn","ts":"2026-08-28T02:18:42.23458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.090081ms","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-28T02:18:42.234607Z","caller":"traceutil/trace.go:171","msg":"trace[1874516662] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3224; }","duration":"142.976327ms","start":"2026-08-28T02:18:42.091618Z","end":"2026-08-28T02:18:42.234595Z","steps":["trace[1874516662] 'agreement among raft nodes before linearized reading' (duration: 142.83331ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:42.234643Z","caller":"traceutil/trace.go:171","msg":"trace[2091491333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3224; }","duration":"161.191486ms","start":"2026-08-28T02:18:42.073432Z","end":"2026-08-28T02:18:42.234623Z","steps":["trace[2091491333] 'agreement among raft nodes before linearized reading' (duration: 161.001328ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:42.415071Z","caller":"traceutil/trace.go:171","msg":"trace[1541577361] linearizableReadLoop","detail":"{readStateIndex:3344; appliedIndex:3343; }","duration":"174.927927ms","start":"2026-08-28T02:18:42.240122Z","end":"2026-08-28T02:18:42.41505Z","steps":["trace[1541577361] 'read index received' (duration: 120.535073ms)","trace[1541577361] 'applied index is now lower than readState.Index' (duration: 54.391754ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:42.415236Z","caller":"traceutil/trace.go:171","msg":"trace[1972758399] transaction","detail":"{read_only:false; response_revision:3225; number_of_response:1; }","duration":"176.265347ms","start":"2026-08-28T02:18:42.238865Z","end":"2026-08-28T02:18:42.415131Z","steps":["trace[1972758399] 'process raft request' (duration: 121.788869ms)","trace[1972758399] 'compare' (duration: 54.262028ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:42.415421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.253341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-08-28T02:18:42.415484Z","caller":"traceutil/trace.go:171","msg":"trace[1527538265] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3225; }","duration":"175.364936ms","start":"2026-08-28T02:18:42.2401Z","end":"2026-08-28T02:18:42.415465Z","steps":["trace[1527538265] 'agreement among raft nodes before linearized reading' (duration: 175.121246ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:42.656071Z","caller":"traceutil/trace.go:171","msg":"trace[2116404993] transaction","detail":"{read_only:false; response_revision:3227; number_of_response:1; }","duration":"236.386912ms","start":"2026-08-28T02:18:42.419655Z","end":"2026-08-28T02:18:42.656042Z","steps":["trace[2116404993] 'process raft request' (duration: 176.927236ms)","trace[2116404993] 'compare' (duration: 59.178193ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:42.963469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.040937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:42.963588Z","caller":"traceutil/trace.go:171","msg":"trace[10874628] linearizableReadLoop","detail":"{readStateIndex:3347; appliedIndex:3346; }","duration":"205.76739ms","start":"2026-08-28T02:18:42.7578Z","end":"2026-08-28T02:18:42.963567Z","steps":["trace[10874628] 'read index received' (duration: 21.547615ms)","trace[10874628] 'applied index is now lower than readState.Index' (duration: 184.218155ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:42.963673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.873165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:18:42.96372Z","caller":"traceutil/trace.go:171","msg":"trace[799137063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3228; }","duration":"205.943368ms","start":"2026-08-28T02:18:42.75776Z","end":"2026-08-28T02:18:42.963703Z","steps":["trace[799137063] 'agreement among raft nodes before linearized reading' (duration: 205.873035ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:42.963691Z","caller":"traceutil/trace.go:171","msg":"trace[1243936815] transaction","detail":"{read_only:false; response_revision:3228; number_of_response:1; }","duration":"272.932693ms","start":"2026-08-28T02:18:42.690735Z","end":"2026-08-28T02:18:42.963667Z","steps":["trace[1243936815] 'process raft request' (duration: 88.576422ms)","trace[1243936815] 'compare' (duration: 183.924783ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:43.201055Z","caller":"traceutil/trace.go:171","msg":"trace[2037863362] transaction","detail":"{read_only:false; response_revision:3229; number_of_response:1; }","duration":"103.961354ms","start":"2026-08-28T02:18:43.097062Z","end":"2026-08-28T02:18:43.201023Z","steps":["trace[2037863362] 'process raft request' (duration: 62.83703ms)","trace[2037863362] 'compare' (duration: 41.03079ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:44.015389Z","caller":"traceutil/trace.go:171","msg":"trace[457454709] transaction","detail":"{read_only:false; response_revision:3231; number_of_response:1; }","duration":"115.092798ms","start":"2026-08-28T02:18:43.900272Z","end":"2026-08-28T02:18:44.015364Z","steps":["trace[457454709] 'process raft request' (duration: 114.941831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:44.51756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.551655ms","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-28T02:18:44.517658Z","caller":"traceutil/trace.go:171","msg":"trace[428472199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3232; }","duration":"279.67947ms","start":"2026-08-28T02:18:44.237963Z","end":"2026-08-28T02:18:44.517642Z","steps":["trace[428472199] 'range keys from in-memory index tree' (duration: 279.427499ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:44.72033Z","caller":"traceutil/trace.go:171","msg":"trace[53077933] linearizableReadLoop","detail":"{readStateIndex:3354; appliedIndex:3352; }","duration":"160.36546ms","start":"2026-08-28T02:18:44.559941Z","end":"2026-08-28T02:18:44.720307Z","steps":["trace[53077933] 'read index received' (duration: 66.083927ms)","trace[53077933] 'applied index is now lower than readState.Index' (duration: 94.280403ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:44.720484Z","caller":"traceutil/trace.go:171","msg":"trace[2138097895] transaction","detail":"{read_only:false; response_revision:3234; number_of_response:1; }","duration":"199.141864ms","start":"2026-08-28T02:18:44.521324Z","end":"2026-08-28T02:18:44.720466Z","steps":["trace[2138097895] 'process raft request' (duration: 104.740006ms)","trace[2138097895] 'compare' (duration: 93.943139ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:44.720678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.704746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-28T02:18:44.720762Z","caller":"traceutil/trace.go:171","msg":"trace[361224622] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3235; }","duration":"160.818171ms","start":"2026-08-28T02:18:44.559913Z","end":"2026-08-28T02:18:44.720732Z","steps":["trace[361224622] 'agreement among raft nodes before linearized reading' (duration: 160.634033ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:44.720881Z","caller":"traceutil/trace.go:171","msg":"trace[647234650] transaction","detail":"{read_only:false; response_revision:3235; number_of_response:1; }","duration":"199.304702ms","start":"2026-08-28T02:18:44.521566Z","end":"2026-08-28T02:18:44.72087Z","steps":["trace[647234650] 'process raft request' (duration: 198.663134ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:44.957691Z","caller":"traceutil/trace.go:171","msg":"trace[332401773] transaction","detail":"{read_only:false; response_revision:3236; number_of_response:1; }","duration":"232.517813ms","start":"2026-08-28T02:18:44.725142Z","end":"2026-08-28T02:18:44.95766Z","steps":["trace[332401773] 'process raft request' (duration: 231.965199ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:44.976991Z","caller":"traceutil/trace.go:171","msg":"trace[802645902] linearizableReadLoop","detail":"{readStateIndex:3356; appliedIndex:3354; }","duration":"219.184215ms","start":"2026-08-28T02:18:44.757789Z","end":"2026-08-28T02:18:44.976973Z","steps":["trace[802645902] 'read index received' (duration: 199.453149ms)","trace[802645902] 'applied index is now lower than readState.Index' (duration: 19.730416ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:44.977153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.323691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:18:44.97722Z","caller":"traceutil/trace.go:171","msg":"trace[1779081130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3237; }","duration":"219.460327ms","start":"2026-08-28T02:18:44.757742Z","end":"2026-08-28T02:18:44.977202Z","steps":["trace[1779081130] 'agreement among raft nodes before linearized reading' (duration: 219.315171ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:44.977679Z","caller":"traceutil/trace.go:171","msg":"trace[555855576] transaction","detail":"{read_only:false; response_revision:3237; number_of_response:1; }","duration":"252.325903ms","start":"2026-08-28T02:18:44.725328Z","end":"2026-08-28T02:18:44.977654Z","steps":["trace[555855576] 'process raft request' (duration: 251.56021ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:45.20463Z","caller":"traceutil/trace.go:171","msg":"trace[250857449] transaction","detail":"{read_only:false; response_revision:3238; number_of_response:1; }","duration":"222.315017ms","start":"2026-08-28T02:18:44.982277Z","end":"2026-08-28T02:18:45.204592Z","steps":["trace[250857449] 'process raft request' (duration: 130.608355ms)","trace[250857449] 'compare' (duration: 91.408299ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:45.574893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.007692ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-28T02:18:45.575009Z","caller":"traceutil/trace.go:171","msg":"trace[846111614] linearizableReadLoop","detail":"{readStateIndex:3359; appliedIndex:3358; }","duration":"117.244995ms","start":"2026-08-28T02:18:45.457743Z","end":"2026-08-28T02:18:45.574988Z","steps":["trace[846111614] 'read index received' (duration: 58.543µs)","trace[846111614] 'applied index is now lower than readState.Index' (duration: 117.184862ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:45.575198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.444923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T02:18:45.575254Z","caller":"traceutil/trace.go:171","msg":"trace[1719544608] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:3239; }","duration":"117.562688ms","start":"2026-08-28T02:18:45.457681Z","end":"2026-08-28T02:18:45.575244Z","steps":["trace[1719544608] 'agreement among raft nodes before linearized reading' (duration: 117.405182ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:45.760338Z","caller":"traceutil/trace.go:171","msg":"trace[227038332] transaction","detail":"{read_only:false; response_revision:3241; number_of_response:1; }","duration":"122.782234ms","start":"2026-08-28T02:18:45.637526Z","end":"2026-08-28T02:18:45.760308Z","steps":["trace[227038332] 'process raft request' (duration: 92.116179ms)","trace[227038332] 'compare' (duration: 30.535179ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:46.340024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.373787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:46.340192Z","caller":"traceutil/trace.go:171","msg":"trace[181026355] linearizableReadLoop","detail":"{readStateIndex:3363; appliedIndex:3362; }","duration":"244.884277ms","start":"2026-08-28T02:18:46.095282Z","end":"2026-08-28T02:18:46.340166Z","steps":["trace[181026355] 'read index received' (duration: 73.254439ms)","trace[181026355] 'applied index is now lower than readState.Index' (duration: 171.627958ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:46.34022Z","caller":"traceutil/trace.go:171","msg":"trace[1772346902] transaction","detail":"{read_only:false; response_revision:3243; number_of_response:1; }","duration":"247.96885ms","start":"2026-08-28T02:18:46.092212Z","end":"2026-08-28T02:18:46.340181Z","steps":["trace[1772346902] 'process raft request' (duration: 76.322811ms)","trace[1772346902] 'compare' (duration: 171.231391ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:46.340612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.289085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-08-28T02:18:46.340672Z","caller":"traceutil/trace.go:171","msg":"trace[1382234200] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3243; }","duration":"245.40927ms","start":"2026-08-28T02:18:46.095245Z","end":"2026-08-28T02:18:46.340654Z","steps":["trace[1382234200] 'agreement among raft nodes before linearized reading' (duration: 245.012633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:46.920838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.684023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:18:46.920945Z","caller":"traceutil/trace.go:171","msg":"trace[224443849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3245; }","duration":"162.893574ms","start":"2026-08-28T02:18:46.758013Z","end":"2026-08-28T02:18:46.920906Z","steps":["trace[224443849] 'range keys from in-memory index tree' (duration: 162.488796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:46.920856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.908172ms","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-28T02:18:46.921068Z","caller":"traceutil/trace.go:171","msg":"trace[2041031790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3245; }","duration":"196.171514ms","start":"2026-08-28T02:18:46.724876Z","end":"2026-08-28T02:18:46.921048Z","steps":["trace[2041031790] 'range keys from in-memory index tree' (duration: 195.736894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:46.921425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.302899ms","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-28T02:18:46.921772Z","caller":"traceutil/trace.go:171","msg":"trace[552399115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3245; }","duration":"196.665324ms","start":"2026-08-28T02:18:46.725095Z","end":"2026-08-28T02:18:46.92176Z","steps":["trace[552399115] 'range keys from in-memory index tree' (duration: 195.43442ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:47.316278Z","caller":"traceutil/trace.go:171","msg":"trace[1932337366] linearizableReadLoop","detail":"{readStateIndex:3367; appliedIndex:3366; }","duration":"355.177914ms","start":"2026-08-28T02:18:46.961072Z","end":"2026-08-28T02:18:47.31625Z","steps":["trace[1932337366] 'read index received' (duration: 28.310687ms)","trace[1932337366] 'applied index is now lower than readState.Index' (duration: 326.865557ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:47.316391Z","caller":"traceutil/trace.go:171","msg":"trace[1082501410] transaction","detail":"{read_only:false; response_revision:3247; number_of_response:1; }","duration":"389.313291ms","start":"2026-08-28T02:18:46.927031Z","end":"2026-08-28T02:18:47.316344Z","steps":["trace[1082501410] 'process raft request' (duration: 321.411686ms)","trace[1082501410] 'compare' (duration: 67.622463ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:47.316557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.454556ms","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-28T02:18:47.316581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:46.927009Z","time spent":"389.466318ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T02:18:47.316612Z","caller":"traceutil/trace.go:171","msg":"trace[2096037294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3247; }","duration":"355.55004ms","start":"2026-08-28T02:18:46.961046Z","end":"2026-08-28T02:18:47.316596Z","steps":["trace[2096037294] 'agreement among raft nodes before linearized reading' (duration: 355.347531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:47.316658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:46.961034Z","time spent":"355.611273ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-28T02:18:47.349946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.903275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-28T02:18:47.350042Z","caller":"traceutil/trace.go:171","msg":"trace[288744052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3248; }","duration":"140.054232ms","start":"2026-08-28T02:18:47.209965Z","end":"2026-08-28T02:18:47.350019Z","steps":["trace[288744052] 'agreement among raft nodes before linearized reading' (duration: 139.765139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:47.661917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.869366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:47.66212Z","caller":"traceutil/trace.go:171","msg":"trace[1964016849] linearizableReadLoop","detail":"{readStateIndex:3371; appliedIndex:3369; }","duration":"154.583293ms","start":"2026-08-28T02:18:47.507518Z","end":"2026-08-28T02:18:47.662102Z","steps":["trace[1964016849] 'read index received' (duration: 59.673µs)","trace[1964016849] 'applied index is now lower than readState.Index' (duration: 154.52261ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:47.662317Z","caller":"traceutil/trace.go:171","msg":"trace[32336502] transaction","detail":"{read_only:false; response_revision:3251; number_of_response:1; }","duration":"295.971472ms","start":"2026-08-28T02:18:47.366333Z","end":"2026-08-28T02:18:47.662304Z","steps":["trace[32336502] 'process raft request' (duration: 295.679929ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:47.662394Z","caller":"traceutil/trace.go:171","msg":"trace[524116715] transaction","detail":"{read_only:false; response_revision:3250; number_of_response:1; }","duration":"307.253844ms","start":"2026-08-28T02:18:47.355116Z","end":"2026-08-28T02:18:47.66237Z","steps":["trace[524116715] 'process raft request' (duration: 133.841215ms)","trace[524116715] 'compare' (duration: 172.665788ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:47.662478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.270642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-28T02:18:47.662508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:47.355078Z","time spent":"307.36639ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T02:18:47.662543Z","caller":"traceutil/trace.go:171","msg":"trace[1373491448] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3251; }","duration":"151.415838ms","start":"2026-08-28T02:18:47.511109Z","end":"2026-08-28T02:18:47.662525Z","steps":["trace[1373491448] 'agreement among raft nodes before linearized reading' (duration: 151.275662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:47.662585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.041033ms","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":"info","ts":"2026-08-28T02:18:47.662639Z","caller":"traceutil/trace.go:171","msg":"trace[735428326] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:3251; }","duration":"155.171308ms","start":"2026-08-28T02:18:47.507449Z","end":"2026-08-28T02:18:47.662621Z","steps":["trace[735428326] 'agreement among raft nodes before linearized reading' (duration: 155.058383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:48.177417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.402597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:18:48.177514Z","caller":"traceutil/trace.go:171","msg":"trace[388190341] linearizableReadLoop","detail":"{readStateIndex:3372; appliedIndex:3371; }","duration":"419.103356ms","start":"2026-08-28T02:18:47.75839Z","end":"2026-08-28T02:18:48.177494Z","steps":["trace[388190341] 'read index received' (duration: 161.590974ms)","trace[388190341] 'applied index is now lower than readState.Index' (duration: 257.511132ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:18:48.177667Z","caller":"traceutil/trace.go:171","msg":"trace[1982269214] transaction","detail":"{read_only:false; response_revision:3252; number_of_response:1; }","duration":"511.010416ms","start":"2026-08-28T02:18:47.666639Z","end":"2026-08-28T02:18:48.17765Z","steps":["trace[1982269214] 'process raft request' (duration: 253.299166ms)","trace[1982269214] 'compare' (duration: 257.108005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:18:48.177794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:47.666615Z","time spent":"511.117511ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T02:18:48.177956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.635542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-28T02:18:48.178066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.6836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T02:18:48.178029Z","caller":"traceutil/trace.go:171","msg":"trace[1399657684] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3252; }","duration":"134.756798ms","start":"2026-08-28T02:18:48.043255Z","end":"2026-08-28T02:18:48.178012Z","steps":["trace[1399657684] 'agreement among raft nodes before linearized reading' (duration: 134.58857ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:18:48.178095Z","caller":"traceutil/trace.go:171","msg":"trace[782490125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3252; }","duration":"419.759203ms","start":"2026-08-28T02:18:47.758327Z","end":"2026-08-28T02:18:48.178087Z","steps":["trace[782490125] 'agreement among raft nodes before linearized reading' (duration: 419.69149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:18:48.178134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T02:18:47.758304Z","time spent":"419.824485ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-28T02:18:49.777126Z","caller":"traceutil/trace.go:171","msg":"trace[559278364] transaction","detail":"{read_only:false; response_revision:3262; number_of_response:1; }","duration":"105.986501ms","start":"2026-08-28T02:18:49.671111Z","end":"2026-08-28T02:18:49.777097Z","steps":["trace[559278364] 'process raft request' (duration: 12.758691ms)","trace[559278364] 'compare' (duration: 92.710208ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:19:58.124615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.244079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T02:19:58.125028Z","caller":"traceutil/trace.go:171","msg":"trace[1661093634] transaction","detail":"{read_only:false; response_revision:3608; number_of_response:1; }","duration":"141.596903ms","start":"2026-08-28T02:19:57.983396Z","end":"2026-08-28T02:19:58.124993Z","steps":["trace[1661093634] 'process raft request' (duration: 25.88664ms)","trace[1661093634] 'compare' (duration: 115.071743ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:20:00.20926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.148256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-08-28T02:20:00.209339Z","caller":"traceutil/trace.go:171","msg":"trace[467830146] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3618; }","duration":"113.29016ms","start":"2026-08-28T02:20:00.096031Z","end":"2026-08-28T02:20:00.209321Z","steps":["trace[467830146] 'range keys from in-memory index tree' (duration: 112.942309ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:20:00.347331Z","caller":"traceutil/trace.go:171","msg":"trace[506489738] transaction","detail":"{read_only:false; response_revision:3620; number_of_response:1; }","duration":"120.235087ms","start":"2026-08-28T02:20:00.227049Z","end":"2026-08-28T02:20:00.347284Z","steps":["trace[506489738] 'process raft request' (duration: 106.404253ms)","trace[506489738] 'compare' (duration: 13.680969ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:20:00.347361Z","caller":"traceutil/trace.go:171","msg":"trace[357973894] linearizableReadLoop","detail":"{readStateIndex:3756; appliedIndex:3754; }","duration":"116.925964ms","start":"2026-08-28T02:20:00.230332Z","end":"2026-08-28T02:20:00.347258Z","steps":["trace[357973894] 'read index received' (duration: 28.133312ms)","trace[357973894] 'applied index is now lower than readState.Index' (duration: 88.790902ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:20:00.347617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.265105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7375"} {"level":"info","ts":"2026-08-28T02:20:00.347715Z","caller":"traceutil/trace.go:171","msg":"trace[713110587] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3620; }","duration":"117.387369ms","start":"2026-08-28T02:20:00.230303Z","end":"2026-08-28T02:20:00.347691Z","steps":["trace[713110587] 'agreement among raft nodes before linearized reading' (duration: 117.11037ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:20:01.58215Z","caller":"traceutil/trace.go:171","msg":"trace[1195724782] transaction","detail":"{read_only:false; response_revision:3628; number_of_response:1; }","duration":"109.595681ms","start":"2026-08-28T02:20:01.472523Z","end":"2026-08-28T02:20:01.582119Z","steps":["trace[1195724782] 'process raft request' (duration: 109.388285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:20:02.237575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.439643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-08-28T02:20:02.237684Z","caller":"traceutil/trace.go:171","msg":"trace[1630712133] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3630; }","duration":"144.579177ms","start":"2026-08-28T02:20:02.09308Z","end":"2026-08-28T02:20:02.237659Z","steps":["trace[1630712133] 'range keys from in-memory index tree' (duration: 144.210436ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:22:36.897196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2887} {"level":"info","ts":"2026-08-28T02:22:36.975015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2887,"took":"76.910444ms","hash":240525282,"current-db-size-bytes":40759296,"current-db-size":"41 MB","current-db-size-in-use-bytes":16437248,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-08-28T02:22:36.975139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":240525282,"revision":2887,"compact-revision":-1} {"level":"info","ts":"2026-08-28T02:22:59.989395Z","caller":"traceutil/trace.go:171","msg":"trace[1019281428] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"123.446958ms","start":"2026-08-28T02:22:59.865931Z","end":"2026-08-28T02:22:59.989378Z","steps":["trace[1019281428] 'process raft request' (duration: 123.182843ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:23:05.494796Z","caller":"traceutil/trace.go:171","msg":"trace[1244749501] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"141.477898ms","start":"2026-08-28T02:23:05.353288Z","end":"2026-08-28T02:23:05.494766Z","steps":["trace[1244749501] 'process raft request' (duration: 141.290524ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:23:24.804551Z","caller":"traceutil/trace.go:171","msg":"trace[412190892] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"106.63459ms","start":"2026-08-28T02:23:24.697899Z","end":"2026-08-28T02:23:24.804534Z","steps":["trace[412190892] 'process raft request' (duration: 106.553278ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:23:24.804761Z","caller":"traceutil/trace.go:171","msg":"trace[1494972286] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"101.336886ms","start":"2026-08-28T02:23:24.703402Z","end":"2026-08-28T02:23:24.804739Z","steps":["trace[1494972286] 'process raft request' (duration: 101.094961ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:23:24.804535Z","caller":"traceutil/trace.go:171","msg":"trace[2137089893] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"107.418145ms","start":"2026-08-28T02:23:24.697087Z","end":"2026-08-28T02:23:24.804505Z","steps":["trace[2137089893] 'process raft request' (duration: 53.468423ms)","trace[2137089893] 'compare' (duration: 53.81185ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:23:55.813473Z","caller":"traceutil/trace.go:171","msg":"trace[1947547809] linearizableReadLoop","detail":"{readStateIndex:5980; appliedIndex:5979; }","duration":"101.851031ms","start":"2026-08-28T02:23:55.711598Z","end":"2026-08-28T02:23:55.813449Z","steps":["trace[1947547809] 'read index received' (duration: 70.811776ms)","trace[1947547809] 'applied index is now lower than readState.Index' (duration: 31.038025ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T02:23:55.813804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.050775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T02:23:55.813852Z","caller":"traceutil/trace.go:171","msg":"trace[935506772] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5765; }","duration":"102.292969ms","start":"2026-08-28T02:23:55.711549Z","end":"2026-08-28T02:23:55.813842Z","steps":["trace[935506772] 'agreement among raft nodes before linearized reading' (duration: 102.020104ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:23:59.722361Z","caller":"traceutil/trace.go:171","msg":"trace[1775399053] linearizableReadLoop","detail":"{readStateIndex:6092; appliedIndex:6091; }","duration":"236.699286ms","start":"2026-08-28T02:23:59.485628Z","end":"2026-08-28T02:23:59.722327Z","steps":["trace[1775399053] 'read index received' (duration: 236.38166ms)","trace[1775399053] 'applied index is now lower than readState.Index' (duration: 316.316µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T02:23:59.722478Z","caller":"traceutil/trace.go:171","msg":"trace[789348207] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"249.66092ms","start":"2026-08-28T02:23:59.472745Z","end":"2026-08-28T02:23:59.722406Z","steps":["trace[789348207] 'process raft request' (duration: 249.310213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T02:23:59.722779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.985412ms","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-28T02:23:59.722872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.203376ms","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-28T02:23:59.722914Z","caller":"traceutil/trace.go:171","msg":"trace[125887570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5876; }","duration":"146.180696ms","start":"2026-08-28T02:23:59.576712Z","end":"2026-08-28T02:23:59.722893Z","steps":["trace[125887570] 'agreement among raft nodes before linearized reading' (duration: 145.86112ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:23:59.722966Z","caller":"traceutil/trace.go:171","msg":"trace[1092763163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5876; }","duration":"237.341748ms","start":"2026-08-28T02:23:59.485602Z","end":"2026-08-28T02:23:59.722943Z","steps":["trace[1092763163] 'agreement among raft nodes before linearized reading' (duration: 237.021632ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:24:10.482084Z","caller":"traceutil/trace.go:171","msg":"trace[2059911449] transaction","detail":"{read_only:false; response_revision:5945; number_of_response:1; }","duration":"141.296058ms","start":"2026-08-28T02:24:10.340758Z","end":"2026-08-28T02:24:10.482054Z","steps":["trace[2059911449] 'process raft request' (duration: 140.902631ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:27:25.041702Z","caller":"traceutil/trace.go:171","msg":"trace[1044657860] transaction","detail":"{read_only:false; response_revision:7322; number_of_response:1; }","duration":"189.661177ms","start":"2026-08-28T02:27:24.852016Z","end":"2026-08-28T02:27:25.041677Z","steps":["trace[1044657860] 'process raft request' (duration: 187.881187ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T02:27:36.9028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4603} {"level":"info","ts":"2026-08-28T02:27:36.949141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4603,"took":"45.100818ms","hash":2770323328,"current-db-size-bytes":40759296,"current-db-size":"41 MB","current-db-size-in-use-bytes":33898496,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-28T02:27:36.949188Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2770323328,"revision":4603,"compact-revision":2887}