{"level":"warn","ts":"2026-10-26T19:24:13.782958Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:24:13.783098Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.241: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.241:2380","--initial-cluster=instance=https://162.253.55.241:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.241:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.241:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-26T19:24:13.783465Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T19:24:13.783493Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-10-26T19:24:13.784108Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T19:24:13.791195Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"]} {"level":"info","ts":"2026-10-26T19:24:13.791496Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.241:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-26T19:24:13.829108Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"30.133872ms"} {"level":"info","ts":"2026-10-26T19:24:13.850047Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f50d212ec16cc59","cluster-id":"30c503bd66239e72"} {"level":"info","ts":"2026-10-26T19:24:13.850192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T19:24:13.850223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 0"} {"level":"info","ts":"2026-10-26T19:24:13.850251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f50d212ec16cc59 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T19:24:13.850285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 1"} {"level":"info","ts":"2026-10-26T19:24:13.850364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"warn","ts":"2026-10-26T19:24:13.86268Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T19:24:13.864442Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T19:24:13.865847Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-26T19:24:13.868282Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f50d212ec16cc59","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T19:24:13.868478Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f50d212ec16cc59","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T19:24:13.86852Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:24:13.868755Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:24:13.868842Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-26T19:24:13.870767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"info","ts":"2026-10-26T19:24:13.871069Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","added-peer-id":"5f50d212ec16cc59","added-peer-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-10-26T19:24:13.874169Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-26T19:24:13.874236Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-10-26T19:24:13.87438Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-10-26T19:24:13.874791Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f50d212ec16cc59","initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T19:24:13.874843Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T19:24:14.050804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T19:24:14.050885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T19:24:14.050919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgPreVoteResp from 5f50d212ec16cc59 at term 1"} {"level":"info","ts":"2026-10-26T19:24:14.050941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became candidate at term 2"} {"level":"info","ts":"2026-10-26T19:24:14.050951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgVoteResp from 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-10-26T19:24:14.050967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became leader at term 2"} {"level":"info","ts":"2026-10-26T19:24:14.050986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f50d212ec16cc59 elected leader 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-10-26T19:24:14.14189Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:24:14.143223Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:24:14.14323Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f50d212ec16cc59","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.241:2379]}","request-path":"/0/members/5f50d212ec16cc59/attributes","cluster-id":"30c503bd66239e72","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T19:24:14.143273Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T19:24:14.143741Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:24:14.143709Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T19:24:14.143829Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T19:24:14.14398Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:24:14.14406Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T19:24:14.1463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T19:24:14.148059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.241:2379"} {"level":"info","ts":"2026-10-26T19:25:06.418632Z","caller":"traceutil/trace.go:171","msg":"trace[872047014] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"133.506956ms","start":"2026-10-26T19:25:06.285088Z","end":"2026-10-26T19:25:06.418595Z","steps":["trace[872047014] 'read index received' (duration: 132.581028ms)","trace[872047014] 'applied index is now lower than readState.Index' (duration: 924.588µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:25:06.418679Z","caller":"traceutil/trace.go:171","msg":"trace[1317186053] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"258.101852ms","start":"2026-10-26T19:25:06.160544Z","end":"2026-10-26T19:25:06.418645Z","steps":["trace[1317186053] 'process raft request' (duration: 257.364538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:06.418858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.751091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qr7zk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-26T19:25:06.418928Z","caller":"traceutil/trace.go:171","msg":"trace[1428790555] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qr7zk; range_end:; response_count:1; response_revision:650; }","duration":"133.872894ms","start":"2026-10-26T19:25:06.285046Z","end":"2026-10-26T19:25:06.418919Z","steps":["trace[1428790555] 'agreement among raft nodes before linearized reading' (duration: 133.68085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:06.94622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.171396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:25:06.946388Z","caller":"traceutil/trace.go:171","msg":"trace[1232503411] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"181.361187ms","start":"2026-10-26T19:25:06.764967Z","end":"2026-10-26T19:25:06.946328Z","steps":["trace[1232503411] 'read index received' (duration: 43.731µs)","trace[1232503411] 'applied index is now lower than readState.Index' (duration: 181.315836ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:25:06.946392Z","caller":"traceutil/trace.go:171","msg":"trace[494221679] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"445.905002ms","start":"2026-10-26T19:25:06.500428Z","end":"2026-10-26T19:25:06.946333Z","steps":["trace[494221679] 'process raft request' (duration: 253.014624ms)","trace[494221679] 'compare' (duration: 192.022012ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:25:06.946489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:25:06.5004Z","time spent":"446.044134ms","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T19:25:06.946575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.599202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-26T19:25:06.946626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.625433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T19:25:06.946624Z","caller":"traceutil/trace.go:171","msg":"trace[905503412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"181.691724ms","start":"2026-10-26T19:25:06.76492Z","end":"2026-10-26T19:25:06.946612Z","steps":["trace[905503412] 'agreement among raft nodes before linearized reading' (duration: 181.4885ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:06.946689Z","caller":"traceutil/trace.go:171","msg":"trace[1730018442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"180.711174ms","start":"2026-10-26T19:25:06.765964Z","end":"2026-10-26T19:25:06.946675Z","steps":["trace[1730018442] 'agreement among raft nodes before linearized reading' (duration: 180.545531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:25:06.946918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.823432ms","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-10-26T19:25:06.947013Z","caller":"traceutil/trace.go:171","msg":"trace[1318020070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"135.018046ms","start":"2026-10-26T19:25:06.811974Z","end":"2026-10-26T19:25:06.946992Z","steps":["trace[1318020070] 'agreement among raft nodes before linearized reading' (duration: 134.732361ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:25:13.486816Z","caller":"traceutil/trace.go:171","msg":"trace[1313154760] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"113.793397ms","start":"2026-10-26T19:25:13.372986Z","end":"2026-10-26T19:25:13.486779Z","steps":["trace[1313154760] 'process raft request' (duration: 74.013702ms)","trace[1313154760] 'compare' (duration: 39.607412ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:25:13.509629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.41555ms","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:5"} {"level":"warn","ts":"2026-10-26T19:25:13.509674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.543373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:25:13.509724Z","caller":"traceutil/trace.go:171","msg":"trace[530055420] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:754; }","duration":"140.560763ms","start":"2026-10-26T19:25:13.369137Z","end":"2026-10-26T19:25:13.509697Z","steps":["trace[530055420] 'agreement among raft nodes before linearized reading' (duration: 117.734544ms)","trace[530055420] 'count revisions from in-memory index tree' (duration: 22.683996ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:25:13.509759Z","caller":"traceutil/trace.go:171","msg":"trace[320860879] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:754; }","duration":"139.656884ms","start":"2026-10-26T19:25:13.370078Z","end":"2026-10-26T19:25:13.509735Z","steps":["trace[320860879] 'agreement among raft nodes before linearized reading' (duration: 116.837086ms)","trace[320860879] 'range keys from in-memory index tree' (duration: 22.690536ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:25:22.971451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.906152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T19:25:22.971556Z","caller":"traceutil/trace.go:171","msg":"trace[10359054] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:817; }","duration":"188.088256ms","start":"2026-10-26T19:25:22.783443Z","end":"2026-10-26T19:25:22.971531Z","steps":["trace[10359054] 'count revisions from in-memory index tree' (duration: 187.791271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:42.734376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.232812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:26:42.734538Z","caller":"traceutil/trace.go:171","msg":"trace[591299414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1900; }","duration":"231.431775ms","start":"2026-10-26T19:26:42.503081Z","end":"2026-10-26T19:26:42.734513Z","steps":["trace[591299414] 'range keys from in-memory index tree' (duration: 231.15137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:26:42.734632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.141508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T19:26:42.734724Z","caller":"traceutil/trace.go:171","msg":"trace[292896991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1900; }","duration":"107.357732ms","start":"2026-10-26T19:26:42.627346Z","end":"2026-10-26T19:26:42.734703Z","steps":["trace[292896991] 'range keys from in-memory index tree' (duration: 106.890763ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:42.936614Z","caller":"traceutil/trace.go:171","msg":"trace[1693366127] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"177.07248ms","start":"2026-10-26T19:26:42.759511Z","end":"2026-10-26T19:26:42.936583Z","steps":["trace[1693366127] 'read index received' (duration: 104.362104ms)","trace[1693366127] 'applied index is now lower than readState.Index' (duration: 72.709276ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:26:42.936734Z","caller":"traceutil/trace.go:171","msg":"trace[650639916] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"196.035905ms","start":"2026-10-26T19:26:42.740641Z","end":"2026-10-26T19:26:42.936677Z","steps":["trace[650639916] 'process raft request' (duration: 123.190996ms)","trace[650639916] 'compare' (duration: 72.619804ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:26:42.936958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.803974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fbb7ccd9-1429-4a84-9861-15071e9dfb7c\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-26T19:26:42.936981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.439217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T19:26:42.937042Z","caller":"traceutil/trace.go:171","msg":"trace[924462245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1902; }","duration":"177.56222ms","start":"2026-10-26T19:26:42.759467Z","end":"2026-10-26T19:26:42.937029Z","steps":["trace[924462245] 'agreement among raft nodes before linearized reading' (duration: 177.271794ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:42.937031Z","caller":"traceutil/trace.go:171","msg":"trace[1948667258] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fbb7ccd9-1429-4a84-9861-15071e9dfb7c; range_end:; response_count:1; response_revision:1902; }","duration":"103.933976ms","start":"2026-10-26T19:26:42.833083Z","end":"2026-10-26T19:26:42.937017Z","steps":["trace[1948667258] 'agreement among raft nodes before linearized reading' (duration: 103.657691ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:43.193121Z","caller":"traceutil/trace.go:171","msg":"trace[1158858867] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"246.918392ms","start":"2026-10-26T19:26:42.946177Z","end":"2026-10-26T19:26:43.193096Z","steps":["trace[1158858867] 'process raft request' (duration: 246.710128ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:43.279975Z","caller":"traceutil/trace.go:171","msg":"trace[488205996] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"296.064876ms","start":"2026-10-26T19:26:42.983883Z","end":"2026-10-26T19:26:43.279948Z","steps":["trace[488205996] 'process raft request' (duration: 295.862542ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:43.280669Z","caller":"traceutil/trace.go:171","msg":"trace[150579249] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"132.118358ms","start":"2026-10-26T19:26:43.148532Z","end":"2026-10-26T19:26:43.28065Z","steps":["trace[150579249] 'process raft request' (duration: 131.366873ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:26:51.865041Z","caller":"traceutil/trace.go:171","msg":"trace[1991747964] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"163.312805ms","start":"2026-10-26T19:26:51.701688Z","end":"2026-10-26T19:26:51.865001Z","steps":["trace[1991747964] 'process raft request' (duration: 110.730636ms)","trace[1991747964] 'compare' (duration: 52.396676ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:26:57.002274Z","caller":"traceutil/trace.go:171","msg":"trace[1084041588] transaction","detail":"{read_only:false; response_revision:2020; number_of_response:1; }","duration":"165.13285ms","start":"2026-10-26T19:26:56.837118Z","end":"2026-10-26T19:26:57.00225Z","steps":["trace[1084041588] 'process raft request' (duration: 164.970167ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:27:27.928014Z","caller":"traceutil/trace.go:171","msg":"trace[616532806] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"102.081707ms","start":"2026-10-26T19:27:27.825897Z","end":"2026-10-26T19:27:27.927978Z","steps":["trace[616532806] 'process raft request' (duration: 51.823108ms)","trace[616532806] 'compare' (duration: 50.103596ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:27:39.600224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.052995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:27:39.600332Z","caller":"traceutil/trace.go:171","msg":"trace[357509436] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:2256; }","duration":"223.222409ms","start":"2026-10-26T19:27:39.377084Z","end":"2026-10-26T19:27:39.600306Z","steps":["trace[357509436] 'count revisions from in-memory index tree' (duration: 222.896352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:27:39.600328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.902886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T19:27:39.60046Z","caller":"traceutil/trace.go:171","msg":"trace[261188613] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2256; }","duration":"188.082298ms","start":"2026-10-26T19:27:39.41235Z","end":"2026-10-26T19:27:39.600432Z","steps":["trace[261188613] 'range keys from in-memory index tree' (duration: 187.609529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:28:15.392309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.059757ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:28:15.392446Z","caller":"traceutil/trace.go:171","msg":"trace[17570215] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2556; }","duration":"102.20182ms","start":"2026-10-26T19:28:15.290226Z","end":"2026-10-26T19:28:15.392428Z","steps":["trace[17570215] 'range keys from in-memory index tree' (duration: 102.046347ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:28:58.889113Z","caller":"traceutil/trace.go:171","msg":"trace[978448417] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"123.894102ms","start":"2026-10-26T19:28:58.765195Z","end":"2026-10-26T19:28:58.889089Z","steps":["trace[978448417] 'process raft request' (duration: 123.599917ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:31:39.74086Z","caller":"traceutil/trace.go:171","msg":"trace[451027292] transaction","detail":"{read_only:false; response_revision:3872; number_of_response:1; }","duration":"134.447903ms","start":"2026-10-26T19:31:39.606373Z","end":"2026-10-26T19:31:39.740821Z","steps":["trace[451027292] 'process raft request' (duration: 126.067851ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:32:11.381385Z","caller":"traceutil/trace.go:171","msg":"trace[176359769] transaction","detail":"{read_only:false; response_revision:4095; number_of_response:1; }","duration":"117.827422ms","start":"2026-10-26T19:32:11.263515Z","end":"2026-10-26T19:32:11.381342Z","steps":["trace[176359769] 'process raft request' (duration: 117.530916ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:41.345388Z","caller":"traceutil/trace.go:171","msg":"trace[1228823571] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"125.777605ms","start":"2026-10-26T19:33:41.219572Z","end":"2026-10-26T19:33:41.345349Z","steps":["trace[1228823571] 'process raft request' (duration: 121.536544ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:45.459785Z","caller":"traceutil/trace.go:171","msg":"trace[540171441] linearizableReadLoop","detail":"{readStateIndex:5373; appliedIndex:5372; }","duration":"114.526789ms","start":"2026-10-26T19:33:45.345229Z","end":"2026-10-26T19:33:45.459756Z","steps":["trace[540171441] 'read index received' (duration: 114.349496ms)","trace[540171441] 'applied index is now lower than readState.Index' (duration: 176.283µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:45.459904Z","caller":"traceutil/trace.go:171","msg":"trace[586243315] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"116.846923ms","start":"2026-10-26T19:33:45.343019Z","end":"2026-10-26T19:33:45.459866Z","steps":["trace[586243315] 'process raft request' (duration: 116.572908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:45.460113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.850525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-26T19:33:45.460204Z","caller":"traceutil/trace.go:171","msg":"trace[414399150] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5172; }","duration":"115.000279ms","start":"2026-10-26T19:33:45.345185Z","end":"2026-10-26T19:33:45.460185Z","steps":["trace[414399150] 'agreement among raft nodes before linearized reading' (duration: 114.701903ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:49.507741Z","caller":"traceutil/trace.go:171","msg":"trace[399519600] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"416.659561ms","start":"2026-10-26T19:33:49.091018Z","end":"2026-10-26T19:33:49.507678Z","steps":["trace[399519600] 'process raft request' (duration: 416.367847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.508194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.090975Z","time spent":"416.936257ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:33:49.597548Z","caller":"traceutil/trace.go:171","msg":"trace[348766486] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5530; }","duration":"446.992843ms","start":"2026-10-26T19:33:49.150484Z","end":"2026-10-26T19:33:49.597477Z","steps":["trace[348766486] 'read index received' (duration: 356.877793ms)","trace[348766486] 'applied index is now lower than readState.Index' (duration: 90.11325ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:49.597574Z","caller":"traceutil/trace.go:171","msg":"trace[2054449666] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"503.336536ms","start":"2026-10-26T19:33:49.094195Z","end":"2026-10-26T19:33:49.597532Z","steps":["trace[2054449666] 'process raft request' (duration: 503.05735ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:49.597716Z","caller":"traceutil/trace.go:171","msg":"trace[1042519253] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"501.859146ms","start":"2026-10-26T19:33:49.095803Z","end":"2026-10-26T19:33:49.597662Z","steps":["trace[1042519253] 'process raft request' (duration: 501.613652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.597823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.952937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T19:33:49.59787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.094175Z","time spent":"503.57947ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:33:49.597881Z","caller":"traceutil/trace.go:171","msg":"trace[230799916] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5326; }","duration":"442.065128ms","start":"2026-10-26T19:33:49.155797Z","end":"2026-10-26T19:33:49.597862Z","steps":["trace[230799916] 'agreement among raft nodes before linearized reading' (duration: 441.945296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.597872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.330519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T19:33:49.597984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.095792Z","time spent":"502.04155ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:33:49.598015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.932439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:49.598029Z","caller":"traceutil/trace.go:171","msg":"trace[2071137154] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5326; }","duration":"447.569404ms","start":"2026-10-26T19:33:49.150423Z","end":"2026-10-26T19:33:49.597992Z","steps":["trace[2071137154] 'agreement among raft nodes before linearized reading' (duration: 447.283498ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:49.598057Z","caller":"traceutil/trace.go:171","msg":"trace[1875237656] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5326; }","duration":"250.025971ms","start":"2026-10-26T19:33:49.348019Z","end":"2026-10-26T19:33:49.598045Z","steps":["trace[1875237656] 'agreement among raft nodes before linearized reading' (duration: 249.95663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.598062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.878708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-26T19:33:49.598109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.150399Z","time spent":"447.690707ms","remote":"127.0.0.1:55004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-10-26T19:33:49.598123Z","caller":"traceutil/trace.go:171","msg":"trace[1635791468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5326; }","duration":"112.98772ms","start":"2026-10-26T19:33:49.485119Z","end":"2026-10-26T19:33:49.598106Z","steps":["trace[1635791468] 'agreement among raft nodes before linearized reading' (duration: 112.782026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.597942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.155783Z","time spent":"442.140631ms","remote":"127.0.0.1:37364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-26T19:33:49.598447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.701822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93c5eef0-99fd-48bf-8044-8ac148623dd8\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-10-26T19:33:49.598462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.318824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:33:49.598536Z","caller":"traceutil/trace.go:171","msg":"trace[673447588] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93c5eef0-99fd-48bf-8044-8ac148623dd8; range_end:; response_count:1; response_revision:5326; }","duration":"377.820005ms","start":"2026-10-26T19:33:49.2207Z","end":"2026-10-26T19:33:49.59852Z","steps":["trace[673447588] 'agreement among raft nodes before linearized reading' (duration: 377.630051ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:49.598563Z","caller":"traceutil/trace.go:171","msg":"trace[1205114721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5326; }","duration":"102.417506ms","start":"2026-10-26T19:33:49.496132Z","end":"2026-10-26T19:33:49.598549Z","steps":["trace[1205114721] 'agreement among raft nodes before linearized reading' (duration: 102.255643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.598588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.220682Z","time spent":"377.892606ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93c5eef0-99fd-48bf-8044-8ac148623dd8\" "} {"level":"warn","ts":"2026-10-26T19:33:49.598673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.873001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:49.59872Z","caller":"traceutil/trace.go:171","msg":"trace[1755668734] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5326; }","duration":"384.961343ms","start":"2026-10-26T19:33:49.213748Z","end":"2026-10-26T19:33:49.59871Z","steps":["trace[1755668734] 'agreement among raft nodes before linearized reading' (duration: 384.889281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.598715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.148169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-59kb2\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:33:49.598753Z","caller":"traceutil/trace.go:171","msg":"trace[959556383] range","detail":"{range_begin:/registry/pods/openstack/keepalived-59kb2; range_end:; response_count:1; response_revision:5326; }","duration":"394.19584ms","start":"2026-10-26T19:33:49.204547Z","end":"2026-10-26T19:33:49.598743Z","steps":["trace[959556383] 'agreement among raft nodes before linearized reading' (duration: 394.060507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.598752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.213718Z","time spent":"385.022234ms","remote":"127.0.0.1:39640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"warn","ts":"2026-10-26T19:33:49.598772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.204542Z","time spent":"394.22601ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-59kb2\" "} {"level":"warn","ts":"2026-10-26T19:33:49.598978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.283093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:49.599038Z","caller":"traceutil/trace.go:171","msg":"trace[167504089] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5326; }","duration":"418.389575ms","start":"2026-10-26T19:33:49.180632Z","end":"2026-10-26T19:33:49.599022Z","steps":["trace[167504089] 'agreement among raft nodes before linearized reading' (duration: 418.279333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:49.599071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:49.180611Z","time spent":"418.452566ms","remote":"127.0.0.1:39940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-26T19:33:51.65303Z","caller":"traceutil/trace.go:171","msg":"trace[2005132476] linearizableReadLoop","detail":"{readStateIndex:5563; appliedIndex:5562; }","duration":"151.03012ms","start":"2026-10-26T19:33:51.501975Z","end":"2026-10-26T19:33:51.653004Z","steps":["trace[2005132476] 'read index received' (duration: 150.844836ms)","trace[2005132476] 'applied index is now lower than readState.Index' (duration: 184.094µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:33:51.65317Z","caller":"traceutil/trace.go:171","msg":"trace[1896071518] transaction","detail":"{read_only:false; response_revision:5355; number_of_response:1; }","duration":"265.177811ms","start":"2026-10-26T19:33:51.387961Z","end":"2026-10-26T19:33:51.653139Z","steps":["trace[1896071518] 'process raft request' (duration: 264.858155ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:51.653203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.211823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:33:51.653244Z","caller":"traceutil/trace.go:171","msg":"trace[1777633559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5355; }","duration":"151.370727ms","start":"2026-10-26T19:33:51.50186Z","end":"2026-10-26T19:33:51.65323Z","steps":["trace[1777633559] 'agreement among raft nodes before linearized reading' (duration: 151.260345ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:51.853085Z","caller":"traceutil/trace.go:171","msg":"trace[328717843] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"249.490461ms","start":"2026-10-26T19:33:51.603563Z","end":"2026-10-26T19:33:51.853054Z","steps":["trace[328717843] 'process raft request' (duration: 249.404719ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:51.853185Z","caller":"traceutil/trace.go:171","msg":"trace[94362884] transaction","detail":"{read_only:false; response_revision:5356; number_of_response:1; }","duration":"339.591092ms","start":"2026-10-26T19:33:51.513556Z","end":"2026-10-26T19:33:51.853147Z","steps":["trace[94362884] 'process raft request' (duration: 332.571836ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:51.853308Z","caller":"traceutil/trace.go:171","msg":"trace[2005709718] linearizableReadLoop","detail":"{readStateIndex:5566; appliedIndex:5563; }","duration":"200.195465ms","start":"2026-10-26T19:33:51.653101Z","end":"2026-10-26T19:33:51.853297Z","steps":["trace[2005709718] 'read index received' (duration: 193.064027ms)","trace[2005709718] 'applied index is now lower than readState.Index' (duration: 7.130288ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:33:51.853396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:33:51.513524Z","time spent":"339.740175ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:33:51.853605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.709282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-59kb2\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:33:51.853644Z","caller":"traceutil/trace.go:171","msg":"trace[1067656191] range","detail":"{range_begin:/registry/pods/openstack/keepalived-59kb2; range_end:; response_count:1; response_revision:5358; }","duration":"202.769263ms","start":"2026-10-26T19:33:51.650866Z","end":"2026-10-26T19:33:51.853635Z","steps":["trace[1067656191] 'agreement among raft nodes before linearized reading' (duration: 202.588461ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:33:51.853431Z","caller":"traceutil/trace.go:171","msg":"trace[306424632] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"248.487453ms","start":"2026-10-26T19:33:51.604932Z","end":"2026-10-26T19:33:51.85342Z","steps":["trace[306424632] 'process raft request' (duration: 248.083825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:51.853797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.336789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T19:33:51.853854Z","caller":"traceutil/trace.go:171","msg":"trace[948384874] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5358; }","duration":"196.440801ms","start":"2026-10-26T19:33:51.657399Z","end":"2026-10-26T19:33:51.85384Z","steps":["trace[948384874] 'agreement among raft nodes before linearized reading' (duration: 196.293828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:51.854001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.176789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-26T19:33:51.854073Z","caller":"traceutil/trace.go:171","msg":"trace[25489919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5358; }","duration":"205.311812ms","start":"2026-10-26T19:33:51.648743Z","end":"2026-10-26T19:33:51.854054Z","steps":["trace[25489919] 'agreement among raft nodes before linearized reading' (duration: 205.084428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:33:51.854134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.466343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T19:33:51.854261Z","caller":"traceutil/trace.go:171","msg":"trace[2142043164] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5358; }","duration":"196.667177ms","start":"2026-10-26T19:33:51.657565Z","end":"2026-10-26T19:33:51.854232Z","steps":["trace[2142043164] 'agreement among raft nodes before linearized reading' (duration: 196.281039ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:04.515017Z","caller":"traceutil/trace.go:171","msg":"trace[143513714] linearizableReadLoop","detail":"{readStateIndex:5808; appliedIndex:5807; }","duration":"110.90486ms","start":"2026-10-26T19:34:04.404083Z","end":"2026-10-26T19:34:04.514988Z","steps":["trace[143513714] 'read index received' (duration: 110.703306ms)","trace[143513714] 'applied index is now lower than readState.Index' (duration: 200.284µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:04.515085Z","caller":"traceutil/trace.go:171","msg":"trace[2106682812] transaction","detail":"{read_only:false; response_revision:5592; number_of_response:1; }","duration":"116.381335ms","start":"2026-10-26T19:34:04.398664Z","end":"2026-10-26T19:34:04.515045Z","steps":["trace[2106682812] 'process raft request' (duration: 116.145921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:04.515253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.128274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:34:04.515374Z","caller":"traceutil/trace.go:171","msg":"trace[165143995] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5592; }","duration":"111.298508ms","start":"2026-10-26T19:34:04.404041Z","end":"2026-10-26T19:34:04.515339Z","steps":["trace[165143995] 'agreement among raft nodes before linearized reading' (duration: 111.100714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:12.33509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.691139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T19:34:12.336968Z","caller":"traceutil/trace.go:171","msg":"trace[2015286364] transaction","detail":"{read_only:false; response_revision:5743; number_of_response:1; }","duration":"595.893713ms","start":"2026-10-26T19:34:11.74101Z","end":"2026-10-26T19:34:12.336903Z","steps":["trace[2015286364] 'process raft request' (duration: 124.273626ms)","trace[2015286364] 'compare' (duration: 469.079088ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:12.337129Z","caller":"traceutil/trace.go:171","msg":"trace[635167429] transaction","detail":"{read_only:false; response_revision:5746; number_of_response:1; }","duration":"243.108848ms","start":"2026-10-26T19:34:12.093987Z","end":"2026-10-26T19:34:12.337096Z","steps":["trace[635167429] 'process raft request' (duration: 243.064188ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:12.337437Z","caller":"traceutil/trace.go:171","msg":"trace[1737367630] transaction","detail":"{read_only:false; response_revision:5744; number_of_response:1; }","duration":"329.956777ms","start":"2026-10-26T19:34:12.007465Z","end":"2026-10-26T19:34:12.337422Z","steps":["trace[1737367630] 'process raft request' (duration: 329.394476ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:12.337539Z","caller":"traceutil/trace.go:171","msg":"trace[50453279] linearizableReadLoop","detail":"{readStateIndex:5961; appliedIndex:5960; }","duration":"482.117378ms","start":"2026-10-26T19:34:11.855407Z","end":"2026-10-26T19:34:12.337524Z","steps":["trace[50453279] 'read index received' (duration: 9.779347ms)","trace[50453279] 'applied index is now lower than readState.Index' (duration: 472.336921ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:34:12.337565Z","caller":"traceutil/trace.go:171","msg":"trace[566321145] transaction","detail":"{read_only:false; response_revision:5745; number_of_response:1; }","duration":"304.800774ms","start":"2026-10-26T19:34:12.032717Z","end":"2026-10-26T19:34:12.337517Z","steps":["trace[566321145] 'process raft request' (duration: 304.294104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:12.337596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:12.007385Z","time spent":"330.0891ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:34:12.33716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:11.74099Z","time spent":"596.063487ms","remote":"127.0.0.1:39544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4772,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:34:12.337693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:12.032684Z","time spent":"304.951177ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:34:12.337965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.559006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08e70f6b-d3aa-4ea8-b230-d15fb639e0fe\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-26T19:34:12.338029Z","caller":"traceutil/trace.go:171","msg":"trace[1835322305] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-08e70f6b-d3aa-4ea8-b230-d15fb639e0fe; range_end:; response_count:1; response_revision:5746; }","duration":"482.673398ms","start":"2026-10-26T19:34:11.855339Z","end":"2026-10-26T19:34:12.338013Z","steps":["trace[1835322305] 'agreement among raft nodes before linearized reading' (duration: 482.484055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:12.338075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:11.855315Z","time spent":"482.742999ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-08e70f6b-d3aa-4ea8-b230-d15fb639e0fe\" "} {"level":"warn","ts":"2026-10-26T19:34:12.338181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.244779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-26T19:34:12.338203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.844482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-59kb2\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T19:34:12.338233Z","caller":"traceutil/trace.go:171","msg":"trace[1476125921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5746; }","duration":"194.336771ms","start":"2026-10-26T19:34:12.143884Z","end":"2026-10-26T19:34:12.33822Z","steps":["trace[1476125921] 'agreement among raft nodes before linearized reading' (duration: 194.163568ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:12.338237Z","caller":"traceutil/trace.go:171","msg":"trace[305702372] range","detail":"{range_begin:/registry/pods/openstack/keepalived-59kb2; range_end:; response_count:1; response_revision:5746; }","duration":"206.900503ms","start":"2026-10-26T19:34:12.131327Z","end":"2026-10-26T19:34:12.338228Z","steps":["trace[305702372] 'agreement among raft nodes before linearized reading' (duration: 206.794961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:12.338228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.329082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T19:34:12.338314Z","caller":"traceutil/trace.go:171","msg":"trace[703129802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5746; }","duration":"104.442635ms","start":"2026-10-26T19:34:12.233836Z","end":"2026-10-26T19:34:12.338279Z","steps":["trace[703129802] 'agreement among raft nodes before linearized reading' (duration: 104.243011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:12.338377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.863694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-10-26T19:34:12.33845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.670849ms","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-10-26T19:34:12.338478Z","caller":"traceutil/trace.go:171","msg":"trace[1413117449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5746; }","duration":"131.725469ms","start":"2026-10-26T19:34:12.206745Z","end":"2026-10-26T19:34:12.33847Z","steps":["trace[1413117449] 'agreement among raft nodes before linearized reading' (duration: 131.617067ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:34:12.338474Z","caller":"traceutil/trace.go:171","msg":"trace[1458236128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5746; }","duration":"267.999536ms","start":"2026-10-26T19:34:12.070448Z","end":"2026-10-26T19:34:12.338448Z","steps":["trace[1458236128] 'agreement among raft nodes before linearized reading' (duration: 267.70915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:12.338378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.329598ms","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-10-26T19:34:12.338596Z","caller":"traceutil/trace.go:171","msg":"trace[82196813] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5746; }","duration":"199.618603ms","start":"2026-10-26T19:34:12.138961Z","end":"2026-10-26T19:34:12.338579Z","steps":["trace[82196813] 'agreement among raft nodes before linearized reading' (duration: 199.225146ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:12.33936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.779595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-26T19:34:12.339467Z","caller":"traceutil/trace.go:171","msg":"trace[140461284] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5746; }","duration":"388.886247ms","start":"2026-10-26T19:34:11.950563Z","end":"2026-10-26T19:34:12.339449Z","steps":["trace[140461284] 'agreement among raft nodes before linearized reading' (duration: 387.47509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:34:12.339559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:34:11.950474Z","time spent":"389.052341ms","remote":"127.0.0.1:39556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225430,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-10-26T19:34:14.437712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-10-26T19:34:14.530922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"91.284422ms","hash":2649583724,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":31965184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T19:34:14.531019Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2649583724,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-10-26T19:35:18.775385Z","caller":"traceutil/trace.go:171","msg":"trace[1759527055] transaction","detail":"{read_only:false; response_revision:6371; number_of_response:1; }","duration":"107.127236ms","start":"2026-10-26T19:35:18.668227Z","end":"2026-10-26T19:35:18.775354Z","steps":["trace[1759527055] 'process raft request' (duration: 107.067965ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:35:18.77546Z","caller":"traceutil/trace.go:171","msg":"trace[759237246] transaction","detail":"{read_only:false; response_revision:6370; number_of_response:1; }","duration":"120.039743ms","start":"2026-10-26T19:35:18.655357Z","end":"2026-10-26T19:35:18.775397Z","steps":["trace[759237246] 'process raft request' (duration: 108.136094ms)","trace[759237246] 'compare' (duration: 11.625513ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:39:14.444009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5774} {"level":"info","ts":"2026-10-26T19:39:14.545798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5774,"took":"99.91437ms","hash":3498182797,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":26775552,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T19:39:14.545913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3498182797,"revision":5774,"compact-revision":2967} {"level":"info","ts":"2026-10-26T19:40:40.864088Z","caller":"traceutil/trace.go:171","msg":"trace[129819980] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"212.679865ms","start":"2026-10-26T19:40:40.651375Z","end":"2026-10-26T19:40:40.864055Z","steps":["trace[129819980] 'process raft request' (duration: 212.45756ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:40.98945Z","caller":"traceutil/trace.go:171","msg":"trace[959785960] linearizableReadLoop","detail":"{readStateIndex:9020; appliedIndex:9018; }","duration":"153.770698ms","start":"2026-10-26T19:40:40.835649Z","end":"2026-10-26T19:40:40.98942Z","steps":["trace[959785960] 'read index received' (duration: 28.26968ms)","trace[959785960] 'applied index is now lower than readState.Index' (duration: 125.499458ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:40:40.98981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.132728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T19:40:40.989781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.088844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-26T19:40:40.989824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.007843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T19:40:40.989894Z","caller":"traceutil/trace.go:171","msg":"trace[457144134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8706; }","duration":"154.258408ms","start":"2026-10-26T19:40:40.835609Z","end":"2026-10-26T19:40:40.989867Z","steps":["trace[457144134] 'agreement among raft nodes before linearized reading' (duration: 153.884761ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:40.989941Z","caller":"traceutil/trace.go:171","msg":"trace[1894073821] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8706; }","duration":"143.144936ms","start":"2026-10-26T19:40:40.846775Z","end":"2026-10-26T19:40:40.98992Z","steps":["trace[1894073821] 'agreement among raft nodes before linearized reading' (duration: 142.872151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:40:40.989954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.624625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-26T19:40:40.99004Z","caller":"traceutil/trace.go:171","msg":"trace[1212681109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8706; }","duration":"143.796757ms","start":"2026-10-26T19:40:40.846221Z","end":"2026-10-26T19:40:40.990018Z","steps":["trace[1212681109] 'agreement among raft nodes before linearized reading' (duration: 143.516821ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:40.989859Z","caller":"traceutil/trace.go:171","msg":"trace[768081135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8706; }","duration":"150.229551ms","start":"2026-10-26T19:40:40.839617Z","end":"2026-10-26T19:40:40.989847Z","steps":["trace[768081135] 'agreement among raft nodes before linearized reading' (duration: 150.018256ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:40.990168Z","caller":"traceutil/trace.go:171","msg":"trace[192907338] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"158.374806ms","start":"2026-10-26T19:40:40.831772Z","end":"2026-10-26T19:40:40.990146Z","steps":["trace[192907338] 'process raft request' (duration: 106.21663ms)","trace[192907338] 'compare' (duration: 51.29416ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:40:42.269236Z","caller":"traceutil/trace.go:171","msg":"trace[2108782947] transaction","detail":"{read_only:false; response_revision:8714; number_of_response:1; }","duration":"163.684208ms","start":"2026-10-26T19:40:42.105512Z","end":"2026-10-26T19:40:42.269196Z","steps":["trace[2108782947] 'process raft request' (duration: 89.753395ms)","trace[2108782947] 'compare' (duration: 73.594846ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:40:42.269434Z","caller":"traceutil/trace.go:171","msg":"trace[2136033595] linearizableReadLoop","detail":"{readStateIndex:9029; appliedIndex:9027; }","duration":"121.720926ms","start":"2026-10-26T19:40:42.147681Z","end":"2026-10-26T19:40:42.269402Z","steps":["trace[2136033595] 'read index received' (duration: 47.65254ms)","trace[2136033595] 'applied index is now lower than readState.Index' (duration: 74.066756ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:40:42.26974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.031121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:40:42.269835Z","caller":"traceutil/trace.go:171","msg":"trace[364472371] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8715; }","duration":"122.187304ms","start":"2026-10-26T19:40:42.147623Z","end":"2026-10-26T19:40:42.26981Z","steps":["trace[364472371] 'agreement among raft nodes before linearized reading' (duration: 122.025551ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:40:42.269913Z","caller":"traceutil/trace.go:171","msg":"trace[2115504539] transaction","detail":"{read_only:false; response_revision:8715; number_of_response:1; }","duration":"148.756582ms","start":"2026-10-26T19:40:42.121107Z","end":"2026-10-26T19:40:42.269863Z","steps":["trace[2115504539] 'process raft request' (duration: 148.016018ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:12.254068Z","caller":"traceutil/trace.go:171","msg":"trace[1216898644] transaction","detail":"{read_only:false; response_revision:8927; number_of_response:1; }","duration":"197.976347ms","start":"2026-10-26T19:41:12.056058Z","end":"2026-10-26T19:41:12.254034Z","steps":["trace[1216898644] 'process raft request' (duration: 197.782663ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:12.465369Z","caller":"traceutil/trace.go:171","msg":"trace[1550727689] transaction","detail":"{read_only:false; response_revision:8928; number_of_response:1; }","duration":"259.232215ms","start":"2026-10-26T19:41:12.206099Z","end":"2026-10-26T19:41:12.465331Z","steps":["trace[1550727689] 'process raft request' (duration: 258.613993ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:14.572468Z","caller":"traceutil/trace.go:171","msg":"trace[970382281] transaction","detail":"{read_only:false; response_revision:8938; number_of_response:1; }","duration":"100.766956ms","start":"2026-10-26T19:41:14.471669Z","end":"2026-10-26T19:41:14.572436Z","steps":["trace[970382281] 'process raft request' (duration: 100.556023ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:14.589273Z","caller":"traceutil/trace.go:171","msg":"trace[1179970389] transaction","detail":"{read_only:false; response_revision:8939; number_of_response:1; }","duration":"114.71301ms","start":"2026-10-26T19:41:14.474527Z","end":"2026-10-26T19:41:14.58924Z","steps":["trace[1179970389] 'process raft request' (duration: 114.393155ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:14.589466Z","caller":"traceutil/trace.go:171","msg":"trace[412843270] transaction","detail":"{read_only:false; response_revision:8940; number_of_response:1; }","duration":"114.073408ms","start":"2026-10-26T19:41:14.475334Z","end":"2026-10-26T19:41:14.589408Z","steps":["trace[412843270] 'process raft request' (duration: 113.800372ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:14.923588Z","caller":"traceutil/trace.go:171","msg":"trace[1620083922] transaction","detail":"{read_only:false; response_revision:8941; number_of_response:1; }","duration":"327.290953ms","start":"2026-10-26T19:41:14.596244Z","end":"2026-10-26T19:41:14.923535Z","steps":["trace[1620083922] 'process raft request' (duration: 327.077489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:14.923786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:41:14.596216Z","time spent":"327.457855ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:41:25.928484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.549386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T19:41:25.928655Z","caller":"traceutil/trace.go:171","msg":"trace[675591781] linearizableReadLoop","detail":"{readStateIndex:9339; appliedIndex:9338; }","duration":"223.316905ms","start":"2026-10-26T19:41:25.705313Z","end":"2026-10-26T19:41:25.92863Z","steps":["trace[675591781] 'read index received' (duration: 88.344501ms)","trace[675591781] 'applied index is now lower than readState.Index' (duration: 134.970024ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:41:25.928762Z","caller":"traceutil/trace.go:171","msg":"trace[288282129] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9013; }","duration":"265.897761ms","start":"2026-10-26T19:41:25.662824Z","end":"2026-10-26T19:41:25.928721Z","steps":["trace[288282129] 'process raft request' (duration: 130.911756ms)","trace[288282129] 'compare' (duration: 134.116348ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:41:25.92894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.633072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2ml49\" ","response":"range_response_count:1 size:4749"} {"level":"info","ts":"2026-10-26T19:41:25.928984Z","caller":"traceutil/trace.go:171","msg":"trace[1078760920] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2ml49; range_end:; response_count:1; response_revision:9013; }","duration":"223.729254ms","start":"2026-10-26T19:41:25.705243Z","end":"2026-10-26T19:41:25.928972Z","steps":["trace[1078760920] 'agreement among raft nodes before linearized reading' (duration: 223.517349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:41:25.929225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.885376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-26T19:41:25.929413Z","caller":"traceutil/trace.go:171","msg":"trace[1018992448] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9013; }","duration":"113.207973ms","start":"2026-10-26T19:41:25.816175Z","end":"2026-10-26T19:41:25.929383Z","steps":["trace[1018992448] 'agreement among raft nodes before linearized reading' (duration: 112.686672ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:41:28.915066Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a2.wal"} {"level":"info","ts":"2026-10-26T19:42:27.648808Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T19:42:27.655141Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T19:42:27.65525Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T19:42:53.422374Z","caller":"traceutil/trace.go:171","msg":"trace[940057765] transaction","detail":"{read_only:false; response_revision:9852; number_of_response:1; }","duration":"118.521533ms","start":"2026-10-26T19:42:53.303815Z","end":"2026-10-26T19:42:53.422336Z","steps":["trace[940057765] 'process raft request' (duration: 118.254268ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:43:15.133365Z","caller":"traceutil/trace.go:171","msg":"trace[121512606] transaction","detail":"{read_only:false; response_revision:10021; number_of_response:1; }","duration":"114.792917ms","start":"2026-10-26T19:43:15.018538Z","end":"2026-10-26T19:43:15.133331Z","steps":["trace[121512606] 'process raft request' (duration: 114.583283ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:44:14.45146Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8024} {"level":"info","ts":"2026-10-26T19:44:14.545596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8024,"took":"91.548522ms","hash":2222377414,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":24842240,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-26T19:44:14.54571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2222377414,"revision":8024,"compact-revision":5774} {"level":"info","ts":"2026-10-26T19:46:23.60315Z","caller":"traceutil/trace.go:171","msg":"trace[111885871] linearizableReadLoop","detail":"{readStateIndex:12022; appliedIndex:12021; }","duration":"101.8905ms","start":"2026-10-26T19:46:23.501228Z","end":"2026-10-26T19:46:23.603119Z","steps":["trace[111885871] 'read index received' (duration: 64.701388ms)","trace[111885871] 'applied index is now lower than readState.Index' (duration: 37.187122ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:46:23.603356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.086354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:46:23.603506Z","caller":"traceutil/trace.go:171","msg":"trace[2075243331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11619; }","duration":"102.253297ms","start":"2026-10-26T19:46:23.501224Z","end":"2026-10-26T19:46:23.603477Z","steps":["trace[2075243331] 'agreement among raft nodes before linearized reading' (duration: 102.009493ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:49:14.458484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10412} {"level":"info","ts":"2026-10-26T19:49:14.551201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10412,"took":"89.43987ms","hash":2939977243,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":27336704,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T19:49:14.551285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2939977243,"revision":10412,"compact-revision":8024} {"level":"info","ts":"2026-10-26T19:52:35.753876Z","caller":"traceutil/trace.go:171","msg":"trace[1614460177] transaction","detail":"{read_only:false; response_revision:14133; number_of_response:1; }","duration":"123.01584ms","start":"2026-10-26T19:52:35.630829Z","end":"2026-10-26T19:52:35.753845Z","steps":["trace[1614460177] 'process raft request' (duration: 122.924229ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:35.75388Z","caller":"traceutil/trace.go:171","msg":"trace[1494783509] transaction","detail":"{read_only:false; response_revision:14132; number_of_response:1; }","duration":"133.759965ms","start":"2026-10-26T19:52:35.620083Z","end":"2026-10-26T19:52:35.753843Z","steps":["trace[1494783509] 'process raft request' (duration: 129.664758ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:52:35.754146Z","caller":"traceutil/trace.go:171","msg":"trace[1085129991] transaction","detail":"{read_only:false; response_revision:14134; number_of_response:1; }","duration":"121.689026ms","start":"2026-10-26T19:52:35.632447Z","end":"2026-10-26T19:52:35.754136Z","steps":["trace[1085129991] 'process raft request' (duration: 121.36014ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:17.543222Z","caller":"traceutil/trace.go:171","msg":"trace[1809468963] linearizableReadLoop","detail":"{readStateIndex:14861; appliedIndex:14860; }","duration":"307.228151ms","start":"2026-10-26T19:53:17.235967Z","end":"2026-10-26T19:53:17.543196Z","steps":["trace[1809468963] 'read index received' (duration: 307.025717ms)","trace[1809468963] 'applied index is now lower than readState.Index' (duration: 201.254µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T19:53:17.543679Z","caller":"traceutil/trace.go:171","msg":"trace[697311115] transaction","detail":"{read_only:false; response_revision:14361; number_of_response:1; }","duration":"308.260571ms","start":"2026-10-26T19:53:17.235395Z","end":"2026-10-26T19:53:17.543655Z","steps":["trace[697311115] 'process raft request' (duration: 307.605688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:17.543814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:17.235364Z","time spent":"308.363813ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:17.543876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.889554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-26T19:53:17.543959Z","caller":"traceutil/trace.go:171","msg":"trace[541183476] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:14361; }","duration":"307.994805ms","start":"2026-10-26T19:53:17.235943Z","end":"2026-10-26T19:53:17.543938Z","steps":["trace[541183476] 'agreement among raft nodes before linearized reading' (duration: 307.73888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:17.544013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:17.235933Z","time spent":"308.066317ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1803,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-10-26T19:53:17.544381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.156589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T19:53:17.544481Z","caller":"traceutil/trace.go:171","msg":"trace[520431121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14362; }","duration":"307.302992ms","start":"2026-10-26T19:53:17.237156Z","end":"2026-10-26T19:53:17.544459Z","steps":["trace[520431121] 'agreement among raft nodes before linearized reading' (duration: 307.031188ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:17.544506Z","caller":"traceutil/trace.go:171","msg":"trace[55213594] transaction","detail":"{read_only:false; response_revision:14362; number_of_response:1; }","duration":"263.737019ms","start":"2026-10-26T19:53:17.280754Z","end":"2026-10-26T19:53:17.544491Z","steps":["trace[55213594] 'process raft request' (duration: 263.272831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:17.54454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:17.237134Z","time spent":"307.383133ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T19:53:27.905103Z","caller":"traceutil/trace.go:171","msg":"trace[1002943850] transaction","detail":"{read_only:false; response_revision:14531; number_of_response:1; }","duration":"227.574509ms","start":"2026-10-26T19:53:27.677497Z","end":"2026-10-26T19:53:27.905071Z","steps":["trace[1002943850] 'process raft request' (duration: 227.408445ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:27.999571Z","caller":"traceutil/trace.go:171","msg":"trace[177403090] transaction","detail":"{read_only:false; response_revision:14532; number_of_response:1; }","duration":"322.040713ms","start":"2026-10-26T19:53:27.677496Z","end":"2026-10-26T19:53:27.999536Z","steps":["trace[177403090] 'process raft request' (duration: 321.787519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:27.999787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:27.677475Z","time spent":"322.210217ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:53:28.000096Z","caller":"traceutil/trace.go:171","msg":"trace[58406991] transaction","detail":"{read_only:false; response_revision:14534; number_of_response:1; }","duration":"322.190376ms","start":"2026-10-26T19:53:27.677857Z","end":"2026-10-26T19:53:28.000047Z","steps":["trace[58406991] 'process raft request' (duration: 321.954882ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:28.0003Z","caller":"traceutil/trace.go:171","msg":"trace[1439356230] linearizableReadLoop","detail":"{readStateIndex:15039; appliedIndex:15033; }","duration":"321.695639ms","start":"2026-10-26T19:53:27.678572Z","end":"2026-10-26T19:53:28.000268Z","steps":["trace[1439356230] 'read index received' (duration: 226.412408ms)","trace[1439356230] 'applied index is now lower than readState.Index' (duration: 95.282261ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T19:53:28.000348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:27.677836Z","time spent":"322.377101ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T19:53:28.000426Z","caller":"traceutil/trace.go:171","msg":"trace[1095785665] transaction","detail":"{read_only:false; response_revision:14537; number_of_response:1; }","duration":"264.321311ms","start":"2026-10-26T19:53:27.736092Z","end":"2026-10-26T19:53:28.000413Z","steps":["trace[1095785665] 'process raft request' (duration: 264.133328ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:28.00053Z","caller":"traceutil/trace.go:171","msg":"trace[1348139067] transaction","detail":"{read_only:false; response_revision:14535; number_of_response:1; }","duration":"322.354769ms","start":"2026-10-26T19:53:27.678166Z","end":"2026-10-26T19:53:28.000521Z","steps":["trace[1348139067] 'process raft request' (duration: 321.816909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:28.000599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:27.678153Z","time spent":"322.40169ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:28.000596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.02869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T19:53:28.000723Z","caller":"traceutil/trace.go:171","msg":"trace[1843074581] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:14537; }","duration":"280.209924ms","start":"2026-10-26T19:53:27.720488Z","end":"2026-10-26T19:53:28.000698Z","steps":["trace[1843074581] 'agreement among raft nodes before linearized reading' (duration: 280.000351ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:28.000072Z","caller":"traceutil/trace.go:171","msg":"trace[1004528835] transaction","detail":"{read_only:false; response_revision:14533; number_of_response:1; }","duration":"322.252528ms","start":"2026-10-26T19:53:27.677805Z","end":"2026-10-26T19:53:28.000058Z","steps":["trace[1004528835] 'process raft request' (duration: 321.685557ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T19:53:28.000795Z","caller":"traceutil/trace.go:171","msg":"trace[941278779] transaction","detail":"{read_only:false; response_revision:14536; number_of_response:1; }","duration":"322.581323ms","start":"2026-10-26T19:53:27.678186Z","end":"2026-10-26T19:53:28.000768Z","steps":["trace[941278779] 'process raft request' (duration: 321.954012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:28.000937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:27.678174Z","time spent":"322.689485ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:28.001005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:27.677791Z","time spent":"323.091734ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T19:53:28.001039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.401931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-5ljtz\" ","response":"range_response_count:1 size:7032"} {"level":"info","ts":"2026-10-26T19:53:28.001098Z","caller":"traceutil/trace.go:171","msg":"trace[1964869418] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-5ljtz; range_end:; response_count:1; response_revision:14537; }","duration":"153.500973ms","start":"2026-10-26T19:53:27.847581Z","end":"2026-10-26T19:53:28.001082Z","steps":["trace[1964869418] 'agreement among raft nodes before linearized reading' (duration: 153.293169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:28.00136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.764848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:632549"} {"level":"info","ts":"2026-10-26T19:53:28.001436Z","caller":"traceutil/trace.go:171","msg":"trace[728743095] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:14537; }","duration":"322.876879ms","start":"2026-10-26T19:53:27.678539Z","end":"2026-10-26T19:53:28.001415Z","steps":["trace[728743095] 'agreement among raft nodes before linearized reading' (duration: 321.898791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T19:53:28.001488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T19:53:27.678526Z","time spent":"322.94587ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":67,"response size":632571,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-26T19:54:14.46689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12748} {"level":"info","ts":"2026-10-26T19:54:14.554489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12748,"took":"84.697857ms","hash":3898490260,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":28815360,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T19:54:14.554644Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3898490260,"revision":12748,"compact-revision":10412} {"level":"info","ts":"2026-10-26T19:59:14.477773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15029} {"level":"info","ts":"2026-10-26T19:59:14.577125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15029,"took":"96.402141ms","hash":292452022,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":30228480,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T19:59:14.577194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":292452022,"revision":15029,"compact-revision":12748} {"level":"info","ts":"2026-10-26T20:00:50.73744Z","caller":"traceutil/trace.go:171","msg":"trace[1314454111] transaction","detail":"{read_only:false; response_revision:18212; number_of_response:1; }","duration":"128.049484ms","start":"2026-10-26T20:00:50.609363Z","end":"2026-10-26T20:00:50.737412Z","steps":["trace[1314454111] 'process raft request' (duration: 76.355437ms)","trace[1314454111] 'compare' (duration: 51.426042ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:00:51.053118Z","caller":"traceutil/trace.go:171","msg":"trace[1924661655] transaction","detail":"{read_only:false; response_revision:18213; number_of_response:1; }","duration":"443.328802ms","start":"2026-10-26T20:00:50.609749Z","end":"2026-10-26T20:00:51.053078Z","steps":["trace[1924661655] 'process raft request' (duration: 384.423567ms)","trace[1924661655] 'compare' (duration: 58.586759ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:00:51.053136Z","caller":"traceutil/trace.go:171","msg":"trace[232676593] transaction","detail":"{read_only:false; response_revision:18214; number_of_response:1; }","duration":"443.337032ms","start":"2026-10-26T20:00:50.609759Z","end":"2026-10-26T20:00:51.053096Z","steps":["trace[232676593] 'process raft request' (duration: 443.22548ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:51.053279Z","caller":"traceutil/trace.go:171","msg":"trace[1507326937] transaction","detail":"{read_only:false; response_revision:18215; number_of_response:1; }","duration":"443.421174ms","start":"2026-10-26T20:00:50.609834Z","end":"2026-10-26T20:00:51.053255Z","steps":["trace[1507326937] 'process raft request' (duration: 443.20853ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:51.0533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:00:50.609726Z","time spent":"443.480665ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:00:51.053324Z","caller":"traceutil/trace.go:171","msg":"trace[768782125] linearizableReadLoop","detail":"{readStateIndex:18839; appliedIndex:18835; }","duration":"393.686754ms","start":"2026-10-26T20:00:50.65962Z","end":"2026-10-26T20:00:51.053307Z","steps":["trace[768782125] 'read index received' (duration: 26.096047ms)","trace[768782125] 'applied index is now lower than readState.Index' (duration: 367.589617ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:00:51.05335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:00:50.609734Z","time spent":"443.497836ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:00:51.053421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:00:50.609825Z","time spent":"443.517566ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:00:51.053553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.92505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7841"} {"level":"info","ts":"2026-10-26T20:00:51.053587Z","caller":"traceutil/trace.go:171","msg":"trace[252309141] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:18216; }","duration":"394.004461ms","start":"2026-10-26T20:00:50.659572Z","end":"2026-10-26T20:00:51.053577Z","steps":["trace[252309141] 'agreement among raft nodes before linearized reading' (duration: 393.872968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:51.053612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:00:50.659552Z","time spent":"394.053172ms","remote":"127.0.0.1:45384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7863,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-10-26T20:00:51.053653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.249527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-26T20:00:51.053719Z","caller":"traceutil/trace.go:171","msg":"trace[2080412739] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18216; }","duration":"317.374699ms","start":"2026-10-26T20:00:50.736329Z","end":"2026-10-26T20:00:51.053703Z","steps":["trace[2080412739] 'agreement among raft nodes before linearized reading' (duration: 317.193086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:00:51.05375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:00:50.736294Z","time spent":"317.448421ms","remote":"127.0.0.1:39702","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-10-26T20:00:51.054082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.827034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9kq5n\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-10-26T20:00:51.054142Z","caller":"traceutil/trace.go:171","msg":"trace[1475106268] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9kq5n; range_end:; response_count:1; response_revision:18216; }","duration":"276.932596ms","start":"2026-10-26T20:00:50.777194Z","end":"2026-10-26T20:00:51.054127Z","steps":["trace[1475106268] 'agreement among raft nodes before linearized reading' (duration: 276.698412ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:00:51.309721Z","caller":"traceutil/trace.go:171","msg":"trace[1401271952] transaction","detail":"{read_only:false; response_revision:18217; number_of_response:1; }","duration":"249.082504ms","start":"2026-10-26T20:00:51.060576Z","end":"2026-10-26T20:00:51.309658Z","steps":["trace[1401271952] 'process raft request' (duration: 178.966406ms)","trace[1401271952] 'compare' (duration: 69.896764ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:00:51.309876Z","caller":"traceutil/trace.go:171","msg":"trace[210118457] transaction","detail":"{read_only:false; response_revision:18218; number_of_response:1; }","duration":"204.866361ms","start":"2026-10-26T20:00:51.10498Z","end":"2026-10-26T20:00:51.309847Z","steps":["trace[210118457] 'process raft request' (duration: 204.617976ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:02:47.297817Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:02:47.306432Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T20:02:47.306646Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T20:04:14.484643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17462} {"level":"info","ts":"2026-10-26T20:04:14.594426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17462,"took":"103.621328ms","hash":571253565,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":35311616,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T20:04:14.594501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":571253565,"revision":17462,"compact-revision":15029} {"level":"info","ts":"2026-10-26T20:06:39.130486Z","caller":"traceutil/trace.go:171","msg":"trace[89013403] linearizableReadLoop","detail":"{readStateIndex:21668; appliedIndex:21667; }","duration":"167.345804ms","start":"2026-10-26T20:06:38.963111Z","end":"2026-10-26T20:06:39.130457Z","steps":["trace[89013403] 'read index received' (duration: 167.11771ms)","trace[89013403] 'applied index is now lower than readState.Index' (duration: 226.944µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:06:39.130552Z","caller":"traceutil/trace.go:171","msg":"trace[1268853713] transaction","detail":"{read_only:false; response_revision:20953; number_of_response:1; }","duration":"218.278906ms","start":"2026-10-26T20:06:38.912234Z","end":"2026-10-26T20:06:39.130513Z","steps":["trace[1268853713] 'process raft request' (duration: 218.054462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.130663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.538749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-26T20:06:39.130729Z","caller":"traceutil/trace.go:171","msg":"trace[2069246251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20953; }","duration":"167.66446ms","start":"2026-10-26T20:06:38.963051Z","end":"2026-10-26T20:06:39.130716Z","steps":["trace[2069246251] 'agreement among raft nodes before linearized reading' (duration: 167.546528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.130775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.689174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T20:06:39.130854Z","caller":"traceutil/trace.go:171","msg":"trace[285582935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20953; }","duration":"164.803566ms","start":"2026-10-26T20:06:38.96603Z","end":"2026-10-26T20:06:39.130834Z","steps":["trace[285582935] 'agreement among raft nodes before linearized reading' (duration: 164.618582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.31521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.160317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:06:39.315323Z","caller":"traceutil/trace.go:171","msg":"trace[791796741] transaction","detail":"{read_only:false; response_revision:20954; number_of_response:1; }","duration":"180.193869ms","start":"2026-10-26T20:06:39.135104Z","end":"2026-10-26T20:06:39.315298Z","steps":["trace[791796741] 'process raft request' (duration: 30.843748ms)","trace[791796741] 'compare' (duration: 148.937243ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:06:39.604458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.569794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:06:39.604723Z","caller":"traceutil/trace.go:171","msg":"trace[74068868] linearizableReadLoop","detail":"{readStateIndex:21673; appliedIndex:21669; }","duration":"333.70643ms","start":"2026-10-26T20:06:39.270976Z","end":"2026-10-26T20:06:39.604683Z","steps":["trace[74068868] 'read index received' (duration: 149.702858ms)","trace[74068868] 'applied index is now lower than readState.Index' (duration: 184.002302ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:06:39.604843Z","caller":"traceutil/trace.go:171","msg":"trace[1966946324] transaction","detail":"{read_only:false; response_revision:20956; number_of_response:1; }","duration":"442.404965ms","start":"2026-10-26T20:06:39.162383Z","end":"2026-10-26T20:06:39.604788Z","steps":["trace[1966946324] 'process raft request' (duration: 442.172411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.604929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.958296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-api-7db4487678-4nm7j.18e22cfb400936a4\" ","response":"range_response_count:1 size:907"} {"level":"info","ts":"2026-10-26T20:06:39.60491Z","caller":"traceutil/trace.go:171","msg":"trace[2128025378] transaction","detail":"{read_only:false; response_revision:20955; number_of_response:1; }","duration":"469.38922ms","start":"2026-10-26T20:06:39.135489Z","end":"2026-10-26T20:06:39.604879Z","steps":["trace[2128025378] 'process raft request' (duration: 285.301106ms)","trace[2128025378] 'compare' (duration: 183.435651ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:06:39.604963Z","caller":"traceutil/trace.go:171","msg":"trace[1312662237] range","detail":"{range_begin:/registry/events/openstack/heat-api-7db4487678-4nm7j.18e22cfb400936a4; range_end:; response_count:1; response_revision:20958; }","duration":"334.019816ms","start":"2026-10-26T20:06:39.27093Z","end":"2026-10-26T20:06:39.60495Z","steps":["trace[1312662237] 'agreement among raft nodes before linearized reading' (duration: 333.866043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.604993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:39.270914Z","time spent":"334.071478ms","remote":"127.0.0.1:39524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":929,"request content":"key:\"/registry/events/openstack/heat-api-7db4487678-4nm7j.18e22cfb400936a4\" "} {"level":"info","ts":"2026-10-26T20:06:39.604933Z","caller":"traceutil/trace.go:171","msg":"trace[928139094] transaction","detail":"{read_only:false; response_revision:20958; number_of_response:1; }","duration":"398.481787ms","start":"2026-10-26T20:06:39.206316Z","end":"2026-10-26T20:06:39.604798Z","steps":["trace[928139094] 'process raft request' (duration: 398.331684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.605033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:39.162355Z","time spent":"442.585448ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:06:39.605052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:39.135469Z","time spent":"469.504352ms","remote":"127.0.0.1:39702","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-10-26T20:06:39.605118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:39.206292Z","time spent":"398.767612ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:06:39.60517Z","caller":"traceutil/trace.go:171","msg":"trace[1235554132] transaction","detail":"{read_only:false; response_revision:20957; number_of_response:1; }","duration":"430.700442ms","start":"2026-10-26T20:06:39.174424Z","end":"2026-10-26T20:06:39.605125Z","steps":["trace[1235554132] 'process raft request' (duration: 430.186302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.605256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.62923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T20:06:39.605261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.273974ms","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:6"} {"level":"warn","ts":"2026-10-26T20:06:39.605291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:39.174411Z","time spent":"430.815934ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T20:06:39.605338Z","caller":"traceutil/trace.go:171","msg":"trace[1234663075] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:20958; }","duration":"198.416107ms","start":"2026-10-26T20:06:39.406903Z","end":"2026-10-26T20:06:39.60532Z","steps":["trace[1234663075] 'agreement among raft nodes before linearized reading' (duration: 198.252904ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:39.605336Z","caller":"traceutil/trace.go:171","msg":"trace[1035526237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20958; }","duration":"102.758082ms","start":"2026-10-26T20:06:39.502554Z","end":"2026-10-26T20:06:39.605312Z","steps":["trace[1035526237] 'agreement among raft nodes before linearized reading' (duration: 102.590559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.605333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.730491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-26T20:06:39.605418Z","caller":"traceutil/trace.go:171","msg":"trace[384087485] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20958; }","duration":"333.845463ms","start":"2026-10-26T20:06:39.271537Z","end":"2026-10-26T20:06:39.605382Z","steps":["trace[384087485] 'agreement among raft nodes before linearized reading' (duration: 333.593698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:06:39.605487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:06:39.271517Z","time spent":"333.957085ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-26T20:06:39.74262Z","caller":"traceutil/trace.go:171","msg":"trace[1938601956] transaction","detail":"{read_only:false; response_revision:20959; number_of_response:1; }","duration":"134.908826ms","start":"2026-10-26T20:06:39.607684Z","end":"2026-10-26T20:06:39.742593Z","steps":["trace[1938601956] 'process raft request' (duration: 130.593383ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:39.806798Z","caller":"traceutil/trace.go:171","msg":"trace[2006717413] transaction","detail":"{read_only:false; response_revision:20960; number_of_response:1; }","duration":"196.480281ms","start":"2026-10-26T20:06:39.610286Z","end":"2026-10-26T20:06:39.806766Z","steps":["trace[2006717413] 'process raft request' (duration: 196.077983ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:06:39.935763Z","caller":"traceutil/trace.go:171","msg":"trace[148759309] transaction","detail":"{read_only:false; response_revision:20961; number_of_response:1; }","duration":"126.352852ms","start":"2026-10-26T20:06:39.809366Z","end":"2026-10-26T20:06:39.935719Z","steps":["trace[148759309] 'process raft request' (duration: 106.629245ms)","trace[148759309] 'compare' (duration: 19.4074ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:06:40.243049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.070279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:06:40.243157Z","caller":"traceutil/trace.go:171","msg":"trace[1287399534] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:20961; }","duration":"168.248592ms","start":"2026-10-26T20:06:40.074885Z","end":"2026-10-26T20:06:40.243134Z","steps":["trace[1287399534] 'count revisions from in-memory index tree' (duration: 167.983017ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:09:14.491673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19928} {"level":"info","ts":"2026-10-26T20:09:14.61656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19928,"took":"121.60602ms","hash":2812260564,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":30158848,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:09:14.616634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2812260564,"revision":19928,"compact-revision":17462} {"level":"info","ts":"2026-10-26T20:10:33.527706Z","caller":"traceutil/trace.go:171","msg":"trace[591286563] linearizableReadLoop","detail":"{readStateIndex:24195; appliedIndex:24194; }","duration":"241.974227ms","start":"2026-10-26T20:10:33.285706Z","end":"2026-10-26T20:10:33.527681Z","steps":["trace[591286563] 'read index received' (duration: 232.002827ms)","trace[591286563] 'applied index is now lower than readState.Index' (duration: 9.97012ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:10:33.527876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.15456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:10:33.527795Z","caller":"traceutil/trace.go:171","msg":"trace[456214772] transaction","detail":"{read_only:false; response_revision:23409; number_of_response:1; }","duration":"258.078795ms","start":"2026-10-26T20:10:33.269686Z","end":"2026-10-26T20:10:33.527765Z","steps":["trace[456214772] 'process raft request' (duration: 248.013153ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:10:33.527934Z","caller":"traceutil/trace.go:171","msg":"trace[1164370149] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:23409; }","duration":"242.250962ms","start":"2026-10-26T20:10:33.285669Z","end":"2026-10-26T20:10:33.52792Z","steps":["trace[1164370149] 'agreement among raft nodes before linearized reading' (duration: 242.15328ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:10:33.532131Z","caller":"traceutil/trace.go:171","msg":"trace[9220139] transaction","detail":"{read_only:false; response_revision:23410; number_of_response:1; }","duration":"241.739774ms","start":"2026-10-26T20:10:33.290375Z","end":"2026-10-26T20:10:33.532114Z","steps":["trace[9220139] 'process raft request' (duration: 241.358796ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:48.947983Z","caller":"traceutil/trace.go:171","msg":"trace[872435050] transaction","detail":"{read_only:false; response_revision:25343; number_of_response:1; }","duration":"164.261955ms","start":"2026-10-26T20:13:48.783701Z","end":"2026-10-26T20:13:48.947963Z","steps":["trace[872435050] 'process raft request' (duration: 164.113082ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:49.262963Z","caller":"traceutil/trace.go:171","msg":"trace[86517729] linearizableReadLoop","detail":"{readStateIndex:26180; appliedIndex:26178; }","duration":"413.45938ms","start":"2026-10-26T20:13:48.849478Z","end":"2026-10-26T20:13:49.262938Z","steps":["trace[86517729] 'read index received' (duration: 98.329827ms)","trace[86517729] 'applied index is now lower than readState.Index' (duration: 315.128143ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:13:49.26307Z","caller":"traceutil/trace.go:171","msg":"trace[745275702] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"475.303489ms","start":"2026-10-26T20:13:48.787755Z","end":"2026-10-26T20:13:49.263058Z","steps":["trace[745275702] 'process raft request' (duration: 475.026194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:49.26317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:48.787706Z","time spent":"475.388761ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T20:13:49.263269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.708235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:13:49.263383Z","caller":"traceutil/trace.go:171","msg":"trace[921221138] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25344; }","duration":"413.929889ms","start":"2026-10-26T20:13:48.849433Z","end":"2026-10-26T20:13:49.263363Z","steps":["trace[921221138] 'agreement among raft nodes before linearized reading' (duration: 413.697065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:49.263522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:48.849409Z","time spent":"414.087272ms","remote":"127.0.0.1:39866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:13:49.409937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.884672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T20:13:49.410121Z","caller":"traceutil/trace.go:171","msg":"trace[2097861698] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"131.248045ms","start":"2026-10-26T20:13:49.278856Z","end":"2026-10-26T20:13:49.410105Z","steps":["trace[2097861698] 'process raft request' (duration: 131.176844ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:13:49.410277Z","caller":"traceutil/trace.go:171","msg":"trace[220958541] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"235.021916ms","start":"2026-10-26T20:13:49.175232Z","end":"2026-10-26T20:13:49.410254Z","steps":["trace[220958541] 'process raft request' (duration: 119.733356ms)","trace[220958541] 'compare' (duration: 114.75592ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:13:49.410332Z","caller":"traceutil/trace.go:171","msg":"trace[1581438430] linearizableReadLoop","detail":"{readStateIndex:26181; appliedIndex:26180; }","duration":"147.288551ms","start":"2026-10-26T20:13:49.263027Z","end":"2026-10-26T20:13:49.410316Z","steps":["trace[1581438430] 'read index received' (duration: 31.93627ms)","trace[1581438430] 'applied index is now lower than readState.Index' (duration: 115.349601ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T20:13:49.410451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.069605ms","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:6"} {"level":"info","ts":"2026-10-26T20:13:49.410497Z","caller":"traceutil/trace.go:171","msg":"trace[1466728120] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25346; }","duration":"475.164547ms","start":"2026-10-26T20:13:48.935318Z","end":"2026-10-26T20:13:49.410483Z","steps":["trace[1466728120] 'agreement among raft nodes before linearized reading' (duration: 475.079696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:49.410541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:48.935293Z","time spent":"475.231168ms","remote":"127.0.0.1:54934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-26T20:13:49.410648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.342052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-26T20:13:49.410771Z","caller":"traceutil/trace.go:171","msg":"trace[408925635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25347; }","duration":"528.518986ms","start":"2026-10-26T20:13:48.882233Z","end":"2026-10-26T20:13:49.410752Z","steps":["trace[408925635] 'agreement among raft nodes before linearized reading' (duration: 528.2087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:49.41081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:48.882214Z","time spent":"528.586736ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T20:13:49.410821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.110064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:13:49.410873Z","caller":"traceutil/trace.go:171","msg":"trace[1420350311] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25347; }","duration":"259.171106ms","start":"2026-10-26T20:13:49.151687Z","end":"2026-10-26T20:13:49.410858Z","steps":["trace[1420350311] 'agreement among raft nodes before linearized reading' (duration: 258.752647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:49.411324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.87658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:13:49.411451Z","caller":"traceutil/trace.go:171","msg":"trace[1978003964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25348; }","duration":"466.031503ms","start":"2026-10-26T20:13:48.945363Z","end":"2026-10-26T20:13:49.411395Z","steps":["trace[1978003964] 'agreement among raft nodes before linearized reading' (duration: 465.023153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:13:49.411624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T20:13:48.945347Z","time spent":"466.263607ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T20:14:14.504143Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22169} {"level":"info","ts":"2026-10-26T20:14:14.621678Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22169,"took":"112.836313ms","hash":3598146235,"current-db-size-bytes":56090624,"current-db-size":"56 MB","current-db-size-in-use-bytes":45821952,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-26T20:14:14.621764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3598146235,"revision":22169,"compact-revision":19928} {"level":"info","ts":"2026-10-26T20:14:51.683789Z","caller":"traceutil/trace.go:171","msg":"trace[1014060260] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"133.950056ms","start":"2026-10-26T20:14:51.549816Z","end":"2026-10-26T20:14:51.683766Z","steps":["trace[1014060260] 'process raft request' (duration: 133.762713ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:52.026552Z","caller":"traceutil/trace.go:171","msg":"trace[1404772792] transaction","detail":"{read_only:false; response_revision:25905; number_of_response:1; }","duration":"289.094006ms","start":"2026-10-26T20:14:51.73743Z","end":"2026-10-26T20:14:52.026524Z","steps":["trace[1404772792] 'process raft request' (duration: 288.925673ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:52.053769Z","caller":"traceutil/trace.go:171","msg":"trace[1076896928] linearizableReadLoop","detail":"{readStateIndex:26757; appliedIndex:26754; }","duration":"229.931078ms","start":"2026-10-26T20:14:51.823782Z","end":"2026-10-26T20:14:52.053714Z","steps":["trace[1076896928] 'read index received' (duration: 202.532665ms)","trace[1076896928] 'applied index is now lower than readState.Index' (duration: 27.397083ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:14:52.053808Z","caller":"traceutil/trace.go:171","msg":"trace[2079792689] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"295.447207ms","start":"2026-10-26T20:14:51.758333Z","end":"2026-10-26T20:14:52.05378Z","steps":["trace[2079792689] 'process raft request' (duration: 292.846728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:52.054013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.372196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-26T20:14:52.054064Z","caller":"traceutil/trace.go:171","msg":"trace[468597224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25907; }","duration":"230.496199ms","start":"2026-10-26T20:14:51.823557Z","end":"2026-10-26T20:14:52.054053Z","steps":["trace[468597224] 'agreement among raft nodes before linearized reading' (duration: 230.327005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:52.054112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.520909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T20:14:52.054196Z","caller":"traceutil/trace.go:171","msg":"trace[1262458485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25907; }","duration":"229.655792ms","start":"2026-10-26T20:14:51.824526Z","end":"2026-10-26T20:14:52.054181Z","steps":["trace[1262458485] 'agreement among raft nodes before linearized reading' (duration: 229.366316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:52.054195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.91388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:14:52.054256Z","caller":"traceutil/trace.go:171","msg":"trace[2125667906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25907; }","duration":"226.001382ms","start":"2026-10-26T20:14:51.828241Z","end":"2026-10-26T20:14:52.054242Z","steps":["trace[2125667906] 'agreement among raft nodes before linearized reading' (duration: 225.848779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:14:52.054323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.5513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T20:14:52.053845Z","caller":"traceutil/trace.go:171","msg":"trace[1757580933] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"294.118093ms","start":"2026-10-26T20:14:51.759702Z","end":"2026-10-26T20:14:52.05382Z","steps":["trace[1757580933] 'process raft request' (duration: 293.95948ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:14:52.054441Z","caller":"traceutil/trace.go:171","msg":"trace[40721447] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25907; }","duration":"229.691213ms","start":"2026-10-26T20:14:51.824721Z","end":"2026-10-26T20:14:52.054413Z","steps":["trace[40721447] 'agreement among raft nodes before linearized reading' (duration: 229.476028ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:15:20.740466Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006986.wal"} {"level":"info","ts":"2026-10-26T20:18:57.670191Z","caller":"traceutil/trace.go:171","msg":"trace[99380740] linearizableReadLoop","detail":"{readStateIndex:29267; appliedIndex:29265; }","duration":"112.254113ms","start":"2026-10-26T20:18:57.557909Z","end":"2026-10-26T20:18:57.670163Z","steps":["trace[99380740] 'read index received' (duration: 44.804135ms)","trace[99380740] 'applied index is now lower than readState.Index' (duration: 67.448818ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T20:18:57.670574Z","caller":"traceutil/trace.go:171","msg":"trace[2147386683] transaction","detail":"{read_only:false; response_revision:28356; number_of_response:1; }","duration":"121.178164ms","start":"2026-10-26T20:18:57.549383Z","end":"2026-10-26T20:18:57.670561Z","steps":["trace[2147386683] 'process raft request' (duration: 120.602034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:18:57.67092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.000997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-26T20:18:57.67096Z","caller":"traceutil/trace.go:171","msg":"trace[316607326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28356; }","duration":"113.102239ms","start":"2026-10-26T20:18:57.557848Z","end":"2026-10-26T20:18:57.670951Z","steps":["trace[316607326] 'agreement among raft nodes before linearized reading' (duration: 112.932266ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T20:18:57.671152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.823794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T20:18:57.671188Z","caller":"traceutil/trace.go:171","msg":"trace[1751780799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28356; }","duration":"102.074979ms","start":"2026-10-26T20:18:57.569102Z","end":"2026-10-26T20:18:57.671177Z","steps":["trace[1751780799] 'agreement among raft nodes before linearized reading' (duration: 101.977597ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T20:19:14.51237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25616} {"level":"info","ts":"2026-10-26T20:19:14.699197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25616,"took":"181.910734ms","hash":261383410,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":36237312,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T20:19:14.699283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":261383410,"revision":25616,"compact-revision":22169} {"level":"info","ts":"2026-10-26T20:20:27.475561Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:20:27.482244Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T20:20:27.482416Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T20:24:14.520722Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28495} {"level":"info","ts":"2026-10-26T20:24:14.672669Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28495,"took":"146.484477ms","hash":1164789919,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":31563776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:24:14.672743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1164789919,"revision":28495,"compact-revision":25616} {"level":"info","ts":"2026-10-26T20:29:14.530798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30724} {"level":"info","ts":"2026-10-26T20:29:14.690548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30724,"took":"155.486817ms","hash":3718072241,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":30507008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:29:14.690632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3718072241,"revision":30724,"compact-revision":28495} {"level":"info","ts":"2026-10-26T20:34:14.539829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32977} {"level":"info","ts":"2026-10-26T20:34:14.708457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32977,"took":"164.258805ms","hash":904421952,"current-db-size-bytes":57507840,"current-db-size":"58 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T20:34:14.708524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":904421952,"revision":32977,"compact-revision":30724} {"level":"info","ts":"2026-10-26T20:39:14.549022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35231} {"level":"info","ts":"2026-10-26T20:39:14.686602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35231,"took":"133.263143ms","hash":2551842866,"current-db-size-bytes":57507840,"current-db-size":"58 MB","current-db-size-in-use-bytes":32464896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:39:14.686671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2551842866,"revision":35231,"compact-revision":32977} {"level":"info","ts":"2026-10-26T20:41:58.773657Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T20:41:58.780006Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T20:41:58.780126Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T20:44:14.556564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37503} {"level":"info","ts":"2026-10-26T20:44:14.694682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37503,"took":"133.809816ms","hash":2352489691,"current-db-size-bytes":59199488,"current-db-size":"59 MB","current-db-size-in-use-bytes":32354304,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T20:44:14.694757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2352489691,"revision":37503,"compact-revision":35231}