{"level":"warn","ts":"2026-06-10T01:16:55.784727Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T01:16:55.784868Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-06-10T01:16:55.784998Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T01:16:55.785017Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-10T01:16:55.785044Z","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-06-10T01:16:55.785942Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-06-10T01:16:55.786067Z","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.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-06-10T01:16:55.790444Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.061334ms"} {"level":"info","ts":"2026-06-10T01:16:55.795933Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-06-10T01:16:55.796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T01:16:55.796023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-06-10T01:16:55.796033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T01:16:55.796048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-06-10T01:16:55.796086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-06-10T01:16:55.805436Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T01:16:55.811695Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T01:16:55.814755Z","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-06-10T01:16:55.817264Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T01:16:55.817563Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T01:16:55.817675Z","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-06-10T01:16:55.817805Z","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-06-10T01:16:55.817828Z","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-06-10T01:16:55.822035Z","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-06-10T01:16:55.822181Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-10T01:16:55.82226Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-10T01:16:55.822546Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T01:16:55.82263Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T01:16:55.823758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-06-10T01:16:55.823933Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-10T01:16:56.696829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T01:16:56.696982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T01:16:56.697045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-06-10T01:16:56.697079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-06-10T01:16:56.697107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-10T01:16:56.697144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-06-10T01:16:56.697174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-10T01:16:56.698234Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T01:16:56.699025Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T01:16:56.699172Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T01:16:56.699265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T01:16:56.699463Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T01:16:56.699527Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T01:16:56.699859Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T01:16:56.700119Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T01:16:56.700187Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T01:16:56.703046Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T01:16:56.703382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-06-10T01:17:39.942247Z","caller":"traceutil/trace.go:171","msg":"trace[1424553557] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"138.3574ms","start":"2026-06-10T01:17:39.803864Z","end":"2026-06-10T01:17:39.942221Z","steps":["trace[1424553557] 'process raft request' (duration: 138.190165ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:17:40.135005Z","caller":"traceutil/trace.go:171","msg":"trace[1655684321] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"163.380502ms","start":"2026-06-10T01:17:39.971592Z","end":"2026-06-10T01:17:40.134973Z","steps":["trace[1655684321] 'process raft request' (duration: 160.647465ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:17:40.296044Z","caller":"traceutil/trace.go:171","msg":"trace[1764323436] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"101.317992ms","start":"2026-06-10T01:17:40.194696Z","end":"2026-06-10T01:17:40.296014Z","steps":["trace[1764323436] 'process raft request' (duration: 101.197358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:17:40.598588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.844385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-10T01:17:40.598789Z","caller":"traceutil/trace.go:171","msg":"trace[2064114640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"180.105042ms","start":"2026-06-10T01:17:40.418663Z","end":"2026-06-10T01:17:40.598769Z","steps":["trace[2064114640] 'range keys from in-memory index tree' (duration: 179.726731ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:17:40.76127Z","caller":"traceutil/trace.go:171","msg":"trace[174240165] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"158.004112ms","start":"2026-06-10T01:17:40.60325Z","end":"2026-06-10T01:17:40.761254Z","steps":["trace[174240165] 'process raft request' (duration: 157.687543ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:17:40.761266Z","caller":"traceutil/trace.go:171","msg":"trace[924000891] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"140.554333ms","start":"2026-06-10T01:17:40.620655Z","end":"2026-06-10T01:17:40.761209Z","steps":["trace[924000891] 'read index received' (duration: 140.437919ms)","trace[924000891] 'applied index is now lower than readState.Index' (duration: 115.174µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:17:40.761597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.739677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:17:40.761649Z","caller":"traceutil/trace.go:171","msg":"trace[1773189930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"141.042815ms","start":"2026-06-10T01:17:40.620591Z","end":"2026-06-10T01:17:40.761634Z","steps":["trace[1773189930] 'agreement among raft nodes before linearized reading' (duration: 140.759198ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:17:42.029184Z","caller":"traceutil/trace.go:171","msg":"trace[1329276853] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"110.266263ms","start":"2026-06-10T01:17:41.918894Z","end":"2026-06-10T01:17:42.02916Z","steps":["trace[1329276853] 'process raft request' (duration: 106.521068ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:18:20.187086Z","caller":"traceutil/trace.go:171","msg":"trace[111017480] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"115.015956ms","start":"2026-06-10T01:18:20.072043Z","end":"2026-06-10T01:18:20.187059Z","steps":["trace[111017480] 'read index received' (duration: 114.763349ms)","trace[111017480] 'applied index is now lower than readState.Index' (duration: 251.737µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:18:20.187146Z","caller":"traceutil/trace.go:171","msg":"trace[1099499084] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"142.98388ms","start":"2026-06-10T01:18:20.044127Z","end":"2026-06-10T01:18:20.187111Z","steps":["trace[1099499084] 'process raft request' (duration: 142.772574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:18:20.187319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.258962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T01:18:20.187361Z","caller":"traceutil/trace.go:171","msg":"trace[332817114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1183; }","duration":"115.361235ms","start":"2026-06-10T01:18:20.071989Z","end":"2026-06-10T01:18:20.187351Z","steps":["trace[332817114] 'agreement among raft nodes before linearized reading' (duration: 115.19552ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:19:06.45275Z","caller":"traceutil/trace.go:171","msg":"trace[551299108] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"135.019928ms","start":"2026-06-10T01:19:06.317695Z","end":"2026-06-10T01:19:06.452715Z","steps":["trace[551299108] 'read index received' (duration: 134.950386ms)","trace[551299108] 'applied index is now lower than readState.Index' (duration: 68.682µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:19:06.452779Z","caller":"traceutil/trace.go:171","msg":"trace[1376487075] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"156.15922ms","start":"2026-06-10T01:19:06.296596Z","end":"2026-06-10T01:19:06.452756Z","steps":["trace[1376487075] 'process raft request' (duration: 155.989465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:19:06.452897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.185703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:19:06.452942Z","caller":"traceutil/trace.go:171","msg":"trace[1279144220] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1843; }","duration":"135.282095ms","start":"2026-06-10T01:19:06.317648Z","end":"2026-06-10T01:19:06.45293Z","steps":["trace[1279144220] 'agreement among raft nodes before linearized reading' (duration: 135.190932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:19:06.467347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.186764ms","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-06-10T01:19:06.467431Z","caller":"traceutil/trace.go:171","msg":"trace[634561038] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"144.328248ms","start":"2026-06-10T01:19:06.323085Z","end":"2026-06-10T01:19:06.467413Z","steps":["trace[634561038] 'agreement among raft nodes before linearized reading' (duration: 144.101312ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:19:06.467636Z","caller":"traceutil/trace.go:171","msg":"trace[1472923818] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"107.287462ms","start":"2026-06-10T01:19:06.360325Z","end":"2026-06-10T01:19:06.467612Z","steps":["trace[1472923818] 'process raft request' (duration: 106.742577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:19:06.815685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.854309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T01:19:06.815809Z","caller":"traceutil/trace.go:171","msg":"trace[1556488663] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"221.980151ms","start":"2026-06-10T01:19:06.593809Z","end":"2026-06-10T01:19:06.815789Z","steps":["trace[1556488663] 'read index received' (duration: 47.922µs)","trace[1556488663] 'applied index is now lower than readState.Index' (duration: 221.930489ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:19:06.815852Z","caller":"traceutil/trace.go:171","msg":"trace[1872722325] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"303.016379ms","start":"2026-06-10T01:19:06.512795Z","end":"2026-06-10T01:19:06.815811Z","steps":["trace[1872722325] 'process raft request' (duration: 16.262215ms)","trace[1872722325] 'compare' (duration: 285.327484ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:19:06.816023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:19:06.512775Z","time spent":"303.175763ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T01:19:06.81606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.243278ms","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-06-10T01:19:06.816121Z","caller":"traceutil/trace.go:171","msg":"trace[1042890212] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1850; }","duration":"222.349491ms","start":"2026-06-10T01:19:06.593755Z","end":"2026-06-10T01:19:06.816104Z","steps":["trace[1042890212] 'agreement among raft nodes before linearized reading' (duration: 222.135825ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:19:06.816378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.532127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-10T01:19:06.816384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.959435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:19:06.816449Z","caller":"traceutil/trace.go:171","msg":"trace[1220634980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1850; }","duration":"128.65132ms","start":"2026-06-10T01:19:06.687782Z","end":"2026-06-10T01:19:06.816434Z","steps":["trace[1220634980] 'agreement among raft nodes before linearized reading' (duration: 128.447084ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:19:06.816482Z","caller":"traceutil/trace.go:171","msg":"trace[818285243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"195.086849ms","start":"2026-06-10T01:19:06.621363Z","end":"2026-06-10T01:19:06.81645Z","steps":["trace[818285243] 'agreement among raft nodes before linearized reading' (duration: 194.868283ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:19:19.842875Z","caller":"traceutil/trace.go:171","msg":"trace[1741159525] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"138.849495ms","start":"2026-06-10T01:19:19.703978Z","end":"2026-06-10T01:19:19.842828Z","steps":["trace[1741159525] 'process raft request' (duration: 111.51935ms)","trace[1741159525] 'compare' (duration: 27.13929ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:19:24.068206Z","caller":"traceutil/trace.go:171","msg":"trace[182890278] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"327.654689ms","start":"2026-06-10T01:19:23.740526Z","end":"2026-06-10T01:19:24.068181Z","steps":["trace[182890278] 'process raft request' (duration: 327.524335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:19:24.068348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:19:23.7405Z","time spent":"327.779872ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T01:20:02.87558Z","caller":"traceutil/trace.go:171","msg":"trace[317543024] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"172.454955ms","start":"2026-06-10T01:20:02.70309Z","end":"2026-06-10T01:20:02.875545Z","steps":["trace[317543024] 'process raft request' (duration: 172.219829ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:20:03.050438Z","caller":"traceutil/trace.go:171","msg":"trace[285531476] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"141.171573ms","start":"2026-06-10T01:20:02.909213Z","end":"2026-06-10T01:20:03.050385Z","steps":["trace[285531476] 'read index received' (duration: 72.778173ms)","trace[285531476] 'applied index is now lower than readState.Index' (duration: 68.391679ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:20:03.050483Z","caller":"traceutil/trace.go:171","msg":"trace[387045851] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"334.710486ms","start":"2026-06-10T01:20:02.715736Z","end":"2026-06-10T01:20:03.050446Z","steps":["trace[387045851] 'process raft request' (duration: 266.339987ms)","trace[387045851] 'compare' (duration: 68.102662ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:20:03.050679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:20:02.715699Z","time spent":"334.882641ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T01:20:03.050749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.512741ms","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-06-10T01:20:03.050812Z","caller":"traceutil/trace.go:171","msg":"trace[1062795324] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2199; }","duration":"141.649476ms","start":"2026-06-10T01:20:02.909139Z","end":"2026-06-10T01:20:03.050788Z","steps":["trace[1062795324] 'agreement among raft nodes before linearized reading' (duration: 141.402379ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:21:30.093872Z","caller":"traceutil/trace.go:171","msg":"trace[1520467602] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"104.952013ms","start":"2026-06-10T01:21:29.988894Z","end":"2026-06-10T01:21:30.093846Z","steps":["trace[1520467602] 'process raft request' (duration: 104.81555ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:21:30.266717Z","caller":"traceutil/trace.go:171","msg":"trace[12503625] linearizableReadLoop","detail":"{readStateIndex:2961; appliedIndex:2960; }","duration":"206.02217ms","start":"2026-06-10T01:21:30.060674Z","end":"2026-06-10T01:21:30.266697Z","steps":["trace[12503625] 'read index received' (duration: 33.225536ms)","trace[12503625] 'applied index is now lower than readState.Index' (duration: 172.795684ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:21:30.266753Z","caller":"traceutil/trace.go:171","msg":"trace[1952529884] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"241.613642ms","start":"2026-06-10T01:21:30.025113Z","end":"2026-06-10T01:21:30.266727Z","steps":["trace[1952529884] 'process raft request' (duration: 241.450817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:21:30.266893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.214075ms","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-06-10T01:21:30.266928Z","caller":"traceutil/trace.go:171","msg":"trace[1176994956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2854; }","duration":"206.300767ms","start":"2026-06-10T01:21:30.060618Z","end":"2026-06-10T01:21:30.266919Z","steps":["trace[1176994956] 'agreement among raft nodes before linearized reading' (duration: 206.168754ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:24:05.636022Z","caller":"traceutil/trace.go:171","msg":"trace[957467921] transaction","detail":"{read_only:false; response_revision:3886; number_of_response:1; }","duration":"116.35232ms","start":"2026-06-10T01:24:05.519605Z","end":"2026-06-10T01:24:05.635957Z","steps":["trace[957467921] 'process raft request' (duration: 115.921678ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:29.676808Z","caller":"traceutil/trace.go:171","msg":"trace[2015233292] linearizableReadLoop","detail":"{readStateIndex:5118; appliedIndex:5117; }","duration":"123.418269ms","start":"2026-06-10T01:25:29.553356Z","end":"2026-06-10T01:25:29.676775Z","steps":["trace[2015233292] 'read index received' (duration: 113.423631ms)","trace[2015233292] 'applied index is now lower than readState.Index' (duration: 9.993438ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:25:29.676842Z","caller":"traceutil/trace.go:171","msg":"trace[618921900] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"130.255779ms","start":"2026-06-10T01:25:29.546548Z","end":"2026-06-10T01:25:29.676804Z","steps":["trace[618921900] 'process raft request' (duration: 120.292402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:25:29.67704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.661056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-10T01:25:29.677101Z","caller":"traceutil/trace.go:171","msg":"trace[419583001] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4933; }","duration":"123.734948ms","start":"2026-06-10T01:25:29.553353Z","end":"2026-06-10T01:25:29.677087Z","steps":["trace[419583001] 'agreement among raft nodes before linearized reading' (duration: 123.518602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:25:29.677146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.678627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-06-10T01:25:29.677193Z","caller":"traceutil/trace.go:171","msg":"trace[1422109233] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4933; }","duration":"123.757198ms","start":"2026-06-10T01:25:29.553423Z","end":"2026-06-10T01:25:29.677181Z","steps":["trace[1422109233] 'agreement among raft nodes before linearized reading' (duration: 123.574983ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:32.394531Z","caller":"traceutil/trace.go:171","msg":"trace[59295474] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"293.839334ms","start":"2026-06-10T01:25:32.100671Z","end":"2026-06-10T01:25:32.39451Z","steps":["trace[59295474] 'process raft request' (duration: 228.794877ms)","trace[59295474] 'compare' (duration: 64.885043ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:25:32.403343Z","caller":"traceutil/trace.go:171","msg":"trace[1840460023] linearizableReadLoop","detail":"{readStateIndex:5253; appliedIndex:5251; }","duration":"162.955267ms","start":"2026-06-10T01:25:32.240366Z","end":"2026-06-10T01:25:32.403322Z","steps":["trace[1840460023] 'read index received' (duration: 89.058554ms)","trace[1840460023] 'applied index is now lower than readState.Index' (duration: 73.895573ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:25:32.403369Z","caller":"traceutil/trace.go:171","msg":"trace[1365862046] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"240.03991ms","start":"2026-06-10T01:25:32.163311Z","end":"2026-06-10T01:25:32.403351Z","steps":["trace[1365862046] 'process raft request' (duration: 239.863045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:25:32.40351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.136533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bcc334ce-d949-40c3-9f58-d20712516b31\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-06-10T01:25:32.403538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.461205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T01:25:32.403548Z","caller":"traceutil/trace.go:171","msg":"trace[407919333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bcc334ce-d949-40c3-9f58-d20712516b31; range_end:; response_count:1; response_revision:5066; }","duration":"163.221585ms","start":"2026-06-10T01:25:32.240313Z","end":"2026-06-10T01:25:32.403535Z","steps":["trace[407919333] 'agreement among raft nodes before linearized reading' (duration: 163.103952ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:32.40357Z","caller":"traceutil/trace.go:171","msg":"trace[1685847057] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5066; }","duration":"116.545808ms","start":"2026-06-10T01:25:32.287012Z","end":"2026-06-10T01:25:32.403558Z","steps":["trace[1685847057] 'agreement among raft nodes before linearized reading' (duration: 116.460395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:25:32.403714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.115876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-hkmms.18b793d737fdc169\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-06-10T01:25:32.403812Z","caller":"traceutil/trace.go:171","msg":"trace[2133655254] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-hkmms.18b793d737fdc169; range_end:; response_count:1; response_revision:5066; }","duration":"161.25811ms","start":"2026-06-10T01:25:32.242529Z","end":"2026-06-10T01:25:32.403787Z","steps":["trace[2133655254] 'agreement among raft nodes before linearized reading' (duration: 160.947811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:25:34.554883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.01726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:25:34.554977Z","caller":"traceutil/trace.go:171","msg":"trace[843459] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5101; }","duration":"185.162964ms","start":"2026-06-10T01:25:34.369791Z","end":"2026-06-10T01:25:34.554954Z","steps":["trace[843459] 'count revisions from in-memory index tree' (duration: 184.907497ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:34.555142Z","caller":"traceutil/trace.go:171","msg":"trace[874819371] transaction","detail":"{read_only:false; response_revision:5102; number_of_response:1; }","duration":"143.61677ms","start":"2026-06-10T01:25:34.411472Z","end":"2026-06-10T01:25:34.555089Z","steps":["trace[874819371] 'process raft request' (duration: 62.577409ms)","trace[874819371] 'compare' (duration: 80.65435ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:25:34.574682Z","caller":"traceutil/trace.go:171","msg":"trace[1083090552] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"162.986349ms","start":"2026-06-10T01:25:34.411671Z","end":"2026-06-10T01:25:34.574658Z","steps":["trace[1083090552] 'process raft request' (duration: 162.811514ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.64235Z","caller":"traceutil/trace.go:171","msg":"trace[1133290797] linearizableReadLoop","detail":"{readStateIndex:5696; appliedIndex:5695; }","duration":"154.175683ms","start":"2026-06-10T01:25:54.488145Z","end":"2026-06-10T01:25:54.642321Z","steps":["trace[1133290797] 'read index received' (duration: 153.959377ms)","trace[1133290797] 'applied index is now lower than readState.Index' (duration: 215.306µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:25:54.642492Z","caller":"traceutil/trace.go:171","msg":"trace[26880026] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"173.630734ms","start":"2026-06-10T01:25:54.468846Z","end":"2026-06-10T01:25:54.642477Z","steps":["trace[26880026] 'process raft request' (duration: 173.283524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:25:54.64267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.502702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-10T01:25:54.642749Z","caller":"traceutil/trace.go:171","msg":"trace[1846286924] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5496; }","duration":"154.621686ms","start":"2026-06-10T01:25:54.488111Z","end":"2026-06-10T01:25:54.642733Z","steps":["trace[1846286924] 'agreement among raft nodes before linearized reading' (duration: 154.437381ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.837604Z","caller":"traceutil/trace.go:171","msg":"trace[603064140] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"187.696395ms","start":"2026-06-10T01:25:54.649881Z","end":"2026-06-10T01:25:54.837577Z","steps":["trace[603064140] 'process raft request' (duration: 187.608613ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.837616Z","caller":"traceutil/trace.go:171","msg":"trace[933144067] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"191.647395ms","start":"2026-06-10T01:25:54.645919Z","end":"2026-06-10T01:25:54.837567Z","steps":["trace[933144067] 'process raft request' (duration: 191.398478ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.837989Z","caller":"traceutil/trace.go:171","msg":"trace[2099507806] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"187.945122ms","start":"2026-06-10T01:25:54.650005Z","end":"2026-06-10T01:25:54.83795Z","steps":["trace[2099507806] 'process raft request' (duration: 187.53421ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.838023Z","caller":"traceutil/trace.go:171","msg":"trace[571241083] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"187.914911ms","start":"2026-06-10T01:25:54.650081Z","end":"2026-06-10T01:25:54.837996Z","steps":["trace[571241083] 'process raft request' (duration: 187.739116ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.838186Z","caller":"traceutil/trace.go:171","msg":"trace[766115928] linearizableReadLoop","detail":"{readStateIndex:5704; appliedIndex:5697; }","duration":"152.755214ms","start":"2026-06-10T01:25:54.685418Z","end":"2026-06-10T01:25:54.838173Z","steps":["trace[766115928] 'read index received' (duration: 151.842749ms)","trace[766115928] 'applied index is now lower than readState.Index' (duration: 911.745µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:25:54.838187Z","caller":"traceutil/trace.go:171","msg":"trace[225360316] transaction","detail":"{read_only:false; response_revision:5502; number_of_response:1; }","duration":"188.048245ms","start":"2026-06-10T01:25:54.650108Z","end":"2026-06-10T01:25:54.838157Z","steps":["trace[225360316] 'process raft request' (duration: 187.828138ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.838231Z","caller":"traceutil/trace.go:171","msg":"trace[422952309] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"187.298974ms","start":"2026-06-10T01:25:54.650921Z","end":"2026-06-10T01:25:54.83822Z","steps":["trace[422952309] 'process raft request' (duration: 187.208922ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.838232Z","caller":"traceutil/trace.go:171","msg":"trace[362508700] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"187.390777ms","start":"2026-06-10T01:25:54.650816Z","end":"2026-06-10T01:25:54.838206Z","steps":["trace[362508700] 'process raft request' (duration: 187.254543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:25:54.838426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.13289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dc7abeec-11f0-48a4-b97a-8265e55d5297\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-06-10T01:25:54.838431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.820162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bcc334ce-d949-40c3-9f58-d20712516b31\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-06-10T01:25:54.838469Z","caller":"traceutil/trace.go:171","msg":"trace[1460119853] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dc7abeec-11f0-48a4-b97a-8265e55d5297; range_end:; response_count:1; response_revision:5504; }","duration":"191.240264ms","start":"2026-06-10T01:25:54.647218Z","end":"2026-06-10T01:25:54.838458Z","steps":["trace[1460119853] 'agreement among raft nodes before linearized reading' (duration: 191.045208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:25:54.83846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.716975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:25:54.838542Z","caller":"traceutil/trace.go:171","msg":"trace[1798087810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5504; }","duration":"151.86111ms","start":"2026-06-10T01:25:54.686654Z","end":"2026-06-10T01:25:54.838515Z","steps":["trace[1798087810] 'agreement among raft nodes before linearized reading' (duration: 151.676224ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:54.838488Z","caller":"traceutil/trace.go:171","msg":"trace[1915623752] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bcc334ce-d949-40c3-9f58-d20712516b31; range_end:; response_count:1; response_revision:5504; }","duration":"190.899694ms","start":"2026-06-10T01:25:54.64757Z","end":"2026-06-10T01:25:54.83847Z","steps":["trace[1915623752] 'agreement among raft nodes before linearized reading' (duration: 190.667628ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:55.009559Z","caller":"traceutil/trace.go:171","msg":"trace[2145087168] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"163.799731ms","start":"2026-06-10T01:25:54.845729Z","end":"2026-06-10T01:25:55.009528Z","steps":["trace[2145087168] 'process raft request' (duration: 139.724482ms)","trace[2145087168] 'compare' (duration: 23.871213ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:25:55.083575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.651219ms","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-06-10T01:25:55.083669Z","caller":"traceutil/trace.go:171","msg":"trace[960795565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5505; }","duration":"135.804793ms","start":"2026-06-10T01:25:54.947844Z","end":"2026-06-10T01:25:55.083649Z","steps":["trace[960795565] 'agreement among raft nodes before linearized reading' (duration: 135.558706ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:25:55.205093Z","caller":"traceutil/trace.go:171","msg":"trace[1659565088] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"116.659142ms","start":"2026-06-10T01:25:55.088399Z","end":"2026-06-10T01:25:55.205058Z","steps":["trace[1659565088] 'process raft request' (duration: 97.027046ms)","trace[1659565088] 'compare' (duration: 19.119731ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:26:56.729548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3007} {"level":"info","ts":"2026-06-10T01:26:56.811991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3007,"took":"80.778021ms","hash":3103218936,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":31625216,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-10T01:26:56.812066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3103218936,"revision":3007,"compact-revision":-1} {"level":"info","ts":"2026-06-10T01:31:56.735824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6081} {"level":"info","ts":"2026-06-10T01:31:56.833766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6081,"took":"95.428179ms","hash":939345377,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":21934080,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-10T01:31:56.833835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":939345377,"revision":6081,"compact-revision":3007} {"level":"info","ts":"2026-06-10T01:32:01.065559Z","caller":"traceutil/trace.go:171","msg":"trace[1578723719] linearizableReadLoop","detail":"{readStateIndex:8623; appliedIndex:8622; }","duration":"117.927864ms","start":"2026-06-10T01:32:00.947607Z","end":"2026-06-10T01:32:01.065534Z","steps":["trace[1578723719] 'read index received' (duration: 117.841821ms)","trace[1578723719] 'applied index is now lower than readState.Index' (duration: 84.743µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:32:01.065606Z","caller":"traceutil/trace.go:171","msg":"trace[1152595043] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"149.608984ms","start":"2026-06-10T01:32:00.915966Z","end":"2026-06-10T01:32:01.065575Z","steps":["trace[1152595043] 'process raft request' (duration: 149.415028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:32:01.065765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.14275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gnppm\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-06-10T01:32:01.065798Z","caller":"traceutil/trace.go:171","msg":"trace[1352263800] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gnppm; range_end:; response_count:1; response_revision:8334; }","duration":"118.223312ms","start":"2026-06-10T01:32:00.947566Z","end":"2026-06-10T01:32:01.06579Z","steps":["trace[1352263800] 'agreement among raft nodes before linearized reading' (duration: 118.074478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:32:12.53524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.533275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T01:32:12.535418Z","caller":"traceutil/trace.go:171","msg":"trace[515084845] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8393; }","duration":"107.661949ms","start":"2026-06-10T01:32:12.427641Z","end":"2026-06-10T01:32:12.535303Z","steps":["trace[515084845] 'count revisions from in-memory index tree' (duration: 107.395182ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:32:13.616542Z","caller":"traceutil/trace.go:171","msg":"trace[254036791] transaction","detail":"{read_only:false; response_revision:8397; number_of_response:1; }","duration":"247.590043ms","start":"2026-06-10T01:32:13.36892Z","end":"2026-06-10T01:32:13.61651Z","steps":["trace[254036791] 'process raft request' (duration: 237.538064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:32:41.814484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.966615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T01:32:41.814659Z","caller":"traceutil/trace.go:171","msg":"trace[1712249882] linearizableReadLoop","detail":"{readStateIndex:8900; appliedIndex:8899; }","duration":"296.288721ms","start":"2026-06-10T01:32:41.518348Z","end":"2026-06-10T01:32:41.814637Z","steps":["trace[1712249882] 'read index received' (duration: 98.934995ms)","trace[1712249882] 'applied index is now lower than readState.Index' (duration: 197.352316ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:32:41.814953Z","caller":"traceutil/trace.go:171","msg":"trace[2074153108] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"376.907468ms","start":"2026-06-10T01:32:41.438009Z","end":"2026-06-10T01:32:41.814916Z","steps":["trace[2074153108] 'process raft request' (duration: 179.344436ms)","trace[2074153108] 'compare' (duration: 196.841342ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T01:32:41.814985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.622511ms","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-06-10T01:32:41.815036Z","caller":"traceutil/trace.go:171","msg":"trace[1413289981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8600; }","duration":"296.716923ms","start":"2026-06-10T01:32:41.518305Z","end":"2026-06-10T01:32:41.815021Z","steps":["trace[1413289981] 'agreement among raft nodes before linearized reading' (duration: 296.462086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:32:41.81504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T01:32:41.437985Z","time spent":"377.014792ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T01:32:41.815893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.923409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T01:32:41.815982Z","caller":"traceutil/trace.go:171","msg":"trace[985902520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8600; }","duration":"195.613348ms","start":"2026-06-10T01:32:41.620335Z","end":"2026-06-10T01:32:41.815948Z","steps":["trace[985902520] 'agreement among raft nodes before linearized reading' (duration: 194.797885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T01:32:54.194108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.333319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T01:32:54.194273Z","caller":"traceutil/trace.go:171","msg":"trace[1081047677] transaction","detail":"{read_only:false; response_revision:8709; number_of_response:1; }","duration":"185.665742ms","start":"2026-06-10T01:32:54.008581Z","end":"2026-06-10T01:32:54.194247Z","steps":["trace[1081047677] 'process raft request' (duration: 65.101647ms)","trace[1081047677] 'compare' (duration: 120.177294ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:32:54.199256Z","caller":"traceutil/trace.go:171","msg":"trace[1741542798] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"178.098132ms","start":"2026-06-10T01:32:54.021134Z","end":"2026-06-10T01:32:54.199232Z","steps":["trace[1741542798] 'process raft request' (duration: 177.985098ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T01:33:10.825253Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d2.wal"} {"level":"info","ts":"2026-06-10T01:34:39.734525Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T01:34:39.739844Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-10T01:34:39.739987Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-10T01:36:56.743504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8182} {"level":"info","ts":"2026-06-10T01:36:56.804045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8182,"took":"58.228124ms","hash":3770331070,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":29696000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-10T01:36:56.804103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3770331070,"revision":8182,"compact-revision":6081} {"level":"info","ts":"2026-06-10T01:41:56.749994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10795} {"level":"info","ts":"2026-06-10T01:41:56.828665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10795,"took":"76.176852ms","hash":3503704465,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":27115520,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-10T01:41:56.828726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3503704465,"revision":10795,"compact-revision":8182} {"level":"info","ts":"2026-06-10T01:43:45.418862Z","caller":"traceutil/trace.go:171","msg":"trace[1316453538] transaction","detail":"{read_only:false; response_revision:13703; number_of_response:1; }","duration":"140.049836ms","start":"2026-06-10T01:43:45.278785Z","end":"2026-06-10T01:43:45.418835Z","steps":["trace[1316453538] 'process raft request' (duration: 76.204169ms)","trace[1316453538] 'compare' (duration: 63.371264ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T01:46:56.755639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12870} {"level":"info","ts":"2026-06-10T01:46:56.828614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12870,"took":"70.930083ms","hash":4108713996,"current-db-size-bytes":57233408,"current-db-size":"57 MB","current-db-size-in-use-bytes":24997888,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-10T01:46:56.828668Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4108713996,"revision":12870,"compact-revision":10795}