{"level":"warn","ts":"2026-04-13T17:11:11.638824Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:11:11.638956Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.137:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.137:2380","--initial-cluster=instance=https://199.19.213.137:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.137:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.137: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-04-13T17:11:11.63904Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T17:11:11.639055Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-04-13T17:11:11.639075Z","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-04-13T17:11:11.639746Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"]} {"level":"info","ts":"2026-04-13T17:11:11.639833Z","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://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.137: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-04-13T17:11:11.643486Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.432353ms"} {"level":"info","ts":"2026-04-13T17:11:11.649964Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2ba8e3c2b0588654","cluster-id":"97694c3f9947e4ca"} {"level":"info","ts":"2026-04-13T17:11:11.65005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T17:11:11.65007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 0"} {"level":"info","ts":"2026-04-13T17:11:11.650081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2ba8e3c2b0588654 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T17:11:11.650093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 1"} {"level":"info","ts":"2026-04-13T17:11:11.65013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"warn","ts":"2026-04-13T17:11:11.657493Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T17:11:11.660049Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T17:11:11.66142Z","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-04-13T17:11:11.664037Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2ba8e3c2b0588654","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T17:11:11.664287Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2ba8e3c2b0588654","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T17:11:11.664363Z","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-04-13T17:11:11.664412Z","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-04-13T17:11:11.664427Z","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-04-13T17:11:11.665308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"info","ts":"2026-04-13T17:11:11.66598Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","added-peer-id":"2ba8e3c2b0588654","added-peer-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-04-13T17:11:11.669773Z","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-04-13T17:11:11.66992Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-04-13T17:11:11.66997Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-04-13T17:11:11.670042Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2ba8e3c2b0588654","initial-advertise-peer-urls":["https://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T17:11:11.670091Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T17:11:12.051461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T17:11:12.051527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T17:11:12.051545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgPreVoteResp from 2ba8e3c2b0588654 at term 1"} {"level":"info","ts":"2026-04-13T17:11:12.051556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became candidate at term 2"} {"level":"info","ts":"2026-04-13T17:11:12.051571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgVoteResp from 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-04-13T17:11:12.051579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became leader at term 2"} {"level":"info","ts":"2026-04-13T17:11:12.051586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2ba8e3c2b0588654 elected leader 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-04-13T17:11:12.059606Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2ba8e3c2b0588654","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.137:2379]}","request-path":"/0/members/2ba8e3c2b0588654/attributes","cluster-id":"97694c3f9947e4ca","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T17:11:12.05972Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:11:12.05968Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:11:12.059873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T17:11:12.060143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T17:11:12.060242Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T17:11:12.060743Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:11:12.060901Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:11:12.060948Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T17:11:12.061503Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T17:11:12.064418Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.137:2379"} {"level":"info","ts":"2026-04-13T17:11:29.317512Z","caller":"traceutil/trace.go:171","msg":"trace[1587348654] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"207.871695ms","start":"2026-04-13T17:11:29.109607Z","end":"2026-04-13T17:11:29.317479Z","steps":["trace[1587348654] 'process raft request' (duration: 207.66591ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:49.377439Z","caller":"traceutil/trace.go:171","msg":"trace[1472451096] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"152.074331ms","start":"2026-04-13T17:11:49.225338Z","end":"2026-04-13T17:11:49.377412Z","steps":["trace[1472451096] 'process raft request' (duration: 151.821174ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:49.671978Z","caller":"traceutil/trace.go:171","msg":"trace[716236594] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"229.061077ms","start":"2026-04-13T17:11:49.442879Z","end":"2026-04-13T17:11:49.67194Z","steps":["trace[716236594] 'process raft request' (duration: 172.597125ms)","trace[716236594] 'compare' (duration: 56.0487ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:50.200119Z","caller":"traceutil/trace.go:171","msg":"trace[1558695731] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"340.937873ms","start":"2026-04-13T17:11:49.859148Z","end":"2026-04-13T17:11:50.200086Z","steps":["trace[1558695731] 'read index received' (duration: 273.703085ms)","trace[1558695731] 'applied index is now lower than readState.Index' (duration: 67.232778ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:11:50.200254Z","caller":"traceutil/trace.go:171","msg":"trace[868381976] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"463.278555ms","start":"2026-04-13T17:11:49.736943Z","end":"2026-04-13T17:11:50.200222Z","steps":["trace[868381976] 'process raft request' (duration: 395.976015ms)","trace[868381976] 'compare' (duration: 66.92085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:11:50.200426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.1872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:11:50.200526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.353675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T17:11:50.200589Z","caller":"traceutil/trace.go:171","msg":"trace[92276963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"189.399516ms","start":"2026-04-13T17:11:50.011158Z","end":"2026-04-13T17:11:50.200557Z","steps":["trace[92276963] 'agreement among raft nodes before linearized reading' (duration: 189.136529ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:50.200632Z","caller":"traceutil/trace.go:171","msg":"trace[1472689582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"341.474448ms","start":"2026-04-13T17:11:49.8591Z","end":"2026-04-13T17:11:50.200575Z","steps":["trace[1472689582] 'agreement among raft nodes before linearized reading' (duration: 341.127268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:50.2006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.370356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-13T17:11:50.200635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.061508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T17:11:50.200727Z","caller":"traceutil/trace.go:171","msg":"trace[1563525710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"341.56225ms","start":"2026-04-13T17:11:49.859148Z","end":"2026-04-13T17:11:50.200711Z","steps":["trace[1563525710] 'agreement among raft nodes before linearized reading' (duration: 341.127039ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:11:50.200757Z","caller":"traceutil/trace.go:171","msg":"trace[518655405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"157.254083ms","start":"2026-04-13T17:11:50.04347Z","end":"2026-04-13T17:11:50.200724Z","steps":["trace[518655405] 'agreement among raft nodes before linearized reading' (duration: 156.831271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:11:50.200717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:49.85907Z","time spent":"341.637952ms","remote":"127.0.0.1:53122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T17:11:50.200816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:49.859124Z","time spent":"341.679964ms","remote":"127.0.0.1:53122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T17:11:50.201027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:11:49.736908Z","time spent":"463.46622ms","remote":"127.0.0.1:53382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T17:11:52.4018Z","caller":"traceutil/trace.go:171","msg":"trace[2137640770] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"103.111175ms","start":"2026-04-13T17:11:52.298665Z","end":"2026-04-13T17:11:52.401776Z","steps":["trace[2137640770] 'process raft request' (duration: 102.971971ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:05.505473Z","caller":"traceutil/trace.go:171","msg":"trace[2093445738] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"182.480906ms","start":"2026-04-13T17:12:05.322963Z","end":"2026-04-13T17:12:05.505444Z","steps":["trace[2093445738] 'process raft request' (duration: 182.26608ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:05.519676Z","caller":"traceutil/trace.go:171","msg":"trace[2009797270] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"170.431995ms","start":"2026-04-13T17:12:05.34922Z","end":"2026-04-13T17:12:05.519652Z","steps":["trace[2009797270] 'process raft request' (duration: 170.25125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:12:56.753039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.661584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:12:56.753204Z","caller":"traceutil/trace.go:171","msg":"trace[787964485] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"153.504606ms","start":"2026-04-13T17:12:56.599666Z","end":"2026-04-13T17:12:56.753171Z","steps":["trace[787964485] 'compare' (duration: 148.477689ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:12:56.753346Z","caller":"traceutil/trace.go:171","msg":"trace[1801086757] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1444; }","duration":"143.363159ms","start":"2026-04-13T17:12:56.609948Z","end":"2026-04-13T17:12:56.753311Z","steps":["trace[1801086757] 'read index received' (duration: 67.061µs)","trace[1801086757] 'applied index is now lower than readState.Index' (duration: 143.293188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:12:56.753609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.645687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T17:12:56.753647Z","caller":"traceutil/trace.go:171","msg":"trace[55088148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1400; }","duration":"143.7296ms","start":"2026-04-13T17:12:56.609905Z","end":"2026-04-13T17:12:56.753634Z","steps":["trace[55088148] 'agreement among raft nodes before linearized reading' (duration: 143.529944ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:21.865351Z","caller":"traceutil/trace.go:171","msg":"trace[1092889303] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"143.806351ms","start":"2026-04-13T17:13:21.721507Z","end":"2026-04-13T17:13:21.865314Z","steps":["trace[1092889303] 'process raft request' (duration: 143.565424ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:22.118228Z","caller":"traceutil/trace.go:171","msg":"trace[470925309] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"107.058318ms","start":"2026-04-13T17:13:22.011138Z","end":"2026-04-13T17:13:22.118196Z","steps":["trace[470925309] 'read index received' (duration: 106.847622ms)","trace[470925309] 'applied index is now lower than readState.Index' (duration: 209.355µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:13:22.118343Z","caller":"traceutil/trace.go:171","msg":"trace[201165130] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"114.11841ms","start":"2026-04-13T17:13:22.004196Z","end":"2026-04-13T17:13:22.118314Z","steps":["trace[201165130] 'process raft request' (duration: 113.77014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:22.118429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.266303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:13:22.118488Z","caller":"traceutil/trace.go:171","msg":"trace[1061276969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1815; }","duration":"107.368656ms","start":"2026-04-13T17:13:22.011103Z","end":"2026-04-13T17:13:22.118472Z","steps":["trace[1061276969] 'agreement among raft nodes before linearized reading' (duration: 107.263533ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:13:30.378986Z","caller":"traceutil/trace.go:171","msg":"trace[1599078831] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"118.237642ms","start":"2026-04-13T17:13:30.260719Z","end":"2026-04-13T17:13:30.378957Z","steps":["trace[1599078831] 'process raft request' (duration: 118.056587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:13:30.556435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.28877ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T17:13:30.556573Z","caller":"traceutil/trace.go:171","msg":"trace[1814993311] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"212.013046ms","start":"2026-04-13T17:13:30.344539Z","end":"2026-04-13T17:13:30.556552Z","steps":["trace[1814993311] 'read index received' (duration: 34.320258ms)","trace[1814993311] 'applied index is now lower than readState.Index' (duration: 177.690788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:13:30.556901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.324895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-13T17:13:30.557021Z","caller":"traceutil/trace.go:171","msg":"trace[940965837] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"212.50401ms","start":"2026-04-13T17:13:30.344489Z","end":"2026-04-13T17:13:30.556993Z","steps":["trace[940965837] 'agreement among raft nodes before linearized reading' (duration: 212.13203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:07.28221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.516419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:14:07.282315Z","caller":"traceutil/trace.go:171","msg":"trace[996507467] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"204.205196ms","start":"2026-04-13T17:14:07.078095Z","end":"2026-04-13T17:14:07.2823Z","steps":["trace[996507467] 'read index received' (duration: 78.561663ms)","trace[996507467] 'applied index is now lower than readState.Index' (duration: 125.642363ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:14:07.28238Z","caller":"traceutil/trace.go:171","msg":"trace[1215073952] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"257.364219ms","start":"2026-04-13T17:14:07.025005Z","end":"2026-04-13T17:14:07.282369Z","steps":["trace[1215073952] 'process raft request' (duration: 131.606194ms)","trace[1215073952] 'compare' (duration: 125.391426ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:14:07.282492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.393401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:14:07.28253Z","caller":"traceutil/trace.go:171","msg":"trace[249875450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2106; }","duration":"204.456213ms","start":"2026-04-13T17:14:07.078065Z","end":"2026-04-13T17:14:07.282521Z","steps":["trace[249875450] 'agreement among raft nodes before linearized reading' (duration: 204.395241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:07.346934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.352964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T17:14:07.347094Z","caller":"traceutil/trace.go:171","msg":"trace[1219077713] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"174.766527ms","start":"2026-04-13T17:14:07.172306Z","end":"2026-04-13T17:14:07.347072Z","steps":["trace[1219077713] 'process raft request' (duration: 174.268393ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:07.347113Z","caller":"traceutil/trace.go:171","msg":"trace[783935613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2107; }","duration":"213.57823ms","start":"2026-04-13T17:14:07.133513Z","end":"2026-04-13T17:14:07.347091Z","steps":["trace[783935613] 'agreement among raft nodes before linearized reading' (duration: 213.245461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:07.347302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.743768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T17:14:07.347449Z","caller":"traceutil/trace.go:171","msg":"trace[1464769151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2107; }","duration":"166.919593ms","start":"2026-04-13T17:14:07.180501Z","end":"2026-04-13T17:14:07.34742Z","steps":["trace[1464769151] 'agreement among raft nodes before linearized reading' (duration: 166.618675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:14:07.347001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.263015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-13T17:14:07.347595Z","caller":"traceutil/trace.go:171","msg":"trace[142021520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2107; }","duration":"117.913342ms","start":"2026-04-13T17:14:07.229652Z","end":"2026-04-13T17:14:07.347566Z","steps":["trace[142021520] 'agreement among raft nodes before linearized reading' (duration: 117.139271ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:07.472407Z","caller":"traceutil/trace.go:171","msg":"trace[1598811075] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"119.27406ms","start":"2026-04-13T17:14:07.353098Z","end":"2026-04-13T17:14:07.472372Z","steps":["trace[1598811075] 'process raft request' (duration: 119.000283ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:07.474905Z","caller":"traceutil/trace.go:171","msg":"trace[1594316913] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"120.249806ms","start":"2026-04-13T17:14:07.354632Z","end":"2026-04-13T17:14:07.474882Z","steps":["trace[1594316913] 'process raft request' (duration: 120.203235ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:14:07.475109Z","caller":"traceutil/trace.go:171","msg":"trace[642507686] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"121.107629ms","start":"2026-04-13T17:14:07.353958Z","end":"2026-04-13T17:14:07.475066Z","steps":["trace[642507686] 'process raft request' (duration: 120.706038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:01.807042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.9095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-13T17:15:01.807255Z","caller":"traceutil/trace.go:171","msg":"trace[471319082] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2576; }","duration":"116.167037ms","start":"2026-04-13T17:15:01.691045Z","end":"2026-04-13T17:15:01.807212Z","steps":["trace[471319082] 'range keys from in-memory index tree' (duration: 115.683404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:01.80712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.184461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:15:01.807468Z","caller":"traceutil/trace.go:171","msg":"trace[1679725354] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2576; }","duration":"136.587252ms","start":"2026-04-13T17:15:01.670854Z","end":"2026-04-13T17:15:01.807441Z","steps":["trace[1679725354] 'count revisions from in-memory index tree' (duration: 136.094548ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:35.114531Z","caller":"traceutil/trace.go:171","msg":"trace[723093885] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2884; }","duration":"111.990613ms","start":"2026-04-13T17:15:35.00251Z","end":"2026-04-13T17:15:35.114501Z","steps":["trace[723093885] 'read index received' (duration: 111.835059ms)","trace[723093885] 'applied index is now lower than readState.Index' (duration: 153.444µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:15:35.114605Z","caller":"traceutil/trace.go:171","msg":"trace[500348106] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"148.983249ms","start":"2026-04-13T17:15:34.965567Z","end":"2026-04-13T17:15:35.114551Z","steps":["trace[500348106] 'process raft request' (duration: 148.704611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:15:35.114737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.166369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T17:15:35.114803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.011706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:15:35.114831Z","caller":"traceutil/trace.go:171","msg":"trace[1223289302] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2776; }","duration":"112.378414ms","start":"2026-04-13T17:15:35.002433Z","end":"2026-04-13T17:15:35.114811Z","steps":["trace[1223289302] 'agreement among raft nodes before linearized reading' (duration: 112.146338ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:35.114865Z","caller":"traceutil/trace.go:171","msg":"trace[714081592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2776; }","duration":"104.13349ms","start":"2026-04-13T17:15:35.010718Z","end":"2026-04-13T17:15:35.114851Z","steps":["trace[714081592] 'agreement among raft nodes before linearized reading' (duration: 104.008367ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:15:35.254666Z","caller":"traceutil/trace.go:171","msg":"trace[1891787261] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"133.483058ms","start":"2026-04-13T17:15:35.12114Z","end":"2026-04-13T17:15:35.254623Z","steps":["trace[1891787261] 'process raft request' (duration: 129.756076ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:18:11.019161Z","caller":"traceutil/trace.go:171","msg":"trace[214814898] linearizableReadLoop","detail":"{readStateIndex:3945; appliedIndex:3944; }","duration":"125.852026ms","start":"2026-04-13T17:18:10.89328Z","end":"2026-04-13T17:18:11.019132Z","steps":["trace[214814898] 'read index received' (duration: 125.660561ms)","trace[214814898] 'applied index is now lower than readState.Index' (duration: 189.915µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:18:11.019202Z","caller":"traceutil/trace.go:171","msg":"trace[650688270] transaction","detail":"{read_only:false; response_revision:3798; number_of_response:1; }","duration":"130.78612ms","start":"2026-04-13T17:18:10.888396Z","end":"2026-04-13T17:18:11.019182Z","steps":["trace[650688270] 'process raft request' (duration: 130.513942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:11.019434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.111183ms","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-04-13T17:18:11.019493Z","caller":"traceutil/trace.go:171","msg":"trace[1492909860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3798; }","duration":"126.216856ms","start":"2026-04-13T17:18:10.893262Z","end":"2026-04-13T17:18:11.019479Z","steps":["trace[1492909860] 'agreement among raft nodes before linearized reading' (duration: 125.99046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:18:11.019944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.422724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-13T17:18:11.021243Z","caller":"traceutil/trace.go:171","msg":"trace[1513515695] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3798; }","duration":"115.963658ms","start":"2026-04-13T17:18:10.904426Z","end":"2026-04-13T17:18:11.020389Z","steps":["trace[1513515695] 'agreement among raft nodes before linearized reading' (duration: 114.936051ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:44.763968Z","caller":"traceutil/trace.go:171","msg":"trace[1049146047] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"112.768263ms","start":"2026-04-13T17:19:44.651168Z","end":"2026-04-13T17:19:44.763936Z","steps":["trace[1049146047] 'process raft request' (duration: 112.709862ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:44.763983Z","caller":"traceutil/trace.go:171","msg":"trace[495631112] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"114.671394ms","start":"2026-04-13T17:19:44.649273Z","end":"2026-04-13T17:19:44.763945Z","steps":["trace[495631112] 'process raft request' (duration: 114.429408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:19:55.89435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.709356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:19:55.894509Z","caller":"traceutil/trace.go:171","msg":"trace[1132881009] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:5239; }","duration":"107.939642ms","start":"2026-04-13T17:19:55.786541Z","end":"2026-04-13T17:19:55.89448Z","steps":["trace[1132881009] 'range keys from in-memory index tree' (duration: 107.615603ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:19:56.00873Z","caller":"traceutil/trace.go:171","msg":"trace[872020266] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"104.315653ms","start":"2026-04-13T17:19:55.904381Z","end":"2026-04-13T17:19:56.008696Z","steps":["trace[872020266] 'process raft request' (duration: 103.614224ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:20:16.306623Z","caller":"traceutil/trace.go:171","msg":"trace[588067201] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"110.017242ms","start":"2026-04-13T17:20:16.196578Z","end":"2026-04-13T17:20:16.306595Z","steps":["trace[588067201] 'process raft request' (duration: 109.780095ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:21:12.247662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2979} {"level":"info","ts":"2026-04-13T17:21:12.318036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2979,"took":"68.337248ms","hash":3494052062,"current-db-size-bytes":58122240,"current-db-size":"58 MB","current-db-size-in-use-bytes":33554432,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-13T17:21:12.318205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3494052062,"revision":2979,"compact-revision":-1} {"level":"info","ts":"2026-04-13T17:26:01.463053Z","caller":"traceutil/trace.go:171","msg":"trace[1217231730] transaction","detail":"{read_only:false; response_revision:8200; number_of_response:1; }","duration":"216.296771ms","start":"2026-04-13T17:26:01.246705Z","end":"2026-04-13T17:26:01.463002Z","steps":["trace[1217231730] 'process raft request' (duration: 215.995273ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:12.254413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6040} {"level":"info","ts":"2026-04-13T17:26:12.345697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6040,"took":"89.293852ms","hash":132684727,"current-db-size-bytes":58122240,"current-db-size":"58 MB","current-db-size-in-use-bytes":23744512,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-13T17:26:12.345765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":132684727,"revision":6040,"compact-revision":2979} {"level":"info","ts":"2026-04-13T17:26:12.985327Z","caller":"traceutil/trace.go:171","msg":"trace[1948487601] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"127.914944ms","start":"2026-04-13T17:26:12.857373Z","end":"2026-04-13T17:26:12.985288Z","steps":["trace[1948487601] 'process raft request' (duration: 127.750039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:13.351143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.751002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T17:26:13.351248Z","caller":"traceutil/trace.go:171","msg":"trace[1853929910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8261; }","duration":"278.919017ms","start":"2026-04-13T17:26:13.072309Z","end":"2026-04-13T17:26:13.351228Z","steps":["trace[1853929910] 'range keys from in-memory index tree' (duration: 278.538157ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:14.190752Z","caller":"traceutil/trace.go:171","msg":"trace[567014496] linearizableReadLoop","detail":"{readStateIndex:8563; appliedIndex:8562; }","duration":"285.735451ms","start":"2026-04-13T17:26:13.904996Z","end":"2026-04-13T17:26:14.190731Z","steps":["trace[567014496] 'read index received' (duration: 238.663459ms)","trace[567014496] 'applied index is now lower than readState.Index' (duration: 47.071292ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:14.190864Z","caller":"traceutil/trace.go:171","msg":"trace[1004053110] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"348.956903ms","start":"2026-04-13T17:26:13.841896Z","end":"2026-04-13T17:26:14.190853Z","steps":["trace[1004053110] 'process raft request' (duration: 301.747528ms)","trace[1004053110] 'compare' (duration: 47.00411ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:26:14.190948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:13.841867Z","time spent":"349.013644ms","remote":"127.0.0.1:53122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:26:14.191089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.101191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-13T17:26:14.191138Z","caller":"traceutil/trace.go:171","msg":"trace[734645362] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8268; }","duration":"286.179633ms","start":"2026-04-13T17:26:13.904951Z","end":"2026-04-13T17:26:14.191131Z","steps":["trace[734645362] 'agreement among raft nodes before linearized reading' (duration: 286.07623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:14.19138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.731754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T17:26:14.19142Z","caller":"traceutil/trace.go:171","msg":"trace[300092373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8268; }","duration":"179.772475ms","start":"2026-04-13T17:26:14.011642Z","end":"2026-04-13T17:26:14.191414Z","steps":["trace[300092373] 'agreement among raft nodes before linearized reading' (duration: 179.716903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:14.191339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.715028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:26:14.191515Z","caller":"traceutil/trace.go:171","msg":"trace[1550710921] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8268; }","duration":"228.958125ms","start":"2026-04-13T17:26:13.962527Z","end":"2026-04-13T17:26:14.191485Z","steps":["trace[1550710921] 'agreement among raft nodes before linearized reading' (duration: 228.697138ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:42.946894Z","caller":"traceutil/trace.go:171","msg":"trace[1624816135] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"391.197911ms","start":"2026-04-13T17:26:42.555659Z","end":"2026-04-13T17:26:42.946857Z","steps":["trace[1624816135] 'process raft request' (duration: 391.030666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:42.947068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T17:26:42.555641Z","time spent":"391.343715ms","remote":"127.0.0.1:53122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T17:26:56.223252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.389134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qqj2h\" ","response":"range_response_count:1 size:4673"} {"level":"warn","ts":"2026-04-13T17:26:56.223519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.459756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-13T17:26:56.223644Z","caller":"traceutil/trace.go:171","msg":"trace[743696100] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8588; }","duration":"119.62107ms","start":"2026-04-13T17:26:56.103995Z","end":"2026-04-13T17:26:56.223616Z","steps":["trace[743696100] 'range keys from in-memory index tree' (duration: 119.128887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:56.223662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.002425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-qqj2h\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-04-13T17:26:56.223769Z","caller":"traceutil/trace.go:171","msg":"trace[2076538553] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-qqj2h; range_end:; response_count:1; response_revision:8588; }","duration":"122.157479ms","start":"2026-04-13T17:26:56.101589Z","end":"2026-04-13T17:26:56.223747Z","steps":["trace[2076538553] 'range keys from in-memory index tree' (duration: 121.788739ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:56.223563Z","caller":"traceutil/trace.go:171","msg":"trace[117214360] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qqj2h; range_end:; response_count:1; response_revision:8588; }","duration":"119.740953ms","start":"2026-04-13T17:26:56.10379Z","end":"2026-04-13T17:26:56.223531Z","steps":["trace[117214360] 'range keys from in-memory index tree' (duration: 119.309422ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:56.35744Z","caller":"traceutil/trace.go:171","msg":"trace[435757354] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8898; }","duration":"123.030273ms","start":"2026-04-13T17:26:56.234376Z","end":"2026-04-13T17:26:56.357407Z","steps":["trace[435757354] 'read index received' (duration: 122.652053ms)","trace[435757354] 'applied index is now lower than readState.Index' (duration: 376.76µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:56.357587Z","caller":"traceutil/trace.go:171","msg":"trace[2146994788] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"124.992837ms","start":"2026-04-13T17:26:56.232498Z","end":"2026-04-13T17:26:56.357491Z","steps":["trace[2146994788] 'process raft request' (duration: 124.601406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:56.357947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.530877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-qqj2h\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-04-13T17:26:56.358008Z","caller":"traceutil/trace.go:171","msg":"trace[1045824107] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-qqj2h; range_end:; response_count:1; response_revision:8590; }","duration":"123.64635ms","start":"2026-04-13T17:26:56.234343Z","end":"2026-04-13T17:26:56.357989Z","steps":["trace[1045824107] 'agreement among raft nodes before linearized reading' (duration: 123.190547ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:56.87491Z","caller":"traceutil/trace.go:171","msg":"trace[1652365568] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"122.146219ms","start":"2026-04-13T17:26:56.752728Z","end":"2026-04-13T17:26:56.874874Z","steps":["trace[1652365568] 'process raft request' (duration: 121.986185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:57.01565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.718214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T17:26:57.015777Z","caller":"traceutil/trace.go:171","msg":"trace[1576721449] linearizableReadLoop","detail":"{readStateIndex:8905; appliedIndex:8904; }","duration":"138.722271ms","start":"2026-04-13T17:26:56.877036Z","end":"2026-04-13T17:26:57.015759Z","steps":["trace[1576721449] 'read index received' (duration: 26.887373ms)","trace[1576721449] 'applied index is now lower than readState.Index' (duration: 111.833418ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:57.016066Z","caller":"traceutil/trace.go:171","msg":"trace[2055397876] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"254.545938ms","start":"2026-04-13T17:26:56.761475Z","end":"2026-04-13T17:26:57.016021Z","steps":["trace[2055397876] 'process raft request' (duration: 142.36664ms)","trace[2055397876] 'compare' (duration: 111.264093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T17:26:57.016317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.123952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T17:26:57.016494Z","caller":"traceutil/trace.go:171","msg":"trace[1773055822] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8596; }","duration":"139.41265ms","start":"2026-04-13T17:26:56.876988Z","end":"2026-04-13T17:26:57.016401Z","steps":["trace[1773055822] 'agreement among raft nodes before linearized reading' (duration: 138.999839ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:26:57.840959Z","caller":"traceutil/trace.go:171","msg":"trace[649469753] linearizableReadLoop","detail":"{readStateIndex:8910; appliedIndex:8909; }","duration":"158.737656ms","start":"2026-04-13T17:26:57.682188Z","end":"2026-04-13T17:26:57.840925Z","steps":["trace[649469753] 'read index received' (duration: 158.436838ms)","trace[649469753] 'applied index is now lower than readState.Index' (duration: 300.188µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T17:26:57.841077Z","caller":"traceutil/trace.go:171","msg":"trace[845044570] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"159.702643ms","start":"2026-04-13T17:26:57.681346Z","end":"2026-04-13T17:26:57.841049Z","steps":["trace[845044570] 'process raft request' (duration: 159.378694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T17:26:57.841532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.328732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-13T17:26:57.841586Z","caller":"traceutil/trace.go:171","msg":"trace[871509812] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8601; }","duration":"159.429295ms","start":"2026-04-13T17:26:57.682142Z","end":"2026-04-13T17:26:57.841571Z","steps":["trace[871509812] 'agreement among raft nodes before linearized reading' (duration: 158.955762ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T17:27:12.712232Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002368.wal"} {"level":"info","ts":"2026-04-13T17:28:56.861259Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2ba8e3c2b0588654","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-13T17:28:56.867806Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-13T17:28:56.86792Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-13T17:31:12.260811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8258} {"level":"info","ts":"2026-04-13T17:31:12.342974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8258,"took":"78.852322ms","hash":1353872626,"current-db-size-bytes":58122240,"current-db-size":"58 MB","current-db-size-in-use-bytes":24907776,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-13T17:31:12.343075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1353872626,"revision":8258,"compact-revision":6040} {"level":"info","ts":"2026-04-13T17:36:12.268121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10654} {"level":"info","ts":"2026-04-13T17:36:12.35073Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10654,"took":"80.195101ms","hash":1197891861,"current-db-size-bytes":58122240,"current-db-size":"58 MB","current-db-size-in-use-bytes":20623360,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-13T17:36:12.350831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1197891861,"revision":10654,"compact-revision":8258}