{"level":"warn","ts":"2026-10-15T14:17:17.061429Z","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-15T14:17:17.061522Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.193: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.193:2380","--initial-cluster=instance=https://162.253.55.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.193: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-15T14:17:17.06159Z","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-15T14:17:17.061601Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-10-15T14:17:17.061624Z","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-15T14:17:17.062373Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"]} {"level":"info","ts":"2026-10-15T14:17:17.062501Z","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.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.193: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-15T14:17:17.067175Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.416075ms"} {"level":"info","ts":"2026-10-15T14:17:17.074138Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6814e3e3d6b4d713","cluster-id":"e5e99c9118b1492a"} {"level":"info","ts":"2026-10-15T14:17:17.074227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:17:17.074266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 0"} {"level":"info","ts":"2026-10-15T14:17:17.074279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6814e3e3d6b4d713 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:17:17.074295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 1"} {"level":"info","ts":"2026-10-15T14:17:17.07433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"warn","ts":"2026-10-15T14:17:17.079779Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:17:17.081432Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:17:17.083796Z","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-15T14:17:17.086253Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6814e3e3d6b4d713","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:17:17.086473Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6814e3e3d6b4d713","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:17:17.086523Z","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-15T14:17:17.086626Z","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-15T14:17:17.086646Z","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-15T14:17:17.088662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"info","ts":"2026-10-15T14:17:17.088861Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","added-peer-id":"6814e3e3d6b4d713","added-peer-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-10-15T14:17:17.093136Z","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-15T14:17:17.093319Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6814e3e3d6b4d713","initial-advertise-peer-urls":["https://162.253.55.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:17:17.093348Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:17:17.093423Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-10-15T14:17:17.093437Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-10-15T14:17:17.275474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:17:17.275625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:17:17.275672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgPreVoteResp from 6814e3e3d6b4d713 at term 1"} {"level":"info","ts":"2026-10-15T14:17:17.275707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:17:17.275719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgVoteResp from 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-10-15T14:17:17.275737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became leader at term 2"} {"level":"info","ts":"2026-10-15T14:17:17.275752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6814e3e3d6b4d713 elected leader 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-10-15T14:17:17.277483Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6814e3e3d6b4d713","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.193:2379]}","request-path":"/0/members/6814e3e3d6b4d713/attributes","cluster-id":"e5e99c9118b1492a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:17:17.277518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:17:17.277562Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:17:17.277804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:17:17.277961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:17:17.27805Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:17:17.280263Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:17:17.280454Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:17:17.28051Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:17:17.281571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.193:2379"} {"level":"info","ts":"2026-10-15T14:17:17.282019Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:17:20.231736Z","caller":"traceutil/trace.go:171","msg":"trace[196689624] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"120.88315ms","start":"2026-10-15T14:17:20.110818Z","end":"2026-10-15T14:17:20.231701Z","steps":["trace[196689624] 'read index received' (duration: 116.810313ms)","trace[196689624] 'applied index is now lower than readState.Index' (duration: 4.071427ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:17:20.231921Z","caller":"traceutil/trace.go:171","msg":"trace[1331373574] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"121.824132ms","start":"2026-10-15T14:17:20.109997Z","end":"2026-10-15T14:17:20.231821Z","steps":["trace[1331373574] 'process raft request' (duration: 117.706484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:20.231984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.137376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-15T14:17:20.232095Z","caller":"traceutil/trace.go:171","msg":"trace[1977812782] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:73; }","duration":"121.29733ms","start":"2026-10-15T14:17:20.11078Z","end":"2026-10-15T14:17:20.232077Z","steps":["trace[1977812782] 'agreement among raft nodes before linearized reading' (duration: 121.089025ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.368662Z","caller":"traceutil/trace.go:171","msg":"trace[1552156059] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"224.020926ms","start":"2026-10-15T14:18:01.14462Z","end":"2026-10-15T14:18:01.368641Z","steps":["trace[1552156059] 'process raft request' (duration: 223.889273ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.368677Z","caller":"traceutil/trace.go:171","msg":"trace[38182279] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:559; }","duration":"210.997626ms","start":"2026-10-15T14:18:01.157644Z","end":"2026-10-15T14:18:01.368642Z","steps":["trace[38182279] 'read index received' (duration: 210.981966ms)","trace[38182279] 'applied index is now lower than readState.Index' (duration: 12.8µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:18:01.368846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.18404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-15T14:18:01.3689Z","caller":"traceutil/trace.go:171","msg":"trace[942957950] range","detail":"{range_begin:/registry/masterleases/162.253.55.193; range_end:; response_count:1; response_revision:539; }","duration":"211.281853ms","start":"2026-10-15T14:18:01.157603Z","end":"2026-10-15T14:18:01.368884Z","steps":["trace[942957950] 'agreement among raft nodes before linearized reading' (duration: 211.15194ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.646014Z","caller":"traceutil/trace.go:171","msg":"trace[1925924380] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"314.925152ms","start":"2026-10-15T14:18:01.331058Z","end":"2026-10-15T14:18:01.645984Z","steps":["trace[1925924380] 'process raft request' (duration: 314.798879ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.646106Z","caller":"traceutil/trace.go:171","msg":"trace[547671449] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"314.624075ms","start":"2026-10-15T14:18:01.331445Z","end":"2026-10-15T14:18:01.64607Z","steps":["trace[547671449] 'process raft request' (duration: 314.452081ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.646259Z","caller":"traceutil/trace.go:171","msg":"trace[691479674] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"301.893371ms","start":"2026-10-15T14:18:01.344353Z","end":"2026-10-15T14:18:01.646247Z","steps":["trace[691479674] 'process raft request' (duration: 301.619185ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.646335Z","caller":"traceutil/trace.go:171","msg":"trace[1161784708] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"302.629489ms","start":"2026-10-15T14:18:01.343658Z","end":"2026-10-15T14:18:01.646287Z","steps":["trace[1161784708] 'process raft request' (duration: 302.279121ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.646409Z","caller":"traceutil/trace.go:171","msg":"trace[2101854055] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"300.296344ms","start":"2026-10-15T14:18:01.34606Z","end":"2026-10-15T14:18:01.646357Z","steps":["trace[2101854055] 'process raft request' (duration: 299.946426ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.646464Z","caller":"traceutil/trace.go:171","msg":"trace[385877502] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"298.962982ms","start":"2026-10-15T14:18:01.347477Z","end":"2026-10-15T14:18:01.64644Z","steps":["trace[385877502] 'process raft request' (duration: 298.763447ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.646599Z","caller":"traceutil/trace.go:171","msg":"trace[2116910473] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"294.454725ms","start":"2026-10-15T14:18:01.352101Z","end":"2026-10-15T14:18:01.646555Z","steps":["trace[2116910473] 'process raft request' (duration: 294.25954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:01.646651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.331033Z","time spent":"315.179949ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:01.646694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.346043Z","time spent":"300.424437ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:01.646611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.33137Z","time spent":"314.828199ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:01.646781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.343631Z","time spent":"302.799694ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:01.646847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.344323Z","time spent":"301.965273ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:18:01.64661Z","caller":"traceutil/trace.go:171","msg":"trace[945751939] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"282.305685ms","start":"2026-10-15T14:18:01.364281Z","end":"2026-10-15T14:18:01.646587Z","steps":["trace[945751939] 'process raft request' (duration: 282.139601ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:01.646567Z","caller":"traceutil/trace.go:171","msg":"trace[936325593] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"322.289997ms","start":"2026-10-15T14:18:01.323742Z","end":"2026-10-15T14:18:01.646032Z","steps":["trace[936325593] 'process raft request' (duration: 322.014861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:01.647072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.323717Z","time spent":"323.296002ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:02.19157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.369981Z","time spent":"821.582162ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-15T14:18:02.191839Z","caller":"traceutil/trace.go:171","msg":"trace[1409039913] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:559; }","duration":"601.787016ms","start":"2026-10-15T14:18:01.590017Z","end":"2026-10-15T14:18:02.191804Z","steps":["trace[1409039913] 'read index received' (duration: 55.808619ms)","trace[1409039913] 'applied index is now lower than readState.Index' (duration: 545.977327ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:02.191888Z","caller":"traceutil/trace.go:171","msg":"trace[1561687287] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"795.801568ms","start":"2026-10-15T14:18:01.39605Z","end":"2026-10-15T14:18:02.191852Z","steps":["trace[1561687287] 'process raft request' (duration: 795.433079ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:02.191902Z","caller":"traceutil/trace.go:171","msg":"trace[794682278] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"778.886314ms","start":"2026-10-15T14:18:01.412979Z","end":"2026-10-15T14:18:02.191865Z","steps":["trace[794682278] 'process raft request' (duration: 778.624078ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:02.191982Z","caller":"traceutil/trace.go:171","msg":"trace[1045924933] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"642.517036ms","start":"2026-10-15T14:18:01.549395Z","end":"2026-10-15T14:18:02.191912Z","steps":["trace[1045924933] 'process raft request' (duration: 642.285231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.192032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.993651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T14:18:02.192041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.396019Z","time spent":"795.952622ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:02.19207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.412958Z","time spent":"779.026368ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:18:02.192073Z","caller":"traceutil/trace.go:171","msg":"trace[765087991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"602.075743ms","start":"2026-10-15T14:18:01.589985Z","end":"2026-10-15T14:18:02.192061Z","steps":["trace[765087991] 'agreement among raft nodes before linearized reading' (duration: 601.9479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.192148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.58997Z","time spent":"602.166705ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T14:18:02.192171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.549363Z","time spent":"642.689631ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:18:02.229339Z","caller":"traceutil/trace.go:171","msg":"trace[1730940793] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"572.449116ms","start":"2026-10-15T14:18:01.656865Z","end":"2026-10-15T14:18:02.229314Z","steps":["trace[1730940793] 'process raft request' (duration: 572.360874ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:02.229349Z","caller":"traceutil/trace.go:171","msg":"trace[1843401718] transaction","detail":"{read_only:false; number_of_response:1; response_revision:553; }","duration":"573.326167ms","start":"2026-10-15T14:18:01.655991Z","end":"2026-10-15T14:18:02.229318Z","steps":["trace[1843401718] 'process raft request' (duration: 573.189504ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:02.229462Z","caller":"traceutil/trace.go:171","msg":"trace[410928620] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"577.132638ms","start":"2026-10-15T14:18:01.652307Z","end":"2026-10-15T14:18:02.229439Z","steps":["trace[410928620] 'process raft request' (duration: 574.343472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.229481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.656855Z","time spent":"572.556819ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:18:02.229556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.652289Z","time spent":"577.223791ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:18:02.229668Z","caller":"traceutil/trace.go:171","msg":"trace[1545485019] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"570.969421ms","start":"2026-10-15T14:18:01.658681Z","end":"2026-10-15T14:18:02.22965Z","steps":["trace[1545485019] 'process raft request' (duration: 570.591742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.229783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.655981Z","time spent":"573.462711ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:18:02.229913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.658641Z","time spent":"571.119675ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:18:02.22937Z","caller":"traceutil/trace.go:171","msg":"trace[613504499] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"574.371923ms","start":"2026-10-15T14:18:01.654973Z","end":"2026-10-15T14:18:02.229345Z","steps":["trace[613504499] 'process raft request' (duration: 574.127137ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.230262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.65496Z","time spent":"575.263164ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:18:02.23017Z","caller":"traceutil/trace.go:171","msg":"trace[499572731] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"571.182636ms","start":"2026-10-15T14:18:01.658975Z","end":"2026-10-15T14:18:02.230158Z","steps":["trace[499572731] 'process raft request' (duration: 570.552611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.230478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.658964Z","time spent":"571.435063ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:18:02.232657Z","caller":"traceutil/trace.go:171","msg":"trace[317712759] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"571.379591ms","start":"2026-10-15T14:18:01.66126Z","end":"2026-10-15T14:18:02.232639Z","steps":["trace[317712759] 'process raft request' (duration: 568.493813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.232784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.661237Z","time spent":"571.511155ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:18:02.232869Z","caller":"traceutil/trace.go:171","msg":"trace[1027954992] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"571.500164ms","start":"2026-10-15T14:18:01.661288Z","end":"2026-10-15T14:18:02.232788Z","steps":["trace[1027954992] 'process raft request' (duration: 571.219547ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.233181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.661268Z","time spent":"571.699889ms","remote":"127.0.0.1:56318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:18:02.233416Z","caller":"traceutil/trace.go:171","msg":"trace[2027087087] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"565.110562ms","start":"2026-10-15T14:18:01.668235Z","end":"2026-10-15T14:18:02.233346Z","steps":["trace[2027087087] 'process raft request' (duration: 564.798625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.234156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.668218Z","time spent":"565.832459ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:18:02.233697Z","caller":"traceutil/trace.go:171","msg":"trace[330774531] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"563.491393ms","start":"2026-10-15T14:18:01.670182Z","end":"2026-10-15T14:18:02.233673Z","steps":["trace[330774531] 'process raft request' (duration: 562.984161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.234445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.670166Z","time spent":"564.116138ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:18:02.233711Z","caller":"traceutil/trace.go:171","msg":"trace[1585730153] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"562.12219ms","start":"2026-10-15T14:18:01.671569Z","end":"2026-10-15T14:18:02.233692Z","steps":["trace[1585730153] 'process raft request' (duration: 561.925706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.234626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.671557Z","time spent":"563.030733ms","remote":"127.0.0.1:55830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:18:02.233834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.079657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T14:18:02.234812Z","caller":"traceutil/trace.go:171","msg":"trace[694627755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"484.097972ms","start":"2026-10-15T14:18:01.750697Z","end":"2026-10-15T14:18:02.234795Z","steps":["trace[694627755] 'agreement among raft nodes before linearized reading' (duration: 483.067247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.233874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.394755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-15T14:18:02.234895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.750679Z","time spent":"484.198855ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T14:18:02.234977Z","caller":"traceutil/trace.go:171","msg":"trace[1764386775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"382.478161ms","start":"2026-10-15T14:18:01.85243Z","end":"2026-10-15T14:18:02.234908Z","steps":["trace[1764386775] 'agreement among raft nodes before linearized reading' (duration: 381.374775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.235199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.852408Z","time spent":"382.681806ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T14:18:02.233933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.021556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:18:02.235551Z","caller":"traceutil/trace.go:171","msg":"trace[2088383541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"332.655704ms","start":"2026-10-15T14:18:01.902877Z","end":"2026-10-15T14:18:02.235533Z","steps":["trace[2088383541] 'agreement among raft nodes before linearized reading' (duration: 330.897882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:02.235652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:18:01.902864Z","time spent":"332.765828ms","remote":"127.0.0.1:55794","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-15T14:18:09.012354Z","caller":"traceutil/trace.go:171","msg":"trace[1895697714] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"109.252923ms","start":"2026-10-15T14:18:08.903021Z","end":"2026-10-15T14:18:09.012274Z","steps":["trace[1895697714] 'read index received' (duration: 101.258914ms)","trace[1895697714] 'applied index is now lower than readState.Index' (duration: 7.992229ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:09.012399Z","caller":"traceutil/trace.go:171","msg":"trace[402000239] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"165.493374ms","start":"2026-10-15T14:18:08.84683Z","end":"2026-10-15T14:18:09.012323Z","steps":["trace[402000239] 'process raft request' (duration: 162.732418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:09.012534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.481969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:18:09.012615Z","caller":"traceutil/trace.go:171","msg":"trace[1090012920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"109.619162ms","start":"2026-10-15T14:18:08.90297Z","end":"2026-10-15T14:18:09.012589Z","steps":["trace[1090012920] 'agreement among raft nodes before linearized reading' (duration: 109.449918ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:09.358375Z","caller":"traceutil/trace.go:171","msg":"trace[1579738427] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"280.608785ms","start":"2026-10-15T14:18:09.077722Z","end":"2026-10-15T14:18:09.358331Z","steps":["trace[1579738427] 'process raft request' (duration: 258.026871ms)","trace[1579738427] 'compare' (duration: 22.398299ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:09.515701Z","caller":"traceutil/trace.go:171","msg":"trace[104807824] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"103.582869ms","start":"2026-10-15T14:18:09.41209Z","end":"2026-10-15T14:18:09.515672Z","steps":["trace[104807824] 'read index received' (duration: 98.839707ms)","trace[104807824] 'applied index is now lower than readState.Index' (duration: 4.742082ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:09.515822Z","caller":"traceutil/trace.go:171","msg":"trace[606002809] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"140.247126ms","start":"2026-10-15T14:18:09.375544Z","end":"2026-10-15T14:18:09.515792Z","steps":["trace[606002809] 'process raft request' (duration: 135.437922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:09.515915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.808114ms","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-15T14:18:09.515955Z","caller":"traceutil/trace.go:171","msg":"trace[1724357613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"103.911116ms","start":"2026-10-15T14:18:09.412033Z","end":"2026-10-15T14:18:09.515944Z","steps":["trace[1724357613] 'agreement among raft nodes before linearized reading' (duration: 103.732782ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:41.262459Z","caller":"traceutil/trace.go:171","msg":"trace[2087378949] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"293.676509ms","start":"2026-10-15T14:19:40.968754Z","end":"2026-10-15T14:19:41.262431Z","steps":["trace[2087378949] 'process raft request' (duration: 293.517065ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:41.262671Z","caller":"traceutil/trace.go:171","msg":"trace[1458603891] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"293.464323ms","start":"2026-10-15T14:19:40.969187Z","end":"2026-10-15T14:19:41.262651Z","steps":["trace[1458603891] 'process raft request' (duration: 293.189126ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:41.2627Z","caller":"traceutil/trace.go:171","msg":"trace[935252084] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"293.275719ms","start":"2026-10-15T14:19:40.969402Z","end":"2026-10-15T14:19:41.262677Z","steps":["trace[935252084] 'process raft request' (duration: 293.168376ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:41.262681Z","caller":"traceutil/trace.go:171","msg":"trace[1067762700] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"294.671114ms","start":"2026-10-15T14:19:40.967971Z","end":"2026-10-15T14:19:41.262642Z","steps":["trace[1067762700] 'process raft request' (duration: 294.13376ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:41.262922Z","caller":"traceutil/trace.go:171","msg":"trace[1148049855] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"286.750833ms","start":"2026-10-15T14:19:40.976155Z","end":"2026-10-15T14:19:41.262906Z","steps":["trace[1148049855] 'process raft request' (duration: 286.461365ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:41.262916Z","caller":"traceutil/trace.go:171","msg":"trace[1952173563] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1947; }","duration":"100.290803ms","start":"2026-10-15T14:19:41.162599Z","end":"2026-10-15T14:19:41.26289Z","steps":["trace[1952173563] 'read index received' (duration: 99.429252ms)","trace[1952173563] 'applied index is now lower than readState.Index' (duration: 858.741µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:41.263186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.459609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-15T14:19:41.263224Z","caller":"traceutil/trace.go:171","msg":"trace[593771492] range","detail":"{range_begin:/registry/masterleases/162.253.55.193; range_end:; response_count:1; response_revision:1882; }","duration":"100.656573ms","start":"2026-10-15T14:19:41.162558Z","end":"2026-10-15T14:19:41.263215Z","steps":["trace[593771492] 'agreement among raft nodes before linearized reading' (duration: 100.427237ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:41.414972Z","caller":"traceutil/trace.go:171","msg":"trace[854991257] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"122.62395ms","start":"2026-10-15T14:19:41.292318Z","end":"2026-10-15T14:19:41.414942Z","steps":["trace[854991257] 'process raft request' (duration: 101.171406ms)","trace[854991257] 'compare' (duration: 21.339091ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:53.947395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.099861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11986"} {"level":"warn","ts":"2026-10-15T14:19:53.94743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.661603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:53.947495Z","caller":"traceutil/trace.go:171","msg":"trace[1848483706] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1984; }","duration":"320.773516ms","start":"2026-10-15T14:19:53.626706Z","end":"2026-10-15T14:19:53.94748Z","steps":["trace[1848483706] 'count revisions from in-memory index tree' (duration: 320.573191ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:53.947498Z","caller":"traceutil/trace.go:171","msg":"trace[776533347] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1984; }","duration":"119.232375ms","start":"2026-10-15T14:19:53.828242Z","end":"2026-10-15T14:19:53.947475Z","steps":["trace[776533347] 'range keys from in-memory index tree' (duration: 118.918017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:53.947529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:53.626684Z","time spent":"320.835598ms","remote":"127.0.0.1:56116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:19:53.947625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.45313ms","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":"warn","ts":"2026-10-15T14:19:53.947676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.195469ms","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-15T14:19:53.947706Z","caller":"traceutil/trace.go:171","msg":"trace[940276349] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1984; }","duration":"134.568753ms","start":"2026-10-15T14:19:53.813114Z","end":"2026-10-15T14:19:53.947682Z","steps":["trace[940276349] 'count revisions from in-memory index tree' (duration: 134.379929ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:53.947741Z","caller":"traceutil/trace.go:171","msg":"trace[1972230900] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1984; }","duration":"450.285841ms","start":"2026-10-15T14:19:53.497429Z","end":"2026-10-15T14:19:53.947715Z","steps":["trace[1972230900] 'range keys from in-memory index tree' (duration: 449.974863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:53.947806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:53.49741Z","time spent":"450.382334ms","remote":"127.0.0.1:58202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-15T14:20:19.362377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.345527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:20:19.36251Z","caller":"traceutil/trace.go:171","msg":"trace[1283432542] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2140; }","duration":"117.628354ms","start":"2026-10-15T14:20:19.244852Z","end":"2026-10-15T14:20:19.36248Z","steps":["trace[1283432542] 'count revisions from in-memory index tree' (duration: 117.247595ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:31.171979Z","caller":"traceutil/trace.go:171","msg":"trace[1599019515] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"162.076865ms","start":"2026-10-15T14:20:31.009879Z","end":"2026-10-15T14:20:31.171956Z","steps":["trace[1599019515] 'process raft request' (duration: 161.90139ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:31.31028Z","caller":"traceutil/trace.go:171","msg":"trace[1313332009] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"133.946859ms","start":"2026-10-15T14:20:31.17631Z","end":"2026-10-15T14:20:31.310257Z","steps":["trace[1313332009] 'process raft request' (duration: 127.589011ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:31.310564Z","caller":"traceutil/trace.go:171","msg":"trace[673137958] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2282; }","duration":"107.482503ms","start":"2026-10-15T14:20:31.20306Z","end":"2026-10-15T14:20:31.310543Z","steps":["trace[673137958] 'read index received' (duration: 100.834428ms)","trace[673137958] 'applied index is now lower than readState.Index' (duration: 6.647065ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:20:31.31072Z","caller":"traceutil/trace.go:171","msg":"trace[110234256] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"120.123816ms","start":"2026-10-15T14:20:31.190503Z","end":"2026-10-15T14:20:31.310627Z","steps":["trace[110234256] 'process raft request' (duration: 119.940261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:31.310839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.756079ms","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-15T14:20:31.310875Z","caller":"traceutil/trace.go:171","msg":"trace[1382016697] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2202; }","duration":"107.851612ms","start":"2026-10-15T14:20:31.203012Z","end":"2026-10-15T14:20:31.310864Z","steps":["trace[1382016697] 'agreement among raft nodes before linearized reading' (duration: 107.646287ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:28.67851Z","caller":"traceutil/trace.go:171","msg":"trace[617241572] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"130.503545ms","start":"2026-10-15T14:21:28.547969Z","end":"2026-10-15T14:21:28.678473Z","steps":["trace[617241572] 'process raft request' (duration: 130.239439ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:06.645875Z","caller":"traceutil/trace.go:171","msg":"trace[2063040988] transaction","detail":"{read_only:false; response_revision:2908; number_of_response:1; }","duration":"345.468463ms","start":"2026-10-15T14:22:06.300365Z","end":"2026-10-15T14:22:06.645833Z","steps":["trace[2063040988] 'process raft request' (duration: 345.221297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:22:06.646047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:22:06.30034Z","time spent":"345.627486ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:22:06.684427Z","caller":"traceutil/trace.go:171","msg":"trace[1657674804] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"251.539082ms","start":"2026-10-15T14:22:06.432803Z","end":"2026-10-15T14:22:06.684342Z","steps":["trace[1657674804] 'process raft request' (duration: 251.256555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:22:06.907665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.309139ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T14:22:06.908028Z","caller":"traceutil/trace.go:171","msg":"trace[174505059] transaction","detail":"{read_only:false; response_revision:2910; number_of_response:1; }","duration":"392.694714ms","start":"2026-10-15T14:22:06.515304Z","end":"2026-10-15T14:22:06.907998Z","steps":["trace[174505059] 'process raft request' (duration: 392.446838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:22:06.908267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:22:06.515271Z","time spent":"392.852058ms","remote":"127.0.0.1:56066","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-15T14:25:09.329156Z","caller":"traceutil/trace.go:171","msg":"trace[942434366] transaction","detail":"{read_only:false; response_revision:4070; number_of_response:1; }","duration":"131.406876ms","start":"2026-10-15T14:25:09.197723Z","end":"2026-10-15T14:25:09.329129Z","steps":["trace[942434366] 'process raft request' (duration: 131.185761ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.106604Z","caller":"traceutil/trace.go:171","msg":"trace[1608744025] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5282; }","duration":"135.496916ms","start":"2026-10-15T14:26:39.971087Z","end":"2026-10-15T14:26:40.106584Z","steps":["trace[1608744025] 'process raft request' (duration: 135.385233ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.106607Z","caller":"traceutil/trace.go:171","msg":"trace[252894896] linearizableReadLoop","detail":"{readStateIndex:5481; appliedIndex:5480; }","duration":"108.402327ms","start":"2026-10-15T14:26:39.998173Z","end":"2026-10-15T14:26:40.106575Z","steps":["trace[252894896] 'read index received' (duration: 108.276654ms)","trace[252894896] 'applied index is now lower than readState.Index' (duration: 124.133µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:26:40.106909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.701814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-f2gkq-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-10-15T14:26:40.107041Z","caller":"traceutil/trace.go:171","msg":"trace[221567187] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-f2gkq-tls; range_end:; response_count:1; response_revision:5282; }","duration":"108.870528ms","start":"2026-10-15T14:26:39.998146Z","end":"2026-10-15T14:26:40.107016Z","steps":["trace[221567187] 'agreement among raft nodes before linearized reading' (duration: 108.549941ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.276572Z","caller":"traceutil/trace.go:171","msg":"trace[1667355568] transaction","detail":"{read_only:false; response_revision:5283; number_of_response:1; }","duration":"159.921681ms","start":"2026-10-15T14:26:40.116626Z","end":"2026-10-15T14:26:40.276547Z","steps":["trace[1667355568] 'process raft request' (duration: 159.711146ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.314671Z","caller":"traceutil/trace.go:171","msg":"trace[1528310440] transaction","detail":"{read_only:false; response_revision:5284; number_of_response:1; }","duration":"182.520693ms","start":"2026-10-15T14:26:40.132126Z","end":"2026-10-15T14:26:40.314647Z","steps":["trace[1528310440] 'process raft request' (duration: 182.352319ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.562689Z","caller":"traceutil/trace.go:171","msg":"trace[745525770] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"113.454788ms","start":"2026-10-15T14:26:40.44921Z","end":"2026-10-15T14:26:40.562665Z","steps":["trace[745525770] 'process raft request' (duration: 113.294515ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.590409Z","caller":"traceutil/trace.go:171","msg":"trace[573512468] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"123.811686ms","start":"2026-10-15T14:26:40.466558Z","end":"2026-10-15T14:26:40.59037Z","steps":["trace[573512468] 'process raft request' (duration: 123.53613ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:55.3808Z","caller":"traceutil/trace.go:171","msg":"trace[737876570] linearizableReadLoop","detail":"{readStateIndex:5759; appliedIndex:5758; }","duration":"247.737875ms","start":"2026-10-15T14:26:55.133049Z","end":"2026-10-15T14:26:55.380787Z","steps":["trace[737876570] 'read index received' (duration: 247.645123ms)","trace[737876570] 'applied index is now lower than readState.Index' (duration: 92.152µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:55.380886Z","caller":"traceutil/trace.go:171","msg":"trace[1805656782] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"430.172106ms","start":"2026-10-15T14:26:54.950672Z","end":"2026-10-15T14:26:55.380844Z","steps":["trace[1805656782] 'process raft request' (duration: 429.998622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:55.380944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.862949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-15T14:26:55.381018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.077744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-zq7w9\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-10-15T14:26:55.381019Z","caller":"traceutil/trace.go:171","msg":"trace[789033410] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5552; }","duration":"248.010722ms","start":"2026-10-15T14:26:55.132995Z","end":"2026-10-15T14:26:55.381006Z","steps":["trace[789033410] 'agreement among raft nodes before linearized reading' (duration: 247.865489ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:55.381044Z","caller":"traceutil/trace.go:171","msg":"trace[1197079801] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-zq7w9; range_end:; response_count:1; response_revision:5552; }","duration":"142.147006ms","start":"2026-10-15T14:26:55.23889Z","end":"2026-10-15T14:26:55.381037Z","steps":["trace[1197079801] 'agreement among raft nodes before linearized reading' (duration: 142.049164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:55.381075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:54.95065Z","time spent":"430.335411ms","remote":"127.0.0.1:55970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:26:55.381211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.843588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-15T14:26:55.381233Z","caller":"traceutil/trace.go:171","msg":"trace[1022480938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5552; }","duration":"247.90864ms","start":"2026-10-15T14:26:55.133319Z","end":"2026-10-15T14:26:55.381227Z","steps":["trace[1022480938] 'agreement among raft nodes before linearized reading' (duration: 247.573512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:13.671505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.03412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-15T14:27:13.671605Z","caller":"traceutil/trace.go:171","msg":"trace[1315137304] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5865; }","duration":"126.177113ms","start":"2026-10-15T14:27:13.545407Z","end":"2026-10-15T14:27:13.671584Z","steps":["trace[1315137304] 'agreement among raft nodes before linearized reading' (duration: 125.865895ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:14.032797Z","caller":"traceutil/trace.go:171","msg":"trace[1998823069] linearizableReadLoop","detail":"{readStateIndex:6083; appliedIndex:6082; }","duration":"129.667906ms","start":"2026-10-15T14:27:13.9031Z","end":"2026-10-15T14:27:14.032768Z","steps":["trace[1998823069] 'read index received' (duration: 60.379076ms)","trace[1998823069] 'applied index is now lower than readState.Index' (duration: 69.28765ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:27:14.032875Z","caller":"traceutil/trace.go:171","msg":"trace[1027148646] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"142.415002ms","start":"2026-10-15T14:27:13.890414Z","end":"2026-10-15T14:27:14.032828Z","steps":["trace[1027148646] 'process raft request' (duration: 73.155842ms)","trace[1027148646] 'compare' (duration: 69.068225ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:27:14.033004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.855572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:27:14.03305Z","caller":"traceutil/trace.go:171","msg":"trace[1359243930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5868; }","duration":"129.976334ms","start":"2026-10-15T14:27:13.903062Z","end":"2026-10-15T14:27:14.033038Z","steps":["trace[1359243930] 'agreement among raft nodes before linearized reading' (duration: 129.853191ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:17.564142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-10-15T14:27:17.64675Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"80.625622ms","hash":1791220959,"current-db-size-bytes":56393728,"current-db-size":"56 MB","current-db-size-in-use-bytes":32395264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:27:17.646821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1791220959,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-10-15T14:27:55.569678Z","caller":"traceutil/trace.go:171","msg":"trace[1072003826] transaction","detail":"{read_only:false; response_revision:6229; number_of_response:1; }","duration":"111.160869ms","start":"2026-10-15T14:27:55.458493Z","end":"2026-10-15T14:27:55.569654Z","steps":["trace[1072003826] 'process raft request' (duration: 111.050516ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:55.569703Z","caller":"traceutil/trace.go:171","msg":"trace[1693071561] transaction","detail":"{read_only:false; response_revision:6228; number_of_response:1; }","duration":"111.163839ms","start":"2026-10-15T14:27:55.458501Z","end":"2026-10-15T14:27:55.569665Z","steps":["trace[1693071561] 'process raft request' (duration: 98.6584ms)","trace[1693071561] 'compare' (duration: 12.258353ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:27:55.569934Z","caller":"traceutil/trace.go:171","msg":"trace[239273696] transaction","detail":"{read_only:false; response_revision:6230; number_of_response:1; }","duration":"111.246651ms","start":"2026-10-15T14:27:55.458661Z","end":"2026-10-15T14:27:55.569908Z","steps":["trace[239273696] 'process raft request' (duration: 110.931143ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:55.570079Z","caller":"traceutil/trace.go:171","msg":"trace[2068861528] linearizableReadLoop","detail":"{readStateIndex:6458; appliedIndex:6452; }","duration":"107.04275ms","start":"2026-10-15T14:27:55.46301Z","end":"2026-10-15T14:27:55.570053Z","steps":["trace[2068861528] 'read index received' (duration: 42.803034ms)","trace[2068861528] 'applied index is now lower than readState.Index' (duration: 64.238506ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:27:55.569982Z","caller":"traceutil/trace.go:171","msg":"trace[1278049920] transaction","detail":"{read_only:false; response_revision:6231; number_of_response:1; }","duration":"110.257537ms","start":"2026-10-15T14:27:55.459712Z","end":"2026-10-15T14:27:55.56997Z","steps":["trace[1278049920] 'process raft request' (duration: 110.071023ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:55.570138Z","caller":"traceutil/trace.go:171","msg":"trace[1779841544] transaction","detail":"{read_only:false; response_revision:6233; number_of_response:1; }","duration":"104.966151ms","start":"2026-10-15T14:27:55.465147Z","end":"2026-10-15T14:27:55.570114Z","steps":["trace[1779841544] 'process raft request' (duration: 104.826667ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:27:55.570227Z","caller":"traceutil/trace.go:171","msg":"trace[1745390488] transaction","detail":"{read_only:false; response_revision:6232; number_of_response:1; }","duration":"110.472653ms","start":"2026-10-15T14:27:55.459723Z","end":"2026-10-15T14:27:55.570196Z","steps":["trace[1745390488] 'process raft request' (duration: 110.164565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:27:55.570231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.190424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:27:55.570301Z","caller":"traceutil/trace.go:171","msg":"trace[1904998357] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:6233; }","duration":"107.273866ms","start":"2026-10-15T14:27:55.463004Z","end":"2026-10-15T14:27:55.570278Z","steps":["trace[1904998357] 'agreement among raft nodes before linearized reading' (duration: 107.154293ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:17.571426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5891} {"level":"info","ts":"2026-10-15T14:32:17.661102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5891,"took":"87.755257ms","hash":3414619317,"current-db-size-bytes":56393728,"current-db-size":"56 MB","current-db-size-in-use-bytes":26607616,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T14:32:17.661169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3414619317,"revision":5891,"compact-revision":2967} {"level":"warn","ts":"2026-10-15T14:33:14.054914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.127588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T14:33:14.055007Z","caller":"traceutil/trace.go:171","msg":"trace[377290306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8576; }","duration":"376.265951ms","start":"2026-10-15T14:33:13.678719Z","end":"2026-10-15T14:33:14.054985Z","steps":["trace[377290306] 'range keys from in-memory index tree' (duration: 375.925823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:14.055045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:33:13.678702Z","time spent":"376.332983ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T14:33:14.055091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.024593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hm7t8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T14:33:14.055217Z","caller":"traceutil/trace.go:171","msg":"trace[515542724] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hm7t8; range_end:; response_count:1; response_revision:8576; }","duration":"152.172996ms","start":"2026-10-15T14:33:13.903019Z","end":"2026-10-15T14:33:14.055192Z","steps":["trace[515542724] 'range keys from in-memory index tree' (duration: 151.783817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:14.055538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.819547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:33:14.055585Z","caller":"traceutil/trace.go:171","msg":"trace[73015315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8576; }","duration":"151.865989ms","start":"2026-10-15T14:33:13.903708Z","end":"2026-10-15T14:33:14.055574Z","steps":["trace[73015315] 'range keys from in-memory index tree' (duration: 151.777617ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:26.268049Z","caller":"traceutil/trace.go:171","msg":"trace[29041560] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"128.637454ms","start":"2026-10-15T14:33:26.13936Z","end":"2026-10-15T14:33:26.267998Z","steps":["trace[29041560] 'process raft request' (duration: 128.46544ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:26.320777Z","caller":"traceutil/trace.go:171","msg":"trace[1427564389] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"180.732618ms","start":"2026-10-15T14:33:26.140005Z","end":"2026-10-15T14:33:26.320738Z","steps":["trace[1427564389] 'process raft request' (duration: 180.519453ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:26.367904Z","caller":"traceutil/trace.go:171","msg":"trace[879448884] linearizableReadLoop","detail":"{readStateIndex:8949; appliedIndex:8947; }","duration":"218.965993ms","start":"2026-10-15T14:33:26.148904Z","end":"2026-10-15T14:33:26.36787Z","steps":["trace[879448884] 'read index received' (duration: 119.012284ms)","trace[879448884] 'applied index is now lower than readState.Index' (duration: 99.948859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:33:26.368111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.187078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hm7t8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T14:33:26.368208Z","caller":"traceutil/trace.go:171","msg":"trace[1003364628] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hm7t8; range_end:; response_count:1; response_revision:8642; }","duration":"219.341241ms","start":"2026-10-15T14:33:26.148842Z","end":"2026-10-15T14:33:26.368183Z","steps":["trace[1003364628] 'agreement among raft nodes before linearized reading' (duration: 219.111316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:26.38806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.141537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:33:26.388134Z","caller":"traceutil/trace.go:171","msg":"trace[1230565410] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8643; }","duration":"178.28422ms","start":"2026-10-15T14:33:26.209831Z","end":"2026-10-15T14:33:26.388115Z","steps":["trace[1230565410] 'agreement among raft nodes before linearized reading' (duration: 158.792325ms)","trace[1230565410] 'count revisions from in-memory index tree' (duration: 19.358323ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:27.758543Z","caller":"traceutil/trace.go:171","msg":"trace[1493946026] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"119.791092ms","start":"2026-10-15T14:33:27.638723Z","end":"2026-10-15T14:33:27.758514Z","steps":["trace[1493946026] 'process raft request' (duration: 91.966847ms)","trace[1493946026] 'compare' (duration: 27.59462ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:33:56.767017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.157227ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T14:33:56.76714Z","caller":"traceutil/trace.go:171","msg":"trace[961152515] linearizableReadLoop","detail":"{readStateIndex:9174; appliedIndex:9173; }","duration":"361.9666ms","start":"2026-10-15T14:33:56.405155Z","end":"2026-10-15T14:33:56.767121Z","steps":["trace[961152515] 'read index received' (duration: 147.679999ms)","trace[961152515] 'applied index is now lower than readState.Index' (duration: 214.2851ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:33:56.767244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.082832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:33:56.767278Z","caller":"traceutil/trace.go:171","msg":"trace[1157433587] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8857; }","duration":"362.157554ms","start":"2026-10-15T14:33:56.405109Z","end":"2026-10-15T14:33:56.767266Z","steps":["trace[1157433587] 'agreement among raft nodes before linearized reading' (duration: 362.080862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:56.767313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:33:56.405087Z","time spent":"362.216425ms","remote":"127.0.0.1:56034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:33:56.769284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.455487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T14:33:56.76933Z","caller":"traceutil/trace.go:171","msg":"trace[1619048357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8858; }","duration":"343.545569ms","start":"2026-10-15T14:33:56.425774Z","end":"2026-10-15T14:33:56.76932Z","steps":["trace[1619048357] 'agreement among raft nodes before linearized reading' (duration: 343.386715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:56.76936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:33:56.425764Z","time spent":"343.58956ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T14:33:56.769593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.069735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hm7t8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T14:33:56.769621Z","caller":"traceutil/trace.go:171","msg":"trace[409361566] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hm7t8; range_end:; response_count:1; response_revision:8858; }","duration":"191.117927ms","start":"2026-10-15T14:33:56.578494Z","end":"2026-10-15T14:33:56.769612Z","steps":["trace[409361566] 'agreement among raft nodes before linearized reading' (duration: 191.029615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:56.769835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.464544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T14:33:56.76987Z","caller":"traceutil/trace.go:171","msg":"trace[835361306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8858; }","duration":"291.581167ms","start":"2026-10-15T14:33:56.478277Z","end":"2026-10-15T14:33:56.769858Z","steps":["trace[835361306] 'agreement among raft nodes before linearized reading' (duration: 291.466504ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:56.76993Z","caller":"traceutil/trace.go:171","msg":"trace[1113906136] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"292.163651ms","start":"2026-10-15T14:33:56.477741Z","end":"2026-10-15T14:33:56.769904Z","steps":["trace[1113906136] 'process raft request' (duration: 291.30496ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:06.965382Z","caller":"traceutil/trace.go:171","msg":"trace[1962481387] linearizableReadLoop","detail":"{readStateIndex:9231; appliedIndex:9230; }","duration":"105.259354ms","start":"2026-10-15T14:34:06.860091Z","end":"2026-10-15T14:34:06.965351Z","steps":["trace[1962481387] 'read index received' (duration: 105.016648ms)","trace[1962481387] 'applied index is now lower than readState.Index' (duration: 241.626µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:34:06.965429Z","caller":"traceutil/trace.go:171","msg":"trace[1643276760] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"127.753472ms","start":"2026-10-15T14:34:06.837647Z","end":"2026-10-15T14:34:06.965401Z","steps":["trace[1643276760] 'process raft request' (duration: 127.512436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:34:06.965655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.541601ms","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-15T14:34:06.965725Z","caller":"traceutil/trace.go:171","msg":"trace[1114797258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8912; }","duration":"105.663414ms","start":"2026-10-15T14:34:06.860048Z","end":"2026-10-15T14:34:06.965711Z","steps":["trace[1114797258] 'agreement among raft nodes before linearized reading' (duration: 105.458019ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:07.112207Z","caller":"traceutil/trace.go:171","msg":"trace[605425321] linearizableReadLoop","detail":"{readStateIndex:9232; appliedIndex:9231; }","duration":"142.210538ms","start":"2026-10-15T14:34:06.969963Z","end":"2026-10-15T14:34:07.112174Z","steps":["trace[605425321] 'read index received' (duration: 141.907521ms)","trace[605425321] 'applied index is now lower than readState.Index' (duration: 301.417µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:34:07.112286Z","caller":"traceutil/trace.go:171","msg":"trace[1418339852] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"142.611417ms","start":"2026-10-15T14:34:06.969621Z","end":"2026-10-15T14:34:07.112232Z","steps":["trace[1418339852] 'process raft request' (duration: 142.30635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:34:07.112729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.73232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228570"} {"level":"info","ts":"2026-10-15T14:34:07.11276Z","caller":"traceutil/trace.go:171","msg":"trace[299563301] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8913; }","duration":"142.800252ms","start":"2026-10-15T14:34:06.969952Z","end":"2026-10-15T14:34:07.112752Z","steps":["trace[299563301] 'agreement among raft nodes before linearized reading' (duration: 142.345191ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:07.119497Z","caller":"traceutil/trace.go:171","msg":"trace[1793413596] transaction","detail":"{read_only:false; response_revision:8915; number_of_response:1; }","duration":"148.432227ms","start":"2026-10-15T14:34:06.971041Z","end":"2026-10-15T14:34:07.119473Z","steps":["trace[1793413596] 'process raft request' (duration: 148.382825ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:07.119582Z","caller":"traceutil/trace.go:171","msg":"trace[1912250314] transaction","detail":"{read_only:false; response_revision:8914; number_of_response:1; }","duration":"148.603791ms","start":"2026-10-15T14:34:06.970945Z","end":"2026-10-15T14:34:07.119549Z","steps":["trace[1912250314] 'process raft request' (duration: 148.331225ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:09.976248Z","caller":"traceutil/trace.go:171","msg":"trace[300353775] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8941; }","duration":"120.232243ms","start":"2026-10-15T14:34:09.855972Z","end":"2026-10-15T14:34:09.976204Z","steps":["trace[300353775] 'process raft request' (duration: 119.627079ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:25.223348Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d5.wal"} {"level":"info","ts":"2026-10-15T14:35:16.393598Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:35:16.400925Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T14:35:16.401038Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T14:37:17.579736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8067} {"level":"info","ts":"2026-10-15T14:37:17.656948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8067,"took":"75.154117ms","hash":459289974,"current-db-size-bytes":56393728,"current-db-size":"56 MB","current-db-size-in-use-bytes":28487680,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:37:17.657019Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":459289974,"revision":8067,"compact-revision":5891} {"level":"info","ts":"2026-10-15T14:42:17.586248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10485} {"level":"info","ts":"2026-10-15T14:42:17.675222Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10485,"took":"86.38137ms","hash":733980354,"current-db-size-bytes":56393728,"current-db-size":"56 MB","current-db-size-in-use-bytes":26025984,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T14:42:17.675296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":733980354,"revision":10485,"compact-revision":8067} {"level":"info","ts":"2026-10-15T14:47:17.594292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12644} {"level":"info","ts":"2026-10-15T14:47:17.676149Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12644,"took":"79.452718ms","hash":2621002471,"current-db-size-bytes":56393728,"current-db-size":"56 MB","current-db-size-in-use-bytes":27688960,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:47:17.67623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2621002471,"revision":12644,"compact-revision":10485} {"level":"info","ts":"2026-10-15T14:47:48.830374Z","caller":"traceutil/trace.go:171","msg":"trace[1411067604] transaction","detail":"{read_only:false; response_revision:14962; number_of_response:1; }","duration":"151.263485ms","start":"2026-10-15T14:47:48.679081Z","end":"2026-10-15T14:47:48.830344Z","steps":["trace[1411067604] 'process raft request' (duration: 151.07576ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:47:48.953718Z","caller":"traceutil/trace.go:171","msg":"trace[1607486461] transaction","detail":"{read_only:false; response_revision:14963; number_of_response:1; }","duration":"268.858974ms","start":"2026-10-15T14:47:48.684832Z","end":"2026-10-15T14:47:48.95369Z","steps":["trace[1607486461] 'process raft request' (duration: 219.137386ms)","trace[1607486461] 'compare' (duration: 49.548654ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:47:48.953983Z","caller":"traceutil/trace.go:171","msg":"trace[83533396] linearizableReadLoop","detail":"{readStateIndex:15481; appliedIndex:15478; }","duration":"156.076429ms","start":"2026-10-15T14:47:48.797864Z","end":"2026-10-15T14:47:48.95394Z","steps":["trace[83533396] 'read index received' (duration: 32.305252ms)","trace[83533396] 'applied index is now lower than readState.Index' (duration: 123.768727ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:47:48.954091Z","caller":"traceutil/trace.go:171","msg":"trace[2073265846] transaction","detail":"{read_only:false; response_revision:14964; number_of_response:1; }","duration":"255.998997ms","start":"2026-10-15T14:47:48.698077Z","end":"2026-10-15T14:47:48.954076Z","steps":["trace[2073265846] 'process raft request' (duration: 255.567847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:47:48.954306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.399808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c1925417-8df3-4d22-9388-c29a6abae293\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-10-15T14:47:48.954357Z","caller":"traceutil/trace.go:171","msg":"trace[978806028] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c1925417-8df3-4d22-9388-c29a6abae293; range_end:; response_count:1; response_revision:14964; }","duration":"156.52457ms","start":"2026-10-15T14:47:48.797818Z","end":"2026-10-15T14:47:48.954342Z","steps":["trace[978806028] 'agreement among raft nodes before linearized reading' (duration: 156.277124ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:47:49.09335Z","caller":"traceutil/trace.go:171","msg":"trace[573824472] transaction","detail":"{read_only:false; response_revision:14965; number_of_response:1; }","duration":"131.492672ms","start":"2026-10-15T14:47:48.961826Z","end":"2026-10-15T14:47:49.093319Z","steps":["trace[573824472] 'process raft request' (duration: 130.933648ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:47:49.208788Z","caller":"traceutil/trace.go:171","msg":"trace[1716094265] transaction","detail":"{read_only:false; response_revision:14967; number_of_response:1; }","duration":"100.884331ms","start":"2026-10-15T14:47:49.107873Z","end":"2026-10-15T14:47:49.208758Z","steps":["trace[1716094265] 'process raft request' (duration: 96.22868ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:49:29.222409Z","caller":"traceutil/trace.go:171","msg":"trace[1325840940] linearizableReadLoop","detail":"{readStateIndex:16600; appliedIndex:16599; }","duration":"116.362651ms","start":"2026-10-15T14:49:29.106023Z","end":"2026-10-15T14:49:29.222385Z","steps":["trace[1325840940] 'read index received' (duration: 116.223408ms)","trace[1325840940] 'applied index is now lower than readState.Index' (duration: 138.213µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:49:29.222474Z","caller":"traceutil/trace.go:171","msg":"trace[627610302] transaction","detail":"{read_only:false; response_revision:16047; number_of_response:1; }","duration":"158.167592ms","start":"2026-10-15T14:49:29.064273Z","end":"2026-10-15T14:49:29.222441Z","steps":["trace[627610302] 'process raft request' (duration: 157.970427ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:49:29.222722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.67863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pxzp9\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-10-15T14:49:29.222809Z","caller":"traceutil/trace.go:171","msg":"trace[878308203] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pxzp9; range_end:; response_count:1; response_revision:16047; }","duration":"116.827563ms","start":"2026-10-15T14:49:29.105963Z","end":"2026-10-15T14:49:29.222791Z","steps":["trace[878308203] 'agreement among raft nodes before linearized reading' (duration: 116.552037ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:52:17.60326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14664} {"level":"info","ts":"2026-10-15T14:52:17.688326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14664,"took":"82.022511ms","hash":3357293210,"current-db-size-bytes":56393728,"current-db-size":"56 MB","current-db-size-in-use-bytes":32546816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:52:17.688511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3357293210,"revision":14664,"compact-revision":12644} {"level":"warn","ts":"2026-10-15T14:55:10.119044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.914331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:10.119144Z","caller":"traceutil/trace.go:171","msg":"trace[508604653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18653; }","duration":"218.057835ms","start":"2026-10-15T14:55:09.901066Z","end":"2026-10-15T14:55:10.119124Z","steps":["trace[508604653] 'range keys from in-memory index tree' (duration: 217.791469ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:10.119213Z","caller":"traceutil/trace.go:171","msg":"trace[216863528] linearizableReadLoop","detail":"{readStateIndex:19291; appliedIndex:19290; }","duration":"180.849235ms","start":"2026-10-15T14:55:09.938339Z","end":"2026-10-15T14:55:10.119189Z","steps":["trace[216863528] 'read index received' (duration: 125.186254ms)","trace[216863528] 'applied index is now lower than readState.Index' (duration: 55.661631ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:10.119281Z","caller":"traceutil/trace.go:171","msg":"trace[164590227] transaction","detail":"{read_only:false; response_revision:18654; number_of_response:1; }","duration":"203.221921ms","start":"2026-10-15T14:55:09.916026Z","end":"2026-10-15T14:55:10.119247Z","steps":["trace[164590227] 'process raft request' (duration: 147.444897ms)","trace[164590227] 'compare' (duration: 55.404795ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:55:10.119437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.06773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-15T14:55:10.119481Z","caller":"traceutil/trace.go:171","msg":"trace[667074464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18654; }","duration":"181.171573ms","start":"2026-10-15T14:55:09.938294Z","end":"2026-10-15T14:55:10.119466Z","steps":["trace[667074464] 'agreement among raft nodes before linearized reading' (duration: 181.015379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:10.11954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.471225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pxzp9\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-15T14:55:10.119611Z","caller":"traceutil/trace.go:171","msg":"trace[1357963237] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pxzp9; range_end:; response_count:1; response_revision:18654; }","duration":"149.573457ms","start":"2026-10-15T14:55:09.97002Z","end":"2026-10-15T14:55:10.119593Z","steps":["trace[1357963237] 'agreement among raft nodes before linearized reading' (duration: 149.354312ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:17.345471Z","caller":"traceutil/trace.go:171","msg":"trace[1954528217] linearizableReadLoop","detail":"{readStateIndex:19347; appliedIndex:19346; }","duration":"121.715821ms","start":"2026-10-15T14:55:17.22374Z","end":"2026-10-15T14:55:17.345456Z","steps":["trace[1954528217] 'read index received' (duration: 121.552017ms)","trace[1954528217] 'applied index is now lower than readState.Index' (duration: 163.094µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:55:17.345563Z","caller":"traceutil/trace.go:171","msg":"trace[470055942] transaction","detail":"{read_only:false; response_revision:18708; number_of_response:1; }","duration":"150.421607ms","start":"2026-10-15T14:55:17.195099Z","end":"2026-10-15T14:55:17.345521Z","steps":["trace[470055942] 'process raft request' (duration: 150.261784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:17.345637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.858074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-10-15T14:55:17.345667Z","caller":"traceutil/trace.go:171","msg":"trace[1285760302] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:18708; }","duration":"121.982347ms","start":"2026-10-15T14:55:17.223676Z","end":"2026-10-15T14:55:17.345658Z","steps":["trace[1285760302] 'agreement among raft nodes before linearized reading' (duration: 121.845233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:18.227617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.819638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T14:55:18.227627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.931794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-cngfj\" ","response":"range_response_count:1 size:20756"} {"level":"info","ts":"2026-10-15T14:55:18.227756Z","caller":"traceutil/trace.go:171","msg":"trace[1308653883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18709; }","duration":"324.963542ms","start":"2026-10-15T14:55:17.902766Z","end":"2026-10-15T14:55:18.227729Z","steps":["trace[1308653883] 'range keys from in-memory index tree' (duration: 324.719165ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:18.227777Z","caller":"traceutil/trace.go:171","msg":"trace[628698330] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-cngfj; range_end:; response_count:1; response_revision:18709; }","duration":"262.133629ms","start":"2026-10-15T14:55:17.965615Z","end":"2026-10-15T14:55:18.227749Z","steps":["trace[628698330] 'range keys from in-memory index tree' (duration: 261.720239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:18.22782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:55:17.902672Z","time spent":"325.131166ms","remote":"127.0.0.1:55794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T14:55:18.228224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.292372ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:18.228874Z","caller":"traceutil/trace.go:171","msg":"trace[927655452] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18709; }","duration":"278.269865ms","start":"2026-10-15T14:55:17.950585Z","end":"2026-10-15T14:55:18.228855Z","steps":["trace[927655452] 'range keys from in-memory index tree' (duration: 277.281891ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:18.332328Z","caller":"traceutil/trace.go:171","msg":"trace[1161993151] linearizableReadLoop","detail":"{readStateIndex:19349; appliedIndex:19348; }","duration":"171.243005ms","start":"2026-10-15T14:55:18.16106Z","end":"2026-10-15T14:55:18.332303Z","steps":["trace[1161993151] 'read index received' (duration: 171.152263ms)","trace[1161993151] 'applied index is now lower than readState.Index' (duration: 89.382µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:55:18.33276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.664886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:55:18.332801Z","caller":"traceutil/trace.go:171","msg":"trace[881976402] transaction","detail":"{read_only:false; response_revision:18710; number_of_response:1; }","duration":"182.350671ms","start":"2026-10-15T14:55:18.150416Z","end":"2026-10-15T14:55:18.332767Z","steps":["trace[881976402] 'process raft request' (duration: 181.715186ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:18.332852Z","caller":"traceutil/trace.go:171","msg":"trace[902045176] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:18710; }","duration":"171.823329ms","start":"2026-10-15T14:55:18.161008Z","end":"2026-10-15T14:55:18.332832Z","steps":["trace[902045176] 'agreement among raft nodes before linearized reading' (duration: 171.617114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:18.361032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.830797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-10-15T14:55:18.361034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.842313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T14:55:18.361087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.123455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pxzp9\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-15T14:55:18.361061Z","caller":"traceutil/trace.go:171","msg":"trace[1177117615] transaction","detail":"{read_only:false; response_revision:18711; number_of_response:1; }","duration":"108.202338ms","start":"2026-10-15T14:55:18.252807Z","end":"2026-10-15T14:55:18.361009Z","steps":["trace[1177117615] 'process raft request' (duration: 107.701436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:55:18.361145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.065789ms","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-15T14:55:18.361162Z","caller":"traceutil/trace.go:171","msg":"trace[1260283028] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pxzp9; range_end:; response_count:1; response_revision:18711; }","duration":"123.214997ms","start":"2026-10-15T14:55:18.237928Z","end":"2026-10-15T14:55:18.361143Z","steps":["trace[1260283028] 'agreement among raft nodes before linearized reading' (duration: 122.949111ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:18.361155Z","caller":"traceutil/trace.go:171","msg":"trace[1823978226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18711; }","duration":"131.980907ms","start":"2026-10-15T14:55:18.229151Z","end":"2026-10-15T14:55:18.361132Z","steps":["trace[1823978226] 'agreement among raft nodes before linearized reading' (duration: 131.780192ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:18.361204Z","caller":"traceutil/trace.go:171","msg":"trace[1507445764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18711; }","duration":"196.165942ms","start":"2026-10-15T14:55:18.165025Z","end":"2026-10-15T14:55:18.361191Z","steps":["trace[1507445764] 'agreement among raft nodes before linearized reading' (duration: 195.929536ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:18.361341Z","caller":"traceutil/trace.go:171","msg":"trace[1010736751] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18711; }","duration":"197.001511ms","start":"2026-10-15T14:55:18.164115Z","end":"2026-10-15T14:55:18.361117Z","steps":["trace[1010736751] 'agreement among raft nodes before linearized reading' (duration: 196.712744ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:41.449853Z","caller":"traceutil/trace.go:171","msg":"trace[129127913] linearizableReadLoop","detail":"{readStateIndex:19728; appliedIndex:19727; }","duration":"156.310098ms","start":"2026-10-15T14:55:41.293516Z","end":"2026-10-15T14:55:41.449826Z","steps":["trace[129127913] 'read index received' (duration: 156.186755ms)","trace[129127913] 'applied index is now lower than readState.Index' (duration: 121.813µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:55:41.450081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.552973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-15T14:55:41.450125Z","caller":"traceutil/trace.go:171","msg":"trace[1927530900] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:19077; }","duration":"156.660827ms","start":"2026-10-15T14:55:41.29345Z","end":"2026-10-15T14:55:41.450111Z","steps":["trace[1927530900] 'agreement among raft nodes before linearized reading' (duration: 156.480603ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:56:10.734171Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:56:10.740782Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T14:56:10.740897Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T14:57:17.609633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17263} {"level":"info","ts":"2026-10-15T14:57:17.718995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17263,"took":"105.583725ms","hash":2091461844,"current-db-size-bytes":56393728,"current-db-size":"56 MB","current-db-size-in-use-bytes":34430976,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T14:57:17.71906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2091461844,"revision":17263,"compact-revision":14664} {"level":"info","ts":"2026-10-15T15:00:44.122552Z","caller":"traceutil/trace.go:171","msg":"trace[1744276928] transaction","detail":"{read_only:false; response_revision:21265; number_of_response:1; }","duration":"127.885858ms","start":"2026-10-15T15:00:43.99463Z","end":"2026-10-15T15:00:44.122516Z","steps":["trace[1744276928] 'process raft request' (duration: 127.663673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:44.410884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.491802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:00:44.411139Z","caller":"traceutil/trace.go:171","msg":"trace[913246392] linearizableReadLoop","detail":"{readStateIndex:21991; appliedIndex:21989; }","duration":"264.327462ms","start":"2026-10-15T15:00:44.146792Z","end":"2026-10-15T15:00:44.411119Z","steps":["trace[913246392] 'read index received' (duration: 120.810659ms)","trace[913246392] 'applied index is now lower than readState.Index' (duration: 143.515772ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:00:44.411239Z","caller":"traceutil/trace.go:171","msg":"trace[450130179] transaction","detail":"{read_only:false; response_revision:21267; number_of_response:1; }","duration":"284.011432ms","start":"2026-10-15T15:00:44.127214Z","end":"2026-10-15T15:00:44.411226Z","steps":["trace[450130179] 'process raft request' (duration: 283.780007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:44.41145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.662549ms","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-15T15:00:44.411485Z","caller":"traceutil/trace.go:171","msg":"trace[2004266560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21267; }","duration":"264.69796ms","start":"2026-10-15T15:00:44.146777Z","end":"2026-10-15T15:00:44.411475Z","steps":["trace[2004266560] 'agreement among raft nodes before linearized reading' (duration: 264.580697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:44.411606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.777828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T15:00:44.411688Z","caller":"traceutil/trace.go:171","msg":"trace[834483749] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21267; }","duration":"176.907651ms","start":"2026-10-15T15:00:44.234758Z","end":"2026-10-15T15:00:44.411666Z","steps":["trace[834483749] 'agreement among raft nodes before linearized reading' (duration: 176.641944ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:00:44.411782Z","caller":"traceutil/trace.go:171","msg":"trace[1818432026] transaction","detail":"{read_only:false; response_revision:21266; number_of_response:1; }","duration":"284.591626ms","start":"2026-10-15T15:00:44.126854Z","end":"2026-10-15T15:00:44.411446Z","steps":["trace[1818432026] 'process raft request' (duration: 140.434879ms)","trace[1818432026] 'compare' (duration: 143.299137ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:00:44.761776Z","caller":"traceutil/trace.go:171","msg":"trace[1744201153] linearizableReadLoop","detail":"{readStateIndex:21993; appliedIndex:21992; }","duration":"277.489546ms","start":"2026-10-15T15:00:44.484262Z","end":"2026-10-15T15:00:44.761752Z","steps":["trace[1744201153] 'read index received' (duration: 277.283441ms)","trace[1744201153] 'applied index is now lower than readState.Index' (duration: 205.195µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:00:44.761905Z","caller":"traceutil/trace.go:171","msg":"trace[1561047274] transaction","detail":"{read_only:false; response_revision:21269; number_of_response:1; }","duration":"345.591075ms","start":"2026-10-15T15:00:44.416261Z","end":"2026-10-15T15:00:44.761852Z","steps":["trace[1561047274] 'process raft request' (duration: 345.331288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:44.761955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.570395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:00:44.762006Z","caller":"traceutil/trace.go:171","msg":"trace[211296661] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:21269; }","duration":"202.690488ms","start":"2026-10-15T15:00:44.559301Z","end":"2026-10-15T15:00:44.761991Z","steps":["trace[211296661] 'agreement among raft nodes before linearized reading' (duration: 202.585955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:44.761997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.707912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:00:44.762086Z","caller":"traceutil/trace.go:171","msg":"trace[1138637542] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:21269; }","duration":"277.856945ms","start":"2026-10-15T15:00:44.484212Z","end":"2026-10-15T15:00:44.762068Z","steps":["trace[1138637542] 'agreement among raft nodes before linearized reading' (duration: 277.66597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:44.762125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:44.41624Z","time spent":"345.765569ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:01:36.522673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.823372ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-15T15:02:17.616005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19773} {"level":"info","ts":"2026-10-15T15:02:17.741738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19773,"took":"121.552477ms","hash":2973423613,"current-db-size-bytes":56393728,"current-db-size":"56 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:02:17.741804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2973423613,"revision":19773,"compact-revision":17263} {"level":"info","ts":"2026-10-15T15:04:43.73429Z","caller":"traceutil/trace.go:171","msg":"trace[520977669] transaction","detail":"{read_only:false; response_revision:23723; number_of_response:1; }","duration":"126.996764ms","start":"2026-10-15T15:04:43.607265Z","end":"2026-10-15T15:04:43.734262Z","steps":["trace[520977669] 'process raft request' (duration: 126.81636ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:17.622349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22071} {"level":"info","ts":"2026-10-15T15:07:17.738486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22071,"took":"112.535059ms","hash":3259886107,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":45416448,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-15T15:07:17.738614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3259886107,"revision":22071,"compact-revision":19773} {"level":"info","ts":"2026-10-15T15:08:19.881262Z","caller":"traceutil/trace.go:171","msg":"trace[112493950] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"145.526268ms","start":"2026-10-15T15:08:19.735697Z","end":"2026-10-15T15:08:19.881223Z","steps":["trace[112493950] 'process raft request' (duration: 145.370544ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:20.073527Z","caller":"traceutil/trace.go:171","msg":"trace[2011528655] linearizableReadLoop","detail":"{readStateIndex:26721; appliedIndex:26720; }","duration":"170.964535ms","start":"2026-10-15T15:08:19.902514Z","end":"2026-10-15T15:08:20.073479Z","steps":["trace[2011528655] 'read index received' (duration: 170.606077ms)","trace[2011528655] 'applied index is now lower than readState.Index' (duration: 355.758µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:08:20.073729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.19026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:08:20.073813Z","caller":"traceutil/trace.go:171","msg":"trace[1635239703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25872; }","duration":"171.314534ms","start":"2026-10-15T15:08:19.902479Z","end":"2026-10-15T15:08:20.073793Z","steps":["trace[1635239703] 'agreement among raft nodes before linearized reading' (duration: 171.17675ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:20.073606Z","caller":"traceutil/trace.go:171","msg":"trace[536934632] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"313.126511ms","start":"2026-10-15T15:08:19.760436Z","end":"2026-10-15T15:08:20.073563Z","steps":["trace[536934632] 'process raft request' (duration: 312.725352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:08:20.074173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:08:19.760419Z","time spent":"313.560112ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:08:20.084177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.016974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:08:20.084302Z","caller":"traceutil/trace.go:171","msg":"trace[822359894] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25873; }","duration":"155.117017ms","start":"2026-10-15T15:08:19.929103Z","end":"2026-10-15T15:08:20.08422Z","steps":["trace[822359894] 'agreement among raft nodes before linearized reading' (duration: 155.032465ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:20.281994Z","caller":"traceutil/trace.go:171","msg":"trace[961768103] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"139.80366ms","start":"2026-10-15T15:08:20.142156Z","end":"2026-10-15T15:08:20.28196Z","steps":["trace[961768103] 'process raft request' (duration: 112.457657ms)","trace[961768103] 'compare' (duration: 26.740279ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:09:00.566508Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069f1.wal"} {"level":"info","ts":"2026-10-15T15:09:00.567028Z","caller":"traceutil/trace.go:171","msg":"trace[1844456654] transaction","detail":"{read_only:false; response_revision:26261; number_of_response:1; }","duration":"105.862299ms","start":"2026-10-15T15:09:00.461138Z","end":"2026-10-15T15:09:00.567001Z","steps":["trace[1844456654] 'process raft request' (duration: 105.586783ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:14.450488Z","caller":"traceutil/trace.go:171","msg":"trace[1925501625] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"155.926066ms","start":"2026-10-15T15:09:14.294534Z","end":"2026-10-15T15:09:14.45046Z","steps":["trace[1925501625] 'process raft request' (duration: 155.730811ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:14.452024Z","caller":"traceutil/trace.go:171","msg":"trace[681680547] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"108.40411ms","start":"2026-10-15T15:09:14.343598Z","end":"2026-10-15T15:09:14.452002Z","steps":["trace[681680547] 'process raft request' (duration: 108.279177ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:19.808882Z","caller":"traceutil/trace.go:171","msg":"trace[159266814] transaction","detail":"{read_only:false; response_revision:26409; number_of_response:1; }","duration":"169.792277ms","start":"2026-10-15T15:09:19.639063Z","end":"2026-10-15T15:09:19.808855Z","steps":["trace[159266814] 'process raft request' (duration: 169.660454ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:20.185399Z","caller":"traceutil/trace.go:171","msg":"trace[586875889] transaction","detail":"{read_only:false; response_revision:26410; number_of_response:1; }","duration":"533.293412ms","start":"2026-10-15T15:09:19.652079Z","end":"2026-10-15T15:09:20.185373Z","steps":["trace[586875889] 'process raft request' (duration: 522.813072ms)","trace[586875889] 'compare' (duration: 10.012019ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:09:20.185546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:19.652054Z","time spent":"533.420985ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:09:20.185983Z","caller":"traceutil/trace.go:171","msg":"trace[1314490332] linearizableReadLoop","detail":"{readStateIndex:27273; appliedIndex:27272; }","duration":"491.177336ms","start":"2026-10-15T15:09:19.694785Z","end":"2026-10-15T15:09:20.185962Z","steps":["trace[1314490332] 'read index received' (duration: 114.141237ms)","trace[1314490332] 'applied index is now lower than readState.Index' (duration: 377.034999ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:09:20.186034Z","caller":"traceutil/trace.go:171","msg":"trace[1878136708] transaction","detail":"{read_only:false; response_revision:26411; number_of_response:1; }","duration":"418.006087ms","start":"2026-10-15T15:09:19.767983Z","end":"2026-10-15T15:09:20.185989Z","steps":["trace[1878136708] 'process raft request' (duration: 417.29696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:20.186169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.35929ms","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:8"} {"level":"info","ts":"2026-10-15T15:09:20.186225Z","caller":"traceutil/trace.go:171","msg":"trace[1655493040] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26412; }","duration":"491.473843ms","start":"2026-10-15T15:09:19.694741Z","end":"2026-10-15T15:09:20.186215Z","steps":["trace[1655493040] 'agreement among raft nodes before linearized reading' (duration: 491.3662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:20.186255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:19.694721Z","time spent":"491.525444ms","remote":"127.0.0.1:58190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:09:20.186253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:19.767961Z","time spent":"418.156561ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:09:20.186043Z","caller":"traceutil/trace.go:171","msg":"trace[582099267] transaction","detail":"{read_only:false; response_revision:26412; number_of_response:1; }","duration":"407.831605ms","start":"2026-10-15T15:09:19.778173Z","end":"2026-10-15T15:09:20.186005Z","steps":["trace[582099267] 'process raft request' (duration: 407.713022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:20.186985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:19.778156Z","time spent":"408.206373ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:09:20.496563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.352702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:09:20.496659Z","caller":"traceutil/trace.go:171","msg":"trace[925803136] linearizableReadLoop","detail":"{readStateIndex:27276; appliedIndex:27275; }","duration":"310.601732ms","start":"2026-10-15T15:09:20.186041Z","end":"2026-10-15T15:09:20.496643Z","steps":["trace[925803136] 'read index received' (duration: 149.962483ms)","trace[925803136] 'applied index is now lower than readState.Index' (duration: 160.638189ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:09:20.496769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.315985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T15:09:20.496797Z","caller":"traceutil/trace.go:171","msg":"trace[847094209] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26413; }","duration":"775.369586ms","start":"2026-10-15T15:09:19.721415Z","end":"2026-10-15T15:09:20.496785Z","steps":["trace[847094209] 'agreement among raft nodes before linearized reading' (duration: 775.267154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:20.496872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:19.721402Z","time spent":"775.413517ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T15:09:20.496848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.870609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:09:20.496962Z","caller":"traceutil/trace.go:171","msg":"trace[288784032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26413; }","duration":"594.025093ms","start":"2026-10-15T15:09:19.902919Z","end":"2026-10-15T15:09:20.496944Z","steps":["trace[288784032] 'agreement among raft nodes before linearized reading' (duration: 593.799498ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:20.496974Z","caller":"traceutil/trace.go:171","msg":"trace[2072645029] transaction","detail":"{read_only:false; response_revision:26413; number_of_response:1; }","duration":"348.6367ms","start":"2026-10-15T15:09:20.148127Z","end":"2026-10-15T15:09:20.496764Z","steps":["trace[2072645029] 'process raft request' (duration: 187.991141ms)","trace[2072645029] 'compare' (duration: 160.038304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:09:20.496996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.880072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-10-15T15:09:20.497091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:19.902893Z","time spent":"594.141616ms","remote":"127.0.0.1:55794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T15:09:20.497131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:20.148099Z","time spent":"348.950487ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:09:20.497236Z","caller":"traceutil/trace.go:171","msg":"trace[1463236070] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26413; }","duration":"646.060606ms","start":"2026-10-15T15:09:19.851016Z","end":"2026-10-15T15:09:20.497077Z","steps":["trace[1463236070] 'agreement among raft nodes before linearized reading' (duration: 645.79733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:20.497177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.684943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-15T15:09:20.497281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:19.851001Z","time spent":"646.270512ms","remote":"127.0.0.1:56258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-10-15T15:09:20.497379Z","caller":"traceutil/trace.go:171","msg":"trace[1121602386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26413; }","duration":"672.851747ms","start":"2026-10-15T15:09:19.824424Z","end":"2026-10-15T15:09:20.497275Z","steps":["trace[1121602386] 'agreement among raft nodes before linearized reading' (duration: 672.444367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:20.497377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.8506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:09:20.497432Z","caller":"traceutil/trace.go:171","msg":"trace[954006383] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:26413; }","duration":"344.940842ms","start":"2026-10-15T15:09:20.152478Z","end":"2026-10-15T15:09:20.497419Z","steps":["trace[954006383] 'agreement among raft nodes before linearized reading' (duration: 344.86672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:20.49743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:19.824405Z","time spent":"673.01575ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T15:09:20.497454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:20.152456Z","time spent":"344.990883ms","remote":"127.0.0.1:54386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:09:20.808109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.359148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:09:20.808342Z","caller":"traceutil/trace.go:171","msg":"trace[1653880522] linearizableReadLoop","detail":"{readStateIndex:27280; appliedIndex:27278; }","duration":"296.304929ms","start":"2026-10-15T15:09:20.512022Z","end":"2026-10-15T15:09:20.808327Z","steps":["trace[1653880522] 'read index received' (duration: 136.540252ms)","trace[1653880522] 'applied index is now lower than readState.Index' (duration: 159.763857ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:09:20.808467Z","caller":"traceutil/trace.go:171","msg":"trace[1364233116] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"303.314537ms","start":"2026-10-15T15:09:20.505142Z","end":"2026-10-15T15:09:20.808457Z","steps":["trace[1364233116] 'process raft request' (duration: 303.096671ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:20.808486Z","caller":"traceutil/trace.go:171","msg":"trace[958950329] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"304.69608ms","start":"2026-10-15T15:09:20.503759Z","end":"2026-10-15T15:09:20.808455Z","steps":["trace[958950329] 'process raft request' (duration: 144.891031ms)","trace[958950329] 'compare' (duration: 159.075011ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:09:20.808534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:20.505119Z","time spent":"303.380139ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:09:20.808637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:20.503746Z","time spent":"304.808522ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:09:20.808784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.333445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-10-15T15:09:20.808819Z","caller":"traceutil/trace.go:171","msg":"trace[1628386286] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26417; }","duration":"305.394996ms","start":"2026-10-15T15:09:20.503413Z","end":"2026-10-15T15:09:20.808808Z","steps":["trace[1628386286] 'agreement among raft nodes before linearized reading' (duration: 305.257663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:09:20.808834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:09:20.503397Z","time spent":"305.432847ms","remote":"127.0.0.1:56258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11860,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-15T15:09:20.808873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.291826ms","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-15T15:09:20.808962Z","caller":"traceutil/trace.go:171","msg":"trace[1746758814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26417; }","duration":"141.416009ms","start":"2026-10-15T15:09:20.667522Z","end":"2026-10-15T15:09:20.808938Z","steps":["trace[1746758814] 'agreement among raft nodes before linearized reading' (duration: 141.168863ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:09:20.913638Z","caller":"traceutil/trace.go:171","msg":"trace[568289015] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"100.330167ms","start":"2026-10-15T15:09:20.813283Z","end":"2026-10-15T15:09:20.913613Z","steps":["trace[568289015] 'process raft request' (duration: 96.543437ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:12:17.630352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25308} {"level":"info","ts":"2026-10-15T15:12:17.788721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25308,"took":"154.331249ms","hash":1822930790,"current-db-size-bytes":58814464,"current-db-size":"59 MB","current-db-size-in-use-bytes":37720064,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-15T15:12:17.788792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1822930790,"revision":25308,"compact-revision":22071} {"level":"info","ts":"2026-10-15T15:13:52.374506Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:13:52.412285Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T15:13:52.412495Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T15:17:17.637148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28149} {"level":"info","ts":"2026-10-15T15:17:17.777572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28149,"took":"136.096213ms","hash":1536927821,"current-db-size-bytes":58814464,"current-db-size":"59 MB","current-db-size-in-use-bytes":32706560,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T15:17:17.777639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1536927821,"revision":28149,"compact-revision":25308} {"level":"info","ts":"2026-10-15T15:22:17.645189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30612} {"level":"info","ts":"2026-10-15T15:22:17.793213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30612,"took":"143.042999ms","hash":913712945,"current-db-size-bytes":58814464,"current-db-size":"59 MB","current-db-size-in-use-bytes":30588928,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:22:17.793387Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":913712945,"revision":30612,"compact-revision":28149} {"level":"info","ts":"2026-10-15T15:26:30.664168Z","caller":"traceutil/trace.go:171","msg":"trace[742798475] transaction","detail":"{read_only:false; response_revision:34747; number_of_response:1; }","duration":"103.592686ms","start":"2026-10-15T15:26:30.560541Z","end":"2026-10-15T15:26:30.664134Z","steps":["trace[742798475] 'process raft request' (duration: 64.588584ms)","trace[742798475] 'compare' (duration: 38.794067ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:26:30.986039Z","caller":"traceutil/trace.go:171","msg":"trace[1749624646] transaction","detail":"{read_only:false; response_revision:34753; number_of_response:1; }","duration":"102.07428ms","start":"2026-10-15T15:26:30.883928Z","end":"2026-10-15T15:26:30.986002Z","steps":["trace[1749624646] 'process raft request' (duration: 70.510266ms)","trace[1749624646] 'compare' (duration: 31.185665ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:26:34.272399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.333983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:26:34.272702Z","caller":"traceutil/trace.go:171","msg":"trace[1342224049] transaction","detail":"{read_only:false; response_revision:34772; number_of_response:1; }","duration":"133.478901ms","start":"2026-10-15T15:26:34.139187Z","end":"2026-10-15T15:26:34.272666Z","steps":["trace[1342224049] 'process raft request' (duration: 13.765519ms)","trace[1342224049] 'compare' (duration: 118.915083ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:26:34.709255Z","caller":"traceutil/trace.go:171","msg":"trace[1951307870] transaction","detail":"{read_only:false; response_revision:34775; number_of_response:1; }","duration":"106.994498ms","start":"2026-10-15T15:26:34.602229Z","end":"2026-10-15T15:26:34.709224Z","steps":["trace[1951307870] 'process raft request' (duration: 106.760112ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:26:34.883861Z","caller":"traceutil/trace.go:171","msg":"trace[462963603] transaction","detail":"{read_only:false; response_revision:34778; number_of_response:1; }","duration":"135.835927ms","start":"2026-10-15T15:26:34.747995Z","end":"2026-10-15T15:26:34.883831Z","steps":["trace[462963603] 'process raft request' (duration: 72.820701ms)","trace[462963603] 'compare' (duration: 62.827112ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:26:34.88406Z","caller":"traceutil/trace.go:171","msg":"trace[1517150363] linearizableReadLoop","detail":"{readStateIndex:35872; appliedIndex:35870; }","duration":"123.610475ms","start":"2026-10-15T15:26:34.760412Z","end":"2026-10-15T15:26:34.884022Z","steps":["trace[1517150363] 'read index received' (duration: 17.530699ms)","trace[1517150363] 'applied index is now lower than readState.Index' (duration: 106.074706ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:26:34.884202Z","caller":"traceutil/trace.go:171","msg":"trace[1102268095] transaction","detail":"{read_only:false; response_revision:34779; number_of_response:1; }","duration":"134.917105ms","start":"2026-10-15T15:26:34.749262Z","end":"2026-10-15T15:26:34.884179Z","steps":["trace[1102268095] 'process raft request' (duration: 134.524076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:26:34.884301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.874842ms","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-15T15:26:34.884368Z","caller":"traceutil/trace.go:171","msg":"trace[105495614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:34779; }","duration":"124.014534ms","start":"2026-10-15T15:26:34.760332Z","end":"2026-10-15T15:26:34.884346Z","steps":["trace[105495614] 'agreement among raft nodes before linearized reading' (duration: 123.81423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:26:35.515945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.110364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:26:35.51603Z","caller":"traceutil/trace.go:171","msg":"trace[1300803276] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:34784; }","duration":"259.266578ms","start":"2026-10-15T15:26:35.256745Z","end":"2026-10-15T15:26:35.516012Z","steps":["trace[1300803276] 'count revisions from in-memory index tree' (duration: 259.017582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:26:35.516442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.061069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:26:35.516578Z","caller":"traceutil/trace.go:171","msg":"trace[528970053] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:34784; }","duration":"263.321855ms","start":"2026-10-15T15:26:35.253224Z","end":"2026-10-15T15:26:35.516545Z","steps":["trace[528970053] 'count revisions from in-memory index tree' (duration: 262.929146ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:27:17.652653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32866} {"level":"info","ts":"2026-10-15T15:27:17.8229Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32866,"took":"165.807003ms","hash":2364599486,"current-db-size-bytes":59854848,"current-db-size":"60 MB","current-db-size-in-use-bytes":30261248,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:27:17.822975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2364599486,"revision":32866,"compact-revision":30612} {"level":"info","ts":"2026-10-15T15:32:17.659162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35090} {"level":"info","ts":"2026-10-15T15:32:17.792928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35090,"took":"130.053079ms","hash":1561819559,"current-db-size-bytes":59854848,"current-db-size":"60 MB","current-db-size-in-use-bytes":32116736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:32:17.793002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1561819559,"revision":35090,"compact-revision":32866} {"level":"info","ts":"2026-10-15T15:35:19.974015Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:35:19.97955Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T15:35:19.979642Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T15:37:17.668068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37384} {"level":"info","ts":"2026-10-15T15:37:17.799822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37384,"took":"127.648272ms","hash":4496614,"current-db-size-bytes":59854848,"current-db-size":"60 MB","current-db-size-in-use-bytes":32342016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:37:17.799913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4496614,"revision":37384,"compact-revision":35090} {"level":"info","ts":"2026-10-15T15:42:17.69416Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39662} {"level":"info","ts":"2026-10-15T15:42:17.838492Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39662,"took":"141.416151ms","hash":1477226487,"current-db-size-bytes":59854848,"current-db-size":"60 MB","current-db-size-in-use-bytes":31264768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:42:17.838559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1477226487,"revision":39662,"compact-revision":37384}