{"level":"warn","ts":"2026-10-17T09:29:32.952432Z","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-17T09:29:32.952589Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.17: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.17:2380","--initial-cluster=instance=https://162.253.55.17:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.17:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.17: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-17T09:29:32.952711Z","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-17T09:29:32.952763Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.17:2380"]} {"level":"info","ts":"2026-10-17T09:29:32.9528Z","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-17T09:29:32.9543Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"]} {"level":"info","ts":"2026-10-17T09:29:32.954509Z","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.17:2380"],"listen-peer-urls":["https://162.253.55.17:2380"],"advertise-client-urls":["https://162.253.55.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.17: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-17T09:29:32.958453Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.501158ms"} {"level":"info","ts":"2026-10-17T09:29:32.962875Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9d6ea9653fb28c79","cluster-id":"57f89a414bd43f68"} {"level":"info","ts":"2026-10-17T09:29:32.963007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=()"} {"level":"info","ts":"2026-10-17T09:29:32.963036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became follower at term 0"} {"level":"info","ts":"2026-10-17T09:29:32.96312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9d6ea9653fb28c79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-17T09:29:32.96313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became follower at term 1"} {"level":"info","ts":"2026-10-17T09:29:32.963191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=(11344190763718315129)"} {"level":"warn","ts":"2026-10-17T09:29:32.967664Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-17T09:29:32.969315Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-17T09:29:32.970687Z","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-17T09:29:32.973537Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9d6ea9653fb28c79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-17T09:29:32.97423Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9d6ea9653fb28c79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-17T09:29:32.974241Z","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-17T09:29:32.974441Z","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-17T09:29:32.974501Z","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-17T09:29:32.976165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 switched to configuration voters=(11344190763718315129)"} {"level":"info","ts":"2026-10-17T09:29:32.97641Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"57f89a414bd43f68","local-member-id":"9d6ea9653fb28c79","added-peer-id":"9d6ea9653fb28c79","added-peer-peer-urls":["https://162.253.55.17:2380"]} {"level":"info","ts":"2026-10-17T09:29:32.979454Z","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-17T09:29:32.979558Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.17:2380"} {"level":"info","ts":"2026-10-17T09:29:32.979596Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.17:2380"} {"level":"info","ts":"2026-10-17T09:29:32.979958Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-17T09:29:32.979904Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9d6ea9653fb28c79","initial-advertise-peer-urls":["https://162.253.55.17:2380"],"listen-peer-urls":["https://162.253.55.17:2380"],"advertise-client-urls":["https://162.253.55.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-17T09:29:33.863724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 is starting a new election at term 1"} {"level":"info","ts":"2026-10-17T09:29:33.863827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-17T09:29:33.86387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 received MsgPreVoteResp from 9d6ea9653fb28c79 at term 1"} {"level":"info","ts":"2026-10-17T09:29:33.863889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became candidate at term 2"} {"level":"info","ts":"2026-10-17T09:29:33.863905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 received MsgVoteResp from 9d6ea9653fb28c79 at term 2"} {"level":"info","ts":"2026-10-17T09:29:33.86392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9d6ea9653fb28c79 became leader at term 2"} {"level":"info","ts":"2026-10-17T09:29:33.863933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9d6ea9653fb28c79 elected leader 9d6ea9653fb28c79 at term 2"} {"level":"info","ts":"2026-10-17T09:29:33.865191Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T09:29:33.866196Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T09:29:33.866226Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-17T09:29:33.866644Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9d6ea9653fb28c79","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.17:2379]}","request-path":"/0/members/9d6ea9653fb28c79/attributes","cluster-id":"57f89a414bd43f68","publish-timeout":"7s"} {"level":"info","ts":"2026-10-17T09:29:33.866675Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-17T09:29:33.866746Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-17T09:29:33.86694Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"57f89a414bd43f68","local-member-id":"9d6ea9653fb28c79","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T09:29:33.867086Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T09:29:33.867135Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-17T09:29:33.869129Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-17T09:29:33.869724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.17:2379"} {"level":"info","ts":"2026-10-17T09:30:17.031003Z","caller":"traceutil/trace.go:171","msg":"trace[1182183695] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"106.496583ms","start":"2026-10-17T09:30:16.924477Z","end":"2026-10-17T09:30:17.030974Z","steps":["trace[1182183695] 'process raft request' (duration: 34.741193ms)","trace[1182183695] 'compare' (duration: 71.611527ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T09:30:22.911604Z","caller":"traceutil/trace.go:171","msg":"trace[1629882426] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"248.11422ms","start":"2026-10-17T09:30:22.663448Z","end":"2026-10-17T09:30:22.911562Z","steps":["trace[1629882426] 'process raft request' (duration: 247.811803ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:30:23.07843Z","caller":"traceutil/trace.go:171","msg":"trace[585681297] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"410.000407ms","start":"2026-10-17T09:30:22.66837Z","end":"2026-10-17T09:30:23.07837Z","steps":["trace[585681297] 'process raft request' (duration: 408.007102ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:30:23.079088Z","caller":"traceutil/trace.go:171","msg":"trace[443835548] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"161.275725ms","start":"2026-10-17T09:30:22.917758Z","end":"2026-10-17T09:30:23.079034Z","steps":["trace[443835548] 'read index received' (duration: 158.550024ms)","trace[443835548] 'applied index is now lower than readState.Index' (duration: 2.72442ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T09:30:23.079108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T09:30:22.668358Z","time spent":"410.16526ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T09:30:23.079181Z","caller":"traceutil/trace.go:171","msg":"trace[1108742778] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"385.817748ms","start":"2026-10-17T09:30:22.693331Z","end":"2026-10-17T09:30:23.079148Z","steps":["trace[1108742778] 'process raft request' (duration: 385.202655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:30:23.079238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.456648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8dlmk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-17T09:30:23.079322Z","caller":"traceutil/trace.go:171","msg":"trace[566814027] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8dlmk; range_end:; response_count:1; response_revision:602; }","duration":"161.594472ms","start":"2026-10-17T09:30:22.917715Z","end":"2026-10-17T09:30:23.07931Z","steps":["trace[566814027] 'agreement among raft nodes before linearized reading' (duration: 161.451408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:30:23.079333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T09:30:22.693311Z","time spent":"385.93797ms","remote":"127.0.0.1:45312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T09:30:38.671461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.326695ms","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-17T09:30:38.671557Z","caller":"traceutil/trace.go:171","msg":"trace[2065133328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"168.504229ms","start":"2026-10-17T09:30:38.503034Z","end":"2026-10-17T09:30:38.671539Z","steps":["trace[2065133328] 'range keys from in-memory index tree' (duration: 168.055359ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:31:08.303838Z","caller":"traceutil/trace.go:171","msg":"trace[1203009116] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"100.926321ms","start":"2026-10-17T09:31:08.20288Z","end":"2026-10-17T09:31:08.303806Z","steps":["trace[1203009116] 'process raft request' (duration: 100.773978ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:31:31.419437Z","caller":"traceutil/trace.go:171","msg":"trace[1004085455] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"130.178497ms","start":"2026-10-17T09:31:31.289205Z","end":"2026-10-17T09:31:31.419384Z","steps":["trace[1004085455] 'process raft request' (duration: 129.798299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:31:55.767255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.284123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T09:31:55.76739Z","caller":"traceutil/trace.go:171","msg":"trace[1943798133] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"301.638675ms","start":"2026-10-17T09:31:55.465727Z","end":"2026-10-17T09:31:55.767366Z","steps":["trace[1943798133] 'process raft request' (duration: 55.925221ms)","trace[1943798133] 'compare' (duration: 245.057458ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T09:31:55.767495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T09:31:55.465695Z","time spent":"301.756877ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T09:31:55.824899Z","caller":"traceutil/trace.go:171","msg":"trace[442227023] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"358.696912ms","start":"2026-10-17T09:31:55.466178Z","end":"2026-10-17T09:31:55.824875Z","steps":["trace[442227023] 'process raft request' (duration: 358.536699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:31:55.82505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T09:31:55.466156Z","time spent":"358.815724ms","remote":"127.0.0.1:45366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T09:31:55.825243Z","caller":"traceutil/trace.go:171","msg":"trace[1626799290] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"358.972927ms","start":"2026-10-17T09:31:55.46624Z","end":"2026-10-17T09:31:55.825213Z","steps":["trace[1626799290] 'process raft request' (duration: 358.565699ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:31:55.825373Z","caller":"traceutil/trace.go:171","msg":"trace[848001087] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1914; }","duration":"301.692705ms","start":"2026-10-17T09:31:55.523655Z","end":"2026-10-17T09:31:55.825348Z","steps":["trace[848001087] 'read index received' (duration: 300.9992ms)","trace[848001087] 'applied index is now lower than readState.Index' (duration: 692.395µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T09:31:55.825451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T09:31:55.466218Z","time spent":"359.105971ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T09:31:55.825531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.855969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-17T09:31:55.825571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.339045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T09:31:55.825593Z","caller":"traceutil/trace.go:171","msg":"trace[299299907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1845; }","duration":"301.969741ms","start":"2026-10-17T09:31:55.523608Z","end":"2026-10-17T09:31:55.825578Z","steps":["trace[299299907] 'agreement among raft nodes before linearized reading' (duration: 301.852769ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:31:55.825612Z","caller":"traceutil/trace.go:171","msg":"trace[976618329] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1845; }","duration":"260.413577ms","start":"2026-10-17T09:31:55.565187Z","end":"2026-10-17T09:31:55.825601Z","steps":["trace[976618329] 'agreement among raft nodes before linearized reading' (duration: 260.345125ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:31:55.825523Z","caller":"traceutil/trace.go:171","msg":"trace[2067958675] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"359.11245ms","start":"2026-10-17T09:31:55.466334Z","end":"2026-10-17T09:31:55.825446Z","steps":["trace[2067958675] 'process raft request' (duration: 358.763063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:31:55.825678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.960023ms","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-17T09:31:55.825685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.634439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-10-17T09:31:55.82569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.668085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T09:31:55.825733Z","caller":"traceutil/trace.go:171","msg":"trace[755126723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1845; }","duration":"293.046585ms","start":"2026-10-17T09:31:55.532672Z","end":"2026-10-17T09:31:55.825718Z","steps":["trace[755126723] 'agreement among raft nodes before linearized reading' (duration: 292.855791ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:31:55.825745Z","caller":"traceutil/trace.go:171","msg":"trace[618178775] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"279.729281ms","start":"2026-10-17T09:31:55.545996Z","end":"2026-10-17T09:31:55.825725Z","steps":["trace[618178775] 'agreement among raft nodes before linearized reading' (duration: 279.543517ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:31:55.82575Z","caller":"traceutil/trace.go:171","msg":"trace[540270228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1845; }","duration":"291.771978ms","start":"2026-10-17T09:31:55.533961Z","end":"2026-10-17T09:31:55.825733Z","steps":["trace[540270228] 'agreement among raft nodes before linearized reading' (duration: 291.611004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:31:55.825814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T09:31:55.466326Z","time spent":"359.356917ms","remote":"127.0.0.1:45366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T09:31:55.825629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T09:31:55.523594Z","time spent":"302.028342ms","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-17T09:32:39.582342Z","caller":"traceutil/trace.go:171","msg":"trace[615374924] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"167.395245ms","start":"2026-10-17T09:32:39.414915Z","end":"2026-10-17T09:32:39.582311Z","steps":["trace[615374924] 'process raft request' (duration: 167.183291ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:32:45.024959Z","caller":"traceutil/trace.go:171","msg":"trace[1243612258] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"177.335703ms","start":"2026-10-17T09:32:44.847596Z","end":"2026-10-17T09:32:45.024931Z","steps":["trace[1243612258] 'process raft request' (duration: 177.18397ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:32:45.029161Z","caller":"traceutil/trace.go:171","msg":"trace[460375685] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"107.487826ms","start":"2026-10-17T09:32:44.921655Z","end":"2026-10-17T09:32:45.029143Z","steps":["trace[460375685] 'process raft request' (duration: 107.455125ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:32:45.029416Z","caller":"traceutil/trace.go:171","msg":"trace[2118399763] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"110.584945ms","start":"2026-10-17T09:32:44.9188Z","end":"2026-10-17T09:32:45.029385Z","steps":["trace[2118399763] 'process raft request' (duration: 110.241016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:32:45.415415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.565619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-17T09:32:45.415511Z","caller":"traceutil/trace.go:171","msg":"trace[620962319] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2166; }","duration":"262.738043ms","start":"2026-10-17T09:32:45.152751Z","end":"2026-10-17T09:32:45.415489Z","steps":["trace[620962319] 'range keys from in-memory index tree' (duration: 262.375576ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:32:45.546234Z","caller":"traceutil/trace.go:171","msg":"trace[394575657] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"126.030285ms","start":"2026-10-17T09:32:45.420173Z","end":"2026-10-17T09:32:45.546203Z","steps":["trace[394575657] 'process raft request' (duration: 125.859671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:32:50.981865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.996633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T09:32:50.981973Z","caller":"traceutil/trace.go:171","msg":"trace[1830651929] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2194; }","duration":"180.180117ms","start":"2026-10-17T09:32:50.801765Z","end":"2026-10-17T09:32:50.981945Z","steps":["trace[1830651929] 'count revisions from in-memory index tree' (duration: 179.871459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:32:50.981978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.341927ms","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-17T09:32:50.982043Z","caller":"traceutil/trace.go:171","msg":"trace[665250252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2194; }","duration":"159.44569ms","start":"2026-10-17T09:32:50.822583Z","end":"2026-10-17T09:32:50.982029Z","steps":["trace[665250252] 'range keys from in-memory index tree' (duration: 159.116702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:33:42.082773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.848654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-17T09:33:42.082864Z","caller":"traceutil/trace.go:171","msg":"trace[775721885] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2640; }","duration":"139.986647ms","start":"2026-10-17T09:33:41.942858Z","end":"2026-10-17T09:33:42.082845Z","steps":["trace[775721885] 'range keys from in-memory index tree' (duration: 139.69304ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:34:15.253093Z","caller":"traceutil/trace.go:171","msg":"trace[1301074446] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"109.801537ms","start":"2026-10-17T09:34:15.143259Z","end":"2026-10-17T09:34:15.25306Z","steps":["trace[1301074446] 'process raft request' (duration: 109.634173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:34:15.59853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.659336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-17T09:34:15.598903Z","caller":"traceutil/trace.go:171","msg":"trace[1838198230] linearizableReadLoop","detail":"{readStateIndex:2954; appliedIndex:2952; }","duration":"185.940383ms","start":"2026-10-17T09:34:15.412765Z","end":"2026-10-17T09:34:15.598705Z","steps":["trace[1838198230] 'read index received' (duration: 63.95054ms)","trace[1838198230] 'applied index is now lower than readState.Index' (duration: 121.988593ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T09:34:15.599015Z","caller":"traceutil/trace.go:171","msg":"trace[246574981] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"245.631943ms","start":"2026-10-17T09:34:15.353357Z","end":"2026-10-17T09:34:15.598988Z","steps":["trace[246574981] 'process raft request' (duration: 245.283995ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:34:15.599008Z","caller":"traceutil/trace.go:171","msg":"trace[721686003] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"248.235739ms","start":"2026-10-17T09:34:15.350741Z","end":"2026-10-17T09:34:15.598976Z","steps":["trace[721686003] 'process raft request' (duration: 126.015371ms)","trace[721686003] 'compare' (duration: 121.506133ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T09:34:15.599294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.495105ms","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-17T09:34:15.599421Z","caller":"traceutil/trace.go:171","msg":"trace[1730136513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2845; }","duration":"186.651209ms","start":"2026-10-17T09:34:15.412718Z","end":"2026-10-17T09:34:15.599369Z","steps":["trace[1730136513] 'agreement among raft nodes before linearized reading' (duration: 186.291861ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:34:15.812893Z","caller":"traceutil/trace.go:171","msg":"trace[11472138] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"208.632671ms","start":"2026-10-17T09:34:15.604227Z","end":"2026-10-17T09:34:15.81286Z","steps":["trace[11472138] 'process raft request' (duration: 150.781794ms)","trace[11472138] 'compare' (duration: 57.731734ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T09:37:21.930573Z","caller":"traceutil/trace.go:171","msg":"trace[1713699683] linearizableReadLoop","detail":"{readStateIndex:4180; appliedIndex:4178; }","duration":"164.657739ms","start":"2026-10-17T09:37:21.765886Z","end":"2026-10-17T09:37:21.930544Z","steps":["trace[1713699683] 'read index received' (duration: 107.440435ms)","trace[1713699683] 'applied index is now lower than readState.Index' (duration: 57.216364ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T09:37:21.930728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.809493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T09:37:21.930698Z","caller":"traceutil/trace.go:171","msg":"trace[768668108] transaction","detail":"{read_only:false; response_revision:4027; number_of_response:1; }","duration":"188.968266ms","start":"2026-10-17T09:37:21.741675Z","end":"2026-10-17T09:37:21.930643Z","steps":["trace[768668108] 'process raft request' (duration: 131.69662ms)","trace[768668108] 'compare' (duration: 56.98992ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T09:37:21.930773Z","caller":"traceutil/trace.go:171","msg":"trace[1817735479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4028; }","duration":"164.919176ms","start":"2026-10-17T09:37:21.765841Z","end":"2026-10-17T09:37:21.93076Z","steps":["trace[1817735479] 'agreement among raft nodes before linearized reading' (duration: 164.819953ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:37:21.93088Z","caller":"traceutil/trace.go:171","msg":"trace[1643154674] transaction","detail":"{read_only:false; response_revision:4028; number_of_response:1; }","duration":"170.203282ms","start":"2026-10-17T09:37:21.760636Z","end":"2026-10-17T09:37:21.93084Z","steps":["trace[1643154674] 'process raft request' (duration: 169.866774ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:38:48.199621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.951121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57bc68cf-4c51-4c5e-801a-f1610e6cb574\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-17T09:38:48.199716Z","caller":"traceutil/trace.go:171","msg":"trace[1175393882] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57bc68cf-4c51-4c5e-801a-f1610e6cb574; range_end:; response_count:1; response_revision:5223; }","duration":"147.096735ms","start":"2026-10-17T09:38:48.052599Z","end":"2026-10-17T09:38:48.199696Z","steps":["trace[1175393882] 'range keys from in-memory index tree' (duration: 146.786268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:38:48.537794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.274667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57bc68cf-4c51-4c5e-801a-f1610e6cb574\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-17T09:38:48.537907Z","caller":"traceutil/trace.go:171","msg":"trace[992124340] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57bc68cf-4c51-4c5e-801a-f1610e6cb574; range_end:; response_count:1; response_revision:5227; }","duration":"109.458002ms","start":"2026-10-17T09:38:48.428427Z","end":"2026-10-17T09:38:48.537885Z","steps":["trace[992124340] 'range keys from in-memory index tree' (duration: 109.072913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:39:02.137751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.098066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-17T09:39:02.138085Z","caller":"traceutil/trace.go:171","msg":"trace[1663345212] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"289.882082ms","start":"2026-10-17T09:39:01.848185Z","end":"2026-10-17T09:39:02.138067Z","steps":["trace[1663345212] 'process raft request' (duration: 23.396938ms)","trace[1663345212] 'compare' (duration: 265.722458ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T09:39:33.902631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2945} {"level":"info","ts":"2026-10-17T09:39:34.004139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2945,"took":"100.086354ms","hash":1952126165,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":32186368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T09:39:34.004202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1952126165,"revision":2945,"compact-revision":-1} {"level":"info","ts":"2026-10-17T09:44:33.909428Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5869} {"level":"info","ts":"2026-10-17T09:44:33.998039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5869,"took":"85.958949ms","hash":1903953903,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":26562560,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T09:44:33.998106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1903953903,"revision":5869,"compact-revision":2945} {"level":"warn","ts":"2026-10-17T09:45:29.504148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.982195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-17T09:45:29.504264Z","caller":"traceutil/trace.go:171","msg":"trace[1071872058] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8531; }","duration":"295.157569ms","start":"2026-10-17T09:45:29.209082Z","end":"2026-10-17T09:45:29.50424Z","steps":["trace[1071872058] 'count revisions from in-memory index tree' (duration: 294.891463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T09:45:29.504865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.677676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dmxh8\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-17T09:45:29.504923Z","caller":"traceutil/trace.go:171","msg":"trace[1850478255] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dmxh8; range_end:; response_count:1; response_revision:8531; }","duration":"147.769739ms","start":"2026-10-17T09:45:29.357137Z","end":"2026-10-17T09:45:29.504907Z","steps":["trace[1850478255] 'range keys from in-memory index tree' (duration: 146.805808ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:45:29.585966Z","caller":"traceutil/trace.go:171","msg":"trace[1576879155] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"127.484002ms","start":"2026-10-17T09:45:29.458425Z","end":"2026-10-17T09:45:29.585909Z","steps":["trace[1576879155] 'process raft request' (duration: 127.327239ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:45:40.830711Z","caller":"traceutil/trace.go:171","msg":"trace[1854862315] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"150.962909ms","start":"2026-10-17T09:45:40.679718Z","end":"2026-10-17T09:45:40.830681Z","steps":["trace[1854862315] 'process raft request' (duration: 150.808395ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:46:11.142833Z","caller":"traceutil/trace.go:171","msg":"trace[549481904] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"167.637475ms","start":"2026-10-17T09:46:10.975176Z","end":"2026-10-17T09:46:11.142813Z","steps":["trace[549481904] 'process raft request' (duration: 167.499622ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:46:22.003792Z","caller":"traceutil/trace.go:171","msg":"trace[1132060876] transaction","detail":"{read_only:false; response_revision:8867; number_of_response:1; }","duration":"108.832082ms","start":"2026-10-17T09:46:21.894934Z","end":"2026-10-17T09:46:22.003766Z","steps":["trace[1132060876] 'process raft request' (duration: 108.668938ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:46:22.387584Z","caller":"traceutil/trace.go:171","msg":"trace[78508874] transaction","detail":"{read_only:false; response_revision:8871; number_of_response:1; }","duration":"181.648223ms","start":"2026-10-17T09:46:22.205763Z","end":"2026-10-17T09:46:22.387411Z","steps":["trace[78508874] 'process raft request' (duration: 121.008151ms)","trace[78508874] 'compare' (duration: 60.472809ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T09:46:28.3365Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243f.wal"} {"level":"info","ts":"2026-10-17T09:47:38.398846Z","caller":"traceutil/trace.go:171","msg":"trace[1263659007] transaction","detail":"{read_only:false; response_revision:9650; number_of_response:1; }","duration":"102.895104ms","start":"2026-10-17T09:47:38.295929Z","end":"2026-10-17T09:47:38.398824Z","steps":["trace[1263659007] 'process raft request' (duration: 102.74208ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:47:38.662864Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9d6ea9653fb28c79","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T09:47:38.6696Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-17T09:47:38.669715Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-17T09:49:33.915853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8040} {"level":"info","ts":"2026-10-17T09:49:33.984548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8040,"took":"66.450547ms","hash":3501772214,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":28184576,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-17T09:49:33.984618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3501772214,"revision":8040,"compact-revision":5869} {"level":"info","ts":"2026-10-17T09:53:31.129324Z","caller":"traceutil/trace.go:171","msg":"trace[1051051861] transaction","detail":"{read_only:false; response_revision:12161; number_of_response:1; }","duration":"175.56058ms","start":"2026-10-17T09:53:30.953724Z","end":"2026-10-17T09:53:31.129285Z","steps":["trace[1051051861] 'process raft request' (duration: 175.350625ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T09:53:31.190135Z","caller":"traceutil/trace.go:171","msg":"trace[2025785712] transaction","detail":"{read_only:false; response_revision:12162; number_of_response:1; }","duration":"234.844831ms","start":"2026-10-17T09:53:30.955201Z","end":"2026-10-17T09:53:31.190046Z","steps":["trace[2025785712] 'process raft request' (duration: 191.511944ms)","trace[2025785712] 'compare' (duration: 42.768755ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T09:54:33.927689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10431} {"level":"info","ts":"2026-10-17T09:54:34.015249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10431,"took":"85.745246ms","hash":2531833539,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":26099712,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-17T09:54:34.015325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2531833539,"revision":10431,"compact-revision":8040} {"level":"info","ts":"2026-10-17T09:59:33.935749Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12600} {"level":"info","ts":"2026-10-17T09:59:34.012312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12600,"took":"74.284391ms","hash":2419388237,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":27176960,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-17T09:59:34.012381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2419388237,"revision":12600,"compact-revision":10431} {"level":"info","ts":"2026-10-17T10:00:00.535434Z","caller":"traceutil/trace.go:171","msg":"trace[1008272094] transaction","detail":"{read_only:false; response_revision:14761; number_of_response:1; }","duration":"121.987696ms","start":"2026-10-17T10:00:00.413409Z","end":"2026-10-17T10:00:00.535397Z","steps":["trace[1008272094] 'process raft request' (duration: 121.838003ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:00:17.450697Z","caller":"traceutil/trace.go:171","msg":"trace[1983631419] transaction","detail":"{read_only:false; response_revision:14859; number_of_response:1; }","duration":"299.550179ms","start":"2026-10-17T10:00:17.151126Z","end":"2026-10-17T10:00:17.450676Z","steps":["trace[1983631419] 'process raft request' (duration: 299.400046ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:00:17.463368Z","caller":"traceutil/trace.go:171","msg":"trace[1213275611] transaction","detail":"{read_only:false; response_revision:14860; number_of_response:1; }","duration":"311.652957ms","start":"2026-10-17T10:00:17.151699Z","end":"2026-10-17T10:00:17.463352Z","steps":["trace[1213275611] 'process raft request' (duration: 311.522404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:00:17.463515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:00:17.151677Z","time spent":"311.737959ms","remote":"127.0.0.1:45382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:00:17.463713Z","caller":"traceutil/trace.go:171","msg":"trace[2051841780] linearizableReadLoop","detail":"{readStateIndex:15387; appliedIndex:15383; }","duration":"243.888249ms","start":"2026-10-17T10:00:17.219814Z","end":"2026-10-17T10:00:17.463702Z","steps":["trace[2051841780] 'read index received' (duration: 230.768329ms)","trace[2051841780] 'applied index is now lower than readState.Index' (duration: 13.11887ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:00:17.463731Z","caller":"traceutil/trace.go:171","msg":"trace[1874391419] transaction","detail":"{read_only:false; response_revision:14861; number_of_response:1; }","duration":"311.662856ms","start":"2026-10-17T10:00:17.15202Z","end":"2026-10-17T10:00:17.463682Z","steps":["trace[1874391419] 'process raft request' (duration: 311.291828ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:00:17.463719Z","caller":"traceutil/trace.go:171","msg":"trace[2044458475] transaction","detail":"{read_only:false; response_revision:14862; number_of_response:1; }","duration":"259.81291ms","start":"2026-10-17T10:00:17.203868Z","end":"2026-10-17T10:00:17.463681Z","steps":["trace[2044458475] 'process raft request' (duration: 259.679398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:00:17.463936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:00:17.151991Z","time spent":"311.83886ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:00:17.463994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.181587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-17T10:00:17.464057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.238766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.17\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-17T10:00:17.464065Z","caller":"traceutil/trace.go:171","msg":"trace[2031286301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14863; }","duration":"130.300399ms","start":"2026-10-17T10:00:17.333746Z","end":"2026-10-17T10:00:17.464047Z","steps":["trace[2031286301] 'agreement among raft nodes before linearized reading' (duration: 130.095925ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:00:17.464087Z","caller":"traceutil/trace.go:171","msg":"trace[1036412874] range","detail":"{range_begin:/registry/masterleases/162.253.55.17; range_end:; response_count:1; response_revision:14863; }","duration":"244.314178ms","start":"2026-10-17T10:00:17.219764Z","end":"2026-10-17T10:00:17.464078Z","steps":["trace[1036412874] 'agreement among raft nodes before linearized reading' (duration: 244.199636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:00:17.464354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.39836ms","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-17T10:00:17.464468Z","caller":"traceutil/trace.go:171","msg":"trace[1177603320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14863; }","duration":"130.548034ms","start":"2026-10-17T10:00:17.333878Z","end":"2026-10-17T10:00:17.464426Z","steps":["trace[1177603320] 'agreement among raft nodes before linearized reading' (duration: 130.252287ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:00:17.464597Z","caller":"traceutil/trace.go:171","msg":"trace[2084760333] transaction","detail":"{read_only:false; response_revision:14863; number_of_response:1; }","duration":"112.004875ms","start":"2026-10-17T10:00:17.352532Z","end":"2026-10-17T10:00:17.464537Z","steps":["trace[2084760333] 'process raft request' (duration: 111.110576ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:00:27.428701Z","caller":"traceutil/trace.go:171","msg":"trace[28828278] linearizableReadLoop","detail":"{readStateIndex:15554; appliedIndex:15553; }","duration":"130.36071ms","start":"2026-10-17T10:00:27.298304Z","end":"2026-10-17T10:00:27.428664Z","steps":["trace[28828278] 'read index received' (duration: 130.128935ms)","trace[28828278] 'applied index is now lower than readState.Index' (duration: 229.905µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:00:27.429006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.682347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11d21e9b-41db-42a9-a8f2-1d1afa8235e8\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-10-17T10:00:27.429091Z","caller":"traceutil/trace.go:171","msg":"trace[1117653752] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11d21e9b-41db-42a9-a8f2-1d1afa8235e8; range_end:; response_count:1; response_revision:15025; }","duration":"130.829621ms","start":"2026-10-17T10:00:27.298239Z","end":"2026-10-17T10:00:27.429069Z","steps":["trace[1117653752] 'agreement among raft nodes before linearized reading' (duration: 130.554365ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:01:54.168794Z","caller":"traceutil/trace.go:171","msg":"trace[752549801] transaction","detail":"{read_only:false; response_revision:16049; number_of_response:1; }","duration":"330.571672ms","start":"2026-10-17T10:01:53.838202Z","end":"2026-10-17T10:01:54.168773Z","steps":["trace[752549801] 'process raft request' (duration: 330.43552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:01:54.168935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:01:53.838186Z","time spent":"330.677996ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:01:54.190072Z","caller":"traceutil/trace.go:171","msg":"trace[1499387896] transaction","detail":"{read_only:false; response_revision:16050; number_of_response:1; }","duration":"343.610671ms","start":"2026-10-17T10:01:53.846444Z","end":"2026-10-17T10:01:54.190054Z","steps":["trace[1499387896] 'process raft request' (duration: 343.473418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:01:54.190164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:01:53.846424Z","time spent":"343.693634ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:01:54.190326Z","caller":"traceutil/trace.go:171","msg":"trace[1727892787] linearizableReadLoop","detail":"{readStateIndex:16611; appliedIndex:16607; }","duration":"265.423715ms","start":"2026-10-17T10:01:53.924879Z","end":"2026-10-17T10:01:54.190302Z","steps":["trace[1727892787] 'read index received' (duration: 243.807767ms)","trace[1727892787] 'applied index is now lower than readState.Index' (duration: 21.614937ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:01:54.190518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.827955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-17T10:01:54.190565Z","caller":"traceutil/trace.go:171","msg":"trace[1124291129] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:16052; }","duration":"233.932138ms","start":"2026-10-17T10:01:53.956619Z","end":"2026-10-17T10:01:54.190551Z","steps":["trace[1124291129] 'agreement among raft nodes before linearized reading' (duration: 233.821496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:01:54.190631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.384001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-17T10:01:54.190683Z","caller":"traceutil/trace.go:171","msg":"trace[1386589345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16052; }","duration":"164.477353ms","start":"2026-10-17T10:01:54.026193Z","end":"2026-10-17T10:01:54.190671Z","steps":["trace[1386589345] 'agreement among raft nodes before linearized reading' (duration: 164.352411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:01:54.190665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.743341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dmxh8\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-10-17T10:01:54.19077Z","caller":"traceutil/trace.go:171","msg":"trace[110171178] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dmxh8; range_end:; response_count:1; response_revision:16052; }","duration":"265.897114ms","start":"2026-10-17T10:01:53.924845Z","end":"2026-10-17T10:01:54.190742Z","steps":["trace[110171178] 'agreement among raft nodes before linearized reading' (duration: 265.576767ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:01:54.190827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.430583ms","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-17T10:01:54.190883Z","caller":"traceutil/trace.go:171","msg":"trace[1706145410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16052; }","duration":"164.501304ms","start":"2026-10-17T10:01:54.026365Z","end":"2026-10-17T10:01:54.190867Z","steps":["trace[1706145410] 'agreement among raft nodes before linearized reading' (duration: 164.382971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:01:54.190843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.730563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-17T10:01:54.190921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.356592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-17T10:01:54.190941Z","caller":"traceutil/trace.go:171","msg":"trace[1751235503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16052; }","duration":"204.845005ms","start":"2026-10-17T10:01:53.986085Z","end":"2026-10-17T10:01:54.19093Z","steps":["trace[1751235503] 'agreement among raft nodes before linearized reading' (duration: 204.663671ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:01:54.190949Z","caller":"traceutil/trace.go:171","msg":"trace[1988491272] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16052; }","duration":"217.407402ms","start":"2026-10-17T10:01:53.973531Z","end":"2026-10-17T10:01:54.190938Z","steps":["trace[1988491272] 'agreement among raft nodes before linearized reading' (duration: 217.330621ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:01:54.19035Z","caller":"traceutil/trace.go:171","msg":"trace[1060975563] transaction","detail":"{read_only:false; response_revision:16051; number_of_response:1; }","duration":"342.310702ms","start":"2026-10-17T10:01:53.848021Z","end":"2026-10-17T10:01:54.190332Z","steps":["trace[1060975563] 'process raft request' (duration: 341.977725ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:01:54.191035Z","caller":"traceutil/trace.go:171","msg":"trace[563518965] transaction","detail":"{read_only:false; response_revision:16052; number_of_response:1; }","duration":"269.306349ms","start":"2026-10-17T10:01:53.921713Z","end":"2026-10-17T10:01:54.191019Z","steps":["trace[563518965] 'process raft request' (duration: 268.525622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:01:54.191222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:01:53.848004Z","time spent":"343.022547ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:04:33.943297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14618} {"level":"info","ts":"2026-10-17T10:04:34.029298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14618,"took":"81.524642ms","hash":4228046314,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":31911936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T10:04:34.029373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4228046314,"revision":14618,"compact-revision":12600} {"level":"info","ts":"2026-10-17T10:07:44.838218Z","caller":"traceutil/trace.go:171","msg":"trace[999091078] transaction","detail":"{read_only:false; response_revision:18636; number_of_response:1; }","duration":"116.150067ms","start":"2026-10-17T10:07:44.722036Z","end":"2026-10-17T10:07:44.838186Z","steps":["trace[999091078] 'process raft request' (duration: 115.988104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:07:52.531565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.891311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T10:07:52.532106Z","caller":"traceutil/trace.go:171","msg":"trace[283023988] transaction","detail":"{read_only:false; response_revision:18710; number_of_response:1; }","duration":"352.237327ms","start":"2026-10-17T10:07:52.179815Z","end":"2026-10-17T10:07:52.532052Z","steps":["trace[283023988] 'process raft request' (duration: 351.88202ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:07:52.532174Z","caller":"traceutil/trace.go:171","msg":"trace[536748203] transaction","detail":"{read_only:false; response_revision:18709; number_of_response:1; }","duration":"353.554006ms","start":"2026-10-17T10:07:52.178585Z","end":"2026-10-17T10:07:52.532139Z","steps":["trace[536748203] 'process raft request' (duration: 93.916868ms)","trace[536748203] 'compare' (duration: 258.622625ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:07:52.532184Z","caller":"traceutil/trace.go:171","msg":"trace[1661078318] transaction","detail":"{read_only:false; response_revision:18711; number_of_response:1; }","duration":"349.13201ms","start":"2026-10-17T10:07:52.183016Z","end":"2026-10-17T10:07:52.532148Z","steps":["trace[1661078318] 'process raft request' (duration: 348.796342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:07:52.532337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:07:52.178564Z","time spent":"353.69028ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:07:52.532384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:07:52.183002Z","time spent":"349.270272ms","remote":"127.0.0.1:33658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:07:52.532486Z","caller":"traceutil/trace.go:171","msg":"trace[1741988640] linearizableReadLoop","detail":"{readStateIndex:19357; appliedIndex:19354; }","duration":"220.404095ms","start":"2026-10-17T10:07:52.312069Z","end":"2026-10-17T10:07:52.532473Z","steps":["trace[1741988640] 'read index received' (duration: 59.701µs)","trace[1741988640] 'applied index is now lower than readState.Index' (duration: 220.342304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:07:52.532619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:07:52.179773Z","time spent":"352.458822ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:07:52.660193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.263864ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-17T10:07:52.660306Z","caller":"traceutil/trace.go:171","msg":"trace[1517218299] linearizableReadLoop","detail":"{readStateIndex:19358; appliedIndex:19357; }","duration":"127.772865ms","start":"2026-10-17T10:07:52.532513Z","end":"2026-10-17T10:07:52.660286Z","steps":["trace[1517218299] 'read index received' (duration: 98.673µs)","trace[1517218299] 'applied index is now lower than readState.Index' (duration: 127.670292ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:07:52.660367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.274871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T10:07:52.660455Z","caller":"traceutil/trace.go:171","msg":"trace[243886869] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:18711; }","duration":"348.426924ms","start":"2026-10-17T10:07:52.312005Z","end":"2026-10-17T10:07:52.660432Z","steps":["trace[243886869] 'agreement among raft nodes before linearized reading' (duration: 220.588378ms)","trace[243886869] 'count revisions from in-memory index tree' (duration: 127.706623ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:07:52.660517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:07:52.311984Z","time spent":"348.522287ms","remote":"127.0.0.1:59614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-10-17T10:07:52.66061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.585054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-17T10:07:52.660696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.158179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-zbg9x\" ","response":"range_response_count:1 size:20736"} {"level":"info","ts":"2026-10-17T10:07:52.660703Z","caller":"traceutil/trace.go:171","msg":"trace[389762770] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:18711; }","duration":"272.722937ms","start":"2026-10-17T10:07:52.38796Z","end":"2026-10-17T10:07:52.660683Z","steps":["trace[389762770] 'agreement among raft nodes before linearized reading' (duration: 272.539083ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:07:52.660739Z","caller":"traceutil/trace.go:171","msg":"trace[874406835] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-zbg9x; range_end:; response_count:1; response_revision:18711; }","duration":"284.278291ms","start":"2026-10-17T10:07:52.376449Z","end":"2026-10-17T10:07:52.660727Z","steps":["trace[874406835] 'agreement among raft nodes before linearized reading' (duration: 284.103438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:07:52.660851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.88301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-heat-default-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T10:07:52.660969Z","caller":"traceutil/trace.go:171","msg":"trace[407435338] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-heat-default-user; range_end:; response_count:0; response_revision:18711; }","duration":"124.066483ms","start":"2026-10-17T10:07:52.536874Z","end":"2026-10-17T10:07:52.660941Z","steps":["trace[407435338] 'agreement among raft nodes before linearized reading' (duration: 123.820458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:07:52.6609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.852782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T10:07:52.661089Z","caller":"traceutil/trace.go:171","msg":"trace[913749931] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18711; }","duration":"314.058177ms","start":"2026-10-17T10:07:52.347018Z","end":"2026-10-17T10:07:52.661076Z","steps":["trace[913749931] 'agreement among raft nodes before linearized reading' (duration: 313.845442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:07:52.661135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:07:52.347007Z","time spent":"314.114898ms","remote":"127.0.0.1:45708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-17T10:07:52.808681Z","caller":"traceutil/trace.go:171","msg":"trace[1163026400] linearizableReadLoop","detail":"{readStateIndex:19360; appliedIndex:19359; }","duration":"107.227641ms","start":"2026-10-17T10:07:52.701408Z","end":"2026-10-17T10:07:52.808636Z","steps":["trace[1163026400] 'read index received' (duration: 96.441874ms)","trace[1163026400] 'applied index is now lower than readState.Index' (duration: 10.783637ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:07:52.808834Z","caller":"traceutil/trace.go:171","msg":"trace[28579925] transaction","detail":"{read_only:false; response_revision:18713; number_of_response:1; }","duration":"123.520501ms","start":"2026-10-17T10:07:52.6853Z","end":"2026-10-17T10:07:52.80882Z","steps":["trace[28579925] 'process raft request' (duration: 112.534459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:07:52.809309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.891077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19587"} {"level":"info","ts":"2026-10-17T10:07:52.809355Z","caller":"traceutil/trace.go:171","msg":"trace[1918442799] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18713; }","duration":"107.997619ms","start":"2026-10-17T10:07:52.701344Z","end":"2026-10-17T10:07:52.809342Z","steps":["trace[1918442799] 'agreement among raft nodes before linearized reading' (duration: 107.787075ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:07:52.82858Z","caller":"traceutil/trace.go:171","msg":"trace[429989358] transaction","detail":"{read_only:false; response_revision:18715; number_of_response:1; }","duration":"121.287851ms","start":"2026-10-17T10:07:52.707264Z","end":"2026-10-17T10:07:52.828552Z","steps":["trace[429989358] 'process raft request' (duration: 121.068265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:07:52.828745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.824361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5sw88\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-10-17T10:07:52.828824Z","caller":"traceutil/trace.go:171","msg":"trace[1832252574] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5sw88; range_end:; response_count:1; response_revision:18716; }","duration":"105.949614ms","start":"2026-10-17T10:07:52.722856Z","end":"2026-10-17T10:07:52.828806Z","steps":["trace[1832252574] 'agreement among raft nodes before linearized reading' (duration: 105.729309ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:07:52.828998Z","caller":"traceutil/trace.go:171","msg":"trace[74890686] transaction","detail":"{read_only:false; response_revision:18716; number_of_response:1; }","duration":"121.18437ms","start":"2026-10-17T10:07:52.707801Z","end":"2026-10-17T10:07:52.828985Z","steps":["trace[74890686] 'process raft request' (duration: 120.610007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:07:52.828977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.849884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122881"} {"level":"info","ts":"2026-10-17T10:07:52.829067Z","caller":"traceutil/trace.go:171","msg":"trace[1255666736] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:18716; }","duration":"122.016267ms","start":"2026-10-17T10:07:52.707029Z","end":"2026-10-17T10:07:52.829045Z","steps":["trace[1255666736] 'agreement among raft nodes before linearized reading' (duration: 121.69608ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:07:52.829305Z","caller":"traceutil/trace.go:171","msg":"trace[1246184630] transaction","detail":"{read_only:false; response_revision:18714; number_of_response:1; }","duration":"122.065278ms","start":"2026-10-17T10:07:52.707218Z","end":"2026-10-17T10:07:52.829283Z","steps":["trace[1246184630] 'process raft request' (duration: 120.955603ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:08:42.441907Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9d6ea9653fb28c79","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T10:08:42.447475Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-17T10:08:42.447562Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-17T10:09:33.950637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17116} {"level":"info","ts":"2026-10-17T10:09:34.054946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17116,"took":"101.337262ms","hash":2573664049,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":34570240,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-17T10:09:34.05501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2573664049,"revision":17116,"compact-revision":14618} {"level":"info","ts":"2026-10-17T10:13:42.122137Z","caller":"traceutil/trace.go:171","msg":"trace[703188424] transaction","detail":"{read_only:false; response_revision:21396; number_of_response:1; }","duration":"121.901234ms","start":"2026-10-17T10:13:42.000204Z","end":"2026-10-17T10:13:42.122105Z","steps":["trace[703188424] 'process raft request' (duration: 121.746341ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:13:42.122488Z","caller":"traceutil/trace.go:171","msg":"trace[1396633266] linearizableReadLoop","detail":"{readStateIndex:22129; appliedIndex:22129; }","duration":"119.562453ms","start":"2026-10-17T10:13:42.002889Z","end":"2026-10-17T10:13:42.122452Z","steps":["trace[1396633266] 'read index received' (duration: 119.551263ms)","trace[1396633266] 'applied index is now lower than readState.Index' (duration: 8.98µs)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:13:42.122774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.854239ms","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-17T10:13:42.122807Z","caller":"traceutil/trace.go:171","msg":"trace[137029601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21396; }","duration":"119.951961ms","start":"2026-10-17T10:13:42.002843Z","end":"2026-10-17T10:13:42.122795Z","steps":["trace[137029601] 'agreement among raft nodes before linearized reading' (duration: 119.744807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:13:42.407211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.279817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T10:13:42.407334Z","caller":"traceutil/trace.go:171","msg":"trace[1418785104] transaction","detail":"{read_only:false; response_revision:21397; number_of_response:1; }","duration":"279.952826ms","start":"2026-10-17T10:13:42.127357Z","end":"2026-10-17T10:13:42.407309Z","steps":["trace[1418785104] 'process raft request' (duration: 130.489084ms)","trace[1418785104] 'compare' (duration: 149.135934ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:13:58.779148Z","caller":"traceutil/trace.go:171","msg":"trace[1345901556] transaction","detail":"{read_only:false; response_revision:21480; number_of_response:1; }","duration":"190.685039ms","start":"2026-10-17T10:13:58.588431Z","end":"2026-10-17T10:13:58.779116Z","steps":["trace[1345901556] 'process raft request' (duration: 141.234491ms)","trace[1345901556] 'compare' (duration: 49.300775ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:13:58.851589Z","caller":"traceutil/trace.go:171","msg":"trace[791865044] linearizableReadLoop","detail":"{readStateIndex:22218; appliedIndex:22216; }","duration":"220.425025ms","start":"2026-10-17T10:13:58.631117Z","end":"2026-10-17T10:13:58.851542Z","steps":["trace[791865044] 'read index received' (duration: 98.552062ms)","trace[791865044] 'applied index is now lower than readState.Index' (duration: 121.872083ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:13:58.851694Z","caller":"traceutil/trace.go:171","msg":"trace[904923792] transaction","detail":"{read_only:false; response_revision:21481; number_of_response:1; }","duration":"262.45036ms","start":"2026-10-17T10:13:58.589204Z","end":"2026-10-17T10:13:58.851655Z","steps":["trace[904923792] 'process raft request' (duration: 262.218895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:13:58.851859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.637719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T10:13:58.851943Z","caller":"traceutil/trace.go:171","msg":"trace[111839574] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:21481; }","duration":"220.908235ms","start":"2026-10-17T10:13:58.631013Z","end":"2026-10-17T10:13:58.851921Z","steps":["trace[111839574] 'agreement among raft nodes before linearized reading' (duration: 220.66026ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:13:59.151433Z","caller":"traceutil/trace.go:171","msg":"trace[879667921] transaction","detail":"{read_only:false; response_revision:21482; number_of_response:1; }","duration":"105.714808ms","start":"2026-10-17T10:13:59.045696Z","end":"2026-10-17T10:13:59.151411Z","steps":["trace[879667921] 'process raft request' (duration: 105.580715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:13:59.450365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.948305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-17T10:13:59.450574Z","caller":"traceutil/trace.go:171","msg":"trace[2128297735] linearizableReadLoop","detail":"{readStateIndex:22220; appliedIndex:22218; }","duration":"393.871324ms","start":"2026-10-17T10:13:59.056667Z","end":"2026-10-17T10:13:59.450538Z","steps":["trace[2128297735] 'read index received' (duration: 94.611243ms)","trace[2128297735] 'applied index is now lower than readState.Index' (duration: 299.258731ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:13:59.450682Z","caller":"traceutil/trace.go:171","msg":"trace[885726125] transaction","detail":"{read_only:false; response_revision:21484; number_of_response:1; }","duration":"390.774097ms","start":"2026-10-17T10:13:59.059866Z","end":"2026-10-17T10:13:59.45064Z","steps":["trace[885726125] 'process raft request' (duration: 390.582402ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:13:59.450691Z","caller":"traceutil/trace.go:171","msg":"trace[1129175210] transaction","detail":"{read_only:false; response_revision:21485; number_of_response:1; }","duration":"388.997967ms","start":"2026-10-17T10:13:59.061663Z","end":"2026-10-17T10:13:59.450661Z","steps":["trace[1129175210] 'process raft request' (duration: 388.836584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:13:59.450752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.08096ms","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-17T10:13:59.450747Z","caller":"traceutil/trace.go:171","msg":"trace[1620708593] transaction","detail":"{read_only:false; response_revision:21483; number_of_response:1; }","duration":"395.057041ms","start":"2026-10-17T10:13:59.055641Z","end":"2026-10-17T10:13:59.450698Z","steps":["trace[1620708593] 'process raft request' (duration: 223.691357ms)","trace[1620708593] 'compare' (duration: 170.822772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:13:59.450788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:13:59.059846Z","time spent":"390.89364ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:13:59.450801Z","caller":"traceutil/trace.go:171","msg":"trace[865434896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21485; }","duration":"394.162891ms","start":"2026-10-17T10:13:59.056625Z","end":"2026-10-17T10:13:59.450788Z","steps":["trace[865434896] 'agreement among raft nodes before linearized reading' (duration: 393.984927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:13:59.450832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:13:59.056605Z","time spent":"394.220183ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-17T10:13:59.450904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:13:59.055633Z","time spent":"395.186584ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-17T10:13:59.45109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-17T10:13:59.061649Z","time spent":"389.158141ms","remote":"127.0.0.1:45490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-17T10:14:33.965371Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19742} {"level":"info","ts":"2026-10-17T10:14:34.073013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19742,"took":"105.418982ms","hash":1463924752,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":29999104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T10:14:34.073066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1463924752,"revision":19742,"compact-revision":17116} {"level":"info","ts":"2026-10-17T10:15:03.959336Z","caller":"traceutil/trace.go:171","msg":"trace[583669919] transaction","detail":"{read_only:false; response_revision:22101; number_of_response:1; }","duration":"253.907344ms","start":"2026-10-17T10:15:03.705404Z","end":"2026-10-17T10:15:03.959311Z","steps":["trace[583669919] 'process raft request' (duration: 253.73568ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:15:03.999519Z","caller":"traceutil/trace.go:171","msg":"trace[918456615] linearizableReadLoop","detail":"{readStateIndex:22865; appliedIndex:22862; }","duration":"159.616857ms","start":"2026-10-17T10:15:03.839878Z","end":"2026-10-17T10:15:03.999495Z","steps":["trace[918456615] 'read index received' (duration: 119.309508ms)","trace[918456615] 'applied index is now lower than readState.Index' (duration: 40.302899ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:15:03.999552Z","caller":"traceutil/trace.go:171","msg":"trace[1550075544] transaction","detail":"{read_only:false; response_revision:22102; number_of_response:1; }","duration":"294.007919ms","start":"2026-10-17T10:15:03.705502Z","end":"2026-10-17T10:15:03.99951Z","steps":["trace[1550075544] 'process raft request' (duration: 293.815444ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:15:03.999674Z","caller":"traceutil/trace.go:171","msg":"trace[263321351] transaction","detail":"{read_only:false; response_revision:22103; number_of_response:1; }","duration":"291.969403ms","start":"2026-10-17T10:15:03.707618Z","end":"2026-10-17T10:15:03.999588Z","steps":["trace[263321351] 'process raft request' (duration: 291.83428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:15:03.999772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.879903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-17T10:15:03.999813Z","caller":"traceutil/trace.go:171","msg":"trace[520517493] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:22103; }","duration":"159.976725ms","start":"2026-10-17T10:15:03.839823Z","end":"2026-10-17T10:15:03.9998Z","steps":["trace[520517493] 'agreement among raft nodes before linearized reading' (duration: 159.815251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:15:03.999841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.7612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-17T10:15:03.99991Z","caller":"traceutil/trace.go:171","msg":"trace[1049224206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22103; }","duration":"115.858113ms","start":"2026-10-17T10:15:03.884032Z","end":"2026-10-17T10:15:03.99989Z","steps":["trace[1049224206] 'agreement among raft nodes before linearized reading' (duration: 115.665789ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:15:04.143427Z","caller":"traceutil/trace.go:171","msg":"trace[975161769] linearizableReadLoop","detail":"{readStateIndex:22866; appliedIndex:22865; }","duration":"118.43535ms","start":"2026-10-17T10:15:04.024962Z","end":"2026-10-17T10:15:04.143397Z","steps":["trace[975161769] 'read index received' (duration: 91.085597ms)","trace[975161769] 'applied index is now lower than readState.Index' (duration: 27.348052ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:15:04.143496Z","caller":"traceutil/trace.go:171","msg":"trace[1064564158] transaction","detail":"{read_only:false; response_revision:22104; number_of_response:1; }","duration":"139.851751ms","start":"2026-10-17T10:15:04.00361Z","end":"2026-10-17T10:15:04.143462Z","steps":["trace[1064564158] 'process raft request' (duration: 112.460027ms)","trace[1064564158] 'compare' (duration: 27.21242ms)"],"step_count":2} {"level":"warn","ts":"2026-10-17T10:15:04.143673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.622573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T10:15:04.143727Z","caller":"traceutil/trace.go:171","msg":"trace[1956709560] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:22104; }","duration":"118.688834ms","start":"2026-10-17T10:15:04.025026Z","end":"2026-10-17T10:15:04.143715Z","steps":["trace[1956709560] 'agreement among raft nodes before linearized reading' (duration: 118.599222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:15:04.143733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.759906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-10-17T10:15:04.14379Z","caller":"traceutil/trace.go:171","msg":"trace[804572505] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22104; }","duration":"118.860479ms","start":"2026-10-17T10:15:04.024915Z","end":"2026-10-17T10:15:04.143776Z","steps":["trace[804572505] 'agreement among raft nodes before linearized reading' (duration: 118.680515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:15:04.144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.664304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T10:15:04.144043Z","caller":"traceutil/trace.go:171","msg":"trace[2021221242] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:22104; }","duration":"118.719935ms","start":"2026-10-17T10:15:04.025311Z","end":"2026-10-17T10:15:04.144031Z","steps":["trace[2021221242] 'agreement among raft nodes before linearized reading' (duration: 118.664994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:15:04.144163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.636385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5138"} {"level":"warn","ts":"2026-10-17T10:15:04.144192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.888539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T10:15:04.144221Z","caller":"traceutil/trace.go:171","msg":"trace[2135383482] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:22104; }","duration":"114.723257ms","start":"2026-10-17T10:15:04.029484Z","end":"2026-10-17T10:15:04.144207Z","steps":["trace[2135383482] 'agreement among raft nodes before linearized reading' (duration: 114.549003ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:15:04.144229Z","caller":"traceutil/trace.go:171","msg":"trace[2134896456] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:22104; }","duration":"118.943121ms","start":"2026-10-17T10:15:04.025275Z","end":"2026-10-17T10:15:04.144219Z","steps":["trace[2134896456] 'agreement among raft nodes before linearized reading' (duration: 118.887069ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:18:03.694519Z","caller":"traceutil/trace.go:171","msg":"trace[868607767] transaction","detail":"{read_only:false; response_revision:23990; number_of_response:1; }","duration":"104.656116ms","start":"2026-10-17T10:18:03.58982Z","end":"2026-10-17T10:18:03.694476Z","steps":["trace[868607767] 'process raft request' (duration: 104.41647ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:18:03.989097Z","caller":"traceutil/trace.go:171","msg":"trace[1439237912] transaction","detail":"{read_only:false; response_revision:23992; number_of_response:1; }","duration":"246.696196ms","start":"2026-10-17T10:18:03.742372Z","end":"2026-10-17T10:18:03.989068Z","steps":["trace[1439237912] 'process raft request' (duration: 201.912609ms)","trace[1439237912] 'compare' (duration: 44.606703ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:19:33.082136Z","caller":"traceutil/trace.go:171","msg":"trace[1752111957] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"118.745977ms","start":"2026-10-17T10:19:32.963359Z","end":"2026-10-17T10:19:33.082105Z","steps":["trace[1752111957] 'process raft request' (duration: 94.216616ms)","trace[1752111957] 'compare' (duration: 24.249545ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:19:33.972234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21881} {"level":"info","ts":"2026-10-17T10:19:34.081128Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21881,"took":"105.096195ms","hash":2921952160,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":43560960,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-17T10:19:34.081199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2921952160,"revision":21881,"compact-revision":19742} {"level":"info","ts":"2026-10-17T10:21:41.252472Z","caller":"traceutil/trace.go:171","msg":"trace[1077013336] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"172.816998ms","start":"2026-10-17T10:21:41.079631Z","end":"2026-10-17T10:21:41.252448Z","steps":["trace[1077013336] 'process raft request' (duration: 172.660744ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:21:41.340183Z","caller":"traceutil/trace.go:171","msg":"trace[1398342146] linearizableReadLoop","detail":"{readStateIndex:26977; appliedIndex:26975; }","duration":"155.532678ms","start":"2026-10-17T10:21:41.184626Z","end":"2026-10-17T10:21:41.340158Z","steps":["trace[1398342146] 'read index received' (duration: 67.735503ms)","trace[1398342146] 'applied index is now lower than readState.Index' (duration: 87.796025ms)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:21:41.340283Z","caller":"traceutil/trace.go:171","msg":"trace[458734680] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"202.054412ms","start":"2026-10-17T10:21:41.138198Z","end":"2026-10-17T10:21:41.340253Z","steps":["trace[458734680] 'process raft request' (duration: 201.816517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:21:41.340419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.777183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-17T10:21:41.340477Z","caller":"traceutil/trace.go:171","msg":"trace[533307763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26112; }","duration":"155.852974ms","start":"2026-10-17T10:21:41.18461Z","end":"2026-10-17T10:21:41.340463Z","steps":["trace[533307763] 'agreement among raft nodes before linearized reading' (duration: 155.65656ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:21:41.341381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.725873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-17T10:21:41.34144Z","caller":"traceutil/trace.go:171","msg":"trace[981070304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26113; }","duration":"156.821065ms","start":"2026-10-17T10:21:41.184603Z","end":"2026-10-17T10:21:41.341424Z","steps":["trace[981070304] 'agreement among raft nodes before linearized reading' (duration: 156.668652ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:21:49.276447Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000699e.wal"} {"level":"info","ts":"2026-10-17T10:22:44.705476Z","caller":"traceutil/trace.go:171","msg":"trace[1542785518] linearizableReadLoop","detail":"{readStateIndex:27558; appliedIndex:27557; }","duration":"179.290871ms","start":"2026-10-17T10:22:44.526158Z","end":"2026-10-17T10:22:44.705449Z","steps":["trace[1542785518] 'read index received' (duration: 179.100937ms)","trace[1542785518] 'applied index is now lower than readState.Index' (duration: 188.944µs)"],"step_count":2} {"level":"info","ts":"2026-10-17T10:22:44.70555Z","caller":"traceutil/trace.go:171","msg":"trace[381082897] transaction","detail":"{read_only:false; response_revision:26678; number_of_response:1; }","duration":"200.082478ms","start":"2026-10-17T10:22:44.505423Z","end":"2026-10-17T10:22:44.705506Z","steps":["trace[381082897] 'process raft request' (duration: 199.881564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-17T10:22:44.705624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.426234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-17T10:22:44.70566Z","caller":"traceutil/trace.go:171","msg":"trace[772696575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26678; }","duration":"179.522526ms","start":"2026-10-17T10:22:44.526126Z","end":"2026-10-17T10:22:44.705649Z","steps":["trace[772696575] 'agreement among raft nodes before linearized reading' (duration: 179.428173ms)"],"step_count":1} {"level":"info","ts":"2026-10-17T10:24:33.987273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24935} {"level":"info","ts":"2026-10-17T10:24:34.142624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24935,"took":"151.359167ms","hash":2221977220,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":36253696,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-17T10:24:34.142725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2221977220,"revision":24935,"compact-revision":21881} {"level":"info","ts":"2026-10-17T10:26:42.500728Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9d6ea9653fb28c79","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T10:26:42.509244Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-17T10:26:42.509338Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-17T10:29:34.003444Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27712} {"level":"info","ts":"2026-10-17T10:29:34.132684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27712,"took":"123.202496ms","hash":2294070760,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":36671488,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-17T10:29:34.132758Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2294070760,"revision":27712,"compact-revision":24935} {"level":"info","ts":"2026-10-17T10:34:34.012997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30358} {"level":"info","ts":"2026-10-17T10:34:34.176479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30358,"took":"159.129932ms","hash":1051252903,"current-db-size-bytes":57159680,"current-db-size":"57 MB","current-db-size-in-use-bytes":30674944,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T10:34:34.176556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1051252903,"revision":30358,"compact-revision":27712} {"level":"info","ts":"2026-10-17T10:39:34.020234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32607} {"level":"info","ts":"2026-10-17T10:39:34.171264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32607,"took":"147.836702ms","hash":651004535,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":30339072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-17T10:39:34.17133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":651004535,"revision":32607,"compact-revision":30358} {"level":"info","ts":"2026-10-17T10:44:34.031297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34839} {"level":"info","ts":"2026-10-17T10:44:34.157114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34839,"took":"121.750336ms","hash":2499405675,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31739904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T10:44:34.15718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2499405675,"revision":34839,"compact-revision":32607} {"level":"info","ts":"2026-10-17T10:48:09.500791Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9d6ea9653fb28c79","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-17T10:48:09.510706Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-17T10:48:09.510816Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-17T10:49:34.037527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37130} {"level":"info","ts":"2026-10-17T10:49:34.167049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37130,"took":"124.905315ms","hash":496840012,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-17T10:49:34.167146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":496840012,"revision":37130,"compact-revision":34839} {"level":"info","ts":"2026-10-17T10:54:34.04585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39408} {"level":"info","ts":"2026-10-17T10:54:34.177836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39408,"took":"127.733677ms","hash":2624078513,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-17T10:54:34.177915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2624078513,"revision":39408,"compact-revision":37130}