{"level":"warn","ts":"2026-09-10T11:07:10.196367Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T11:07:10.196453Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.71:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.71:2380","--initial-cluster=instance=https://162.253.55.71:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.71:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.71: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-09-10T11:07:10.196511Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T11:07:10.196522Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-09-10T11:07:10.196537Z","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-09-10T11:07:10.197225Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"]} {"level":"info","ts":"2026-09-10T11:07:10.197297Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.71: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-09-10T11:07:10.200484Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.407303ms"} {"level":"info","ts":"2026-09-10T11:07:10.209223Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54232768b5552f01","cluster-id":"66db39a47e9fb23b"} {"level":"info","ts":"2026-09-10T11:07:10.209361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T11:07:10.209394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 0"} {"level":"info","ts":"2026-09-10T11:07:10.209411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54232768b5552f01 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T11:07:10.209426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 1"} {"level":"info","ts":"2026-09-10T11:07:10.209495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"warn","ts":"2026-09-10T11:07:10.215127Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T11:07:10.217877Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T11:07:10.219548Z","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-09-10T11:07:10.222031Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54232768b5552f01","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T11:07:10.223133Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54232768b5552f01","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T11:07:10.223524Z","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-09-10T11:07:10.223578Z","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-09-10T11:07:10.223597Z","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-09-10T11:07:10.22458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"info","ts":"2026-09-10T11:07:10.224776Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","added-peer-id":"54232768b5552f01","added-peer-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-09-10T11:07:10.225432Z","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-09-10T11:07:10.225567Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-09-10T11:07:10.225611Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-09-10T11:07:10.225709Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54232768b5552f01","initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T11:07:10.225745Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T11:07:10.610233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T11:07:10.610287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T11:07:10.610308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgPreVoteResp from 54232768b5552f01 at term 1"} {"level":"info","ts":"2026-09-10T11:07:10.610317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became candidate at term 2"} {"level":"info","ts":"2026-09-10T11:07:10.610323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgVoteResp from 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-09-10T11:07:10.61033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became leader at term 2"} {"level":"info","ts":"2026-09-10T11:07:10.610337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54232768b5552f01 elected leader 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-09-10T11:07:10.612476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54232768b5552f01","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.71:2379]}","request-path":"/0/members/54232768b5552f01/attributes","cluster-id":"66db39a47e9fb23b","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T11:07:10.612982Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:07:10.614476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T11:07:10.614526Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T11:07:10.615022Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T11:07:10.615105Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T11:07:10.615616Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:07:10.615793Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:07:10.615841Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:07:10.619143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T11:07:10.619764Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.71:2379"} {"level":"info","ts":"2026-09-10T11:07:28.246325Z","caller":"traceutil/trace.go:171","msg":"trace[753662427] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"105.070566ms","start":"2026-09-10T11:07:28.141226Z","end":"2026-09-10T11:07:28.246297Z","steps":["trace[753662427] 'process raft request' (duration: 104.928372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:28.550859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.649597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-10T11:07:28.551045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.872594ms","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-09-10T11:07:28.551078Z","caller":"traceutil/trace.go:171","msg":"trace[1435721559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"212.921112ms","start":"2026-09-10T11:07:28.338133Z","end":"2026-09-10T11:07:28.551054Z","steps":["trace[1435721559] 'range keys from in-memory index tree' (duration: 212.532394ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:28.551132Z","caller":"traceutil/trace.go:171","msg":"trace[398861099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"202.971236ms","start":"2026-09-10T11:07:28.348138Z","end":"2026-09-10T11:07:28.551109Z","steps":["trace[398861099] 'range keys from in-memory index tree' (duration: 202.710251ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:03.196377Z","caller":"traceutil/trace.go:171","msg":"trace[263386949] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"300.4133ms","start":"2026-09-10T11:08:02.895939Z","end":"2026-09-10T11:08:03.196352Z","steps":["trace[263386949] 'process raft request' (duration: 240.682059ms)","trace[263386949] 'compare' (duration: 59.637029ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:08:03.196892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:08:02.895913Z","time spent":"300.530802ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:08:20.989141Z","caller":"traceutil/trace.go:171","msg":"trace[2095841222] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"129.872904ms","start":"2026-09-10T11:08:20.859232Z","end":"2026-09-10T11:08:20.989105Z","steps":["trace[2095841222] 'read index received' (duration: 129.6723ms)","trace[2095841222] 'applied index is now lower than readState.Index' (duration: 199.224µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:08:20.989239Z","caller":"traceutil/trace.go:171","msg":"trace[278650979] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"148.457806ms","start":"2026-09-10T11:08:20.840739Z","end":"2026-09-10T11:08:20.989196Z","steps":["trace[278650979] 'process raft request' (duration: 148.116209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:20.989486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.221742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-10T11:08:20.989487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.838505ms","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-09-10T11:08:20.989529Z","caller":"traceutil/trace.go:171","msg":"trace[162669993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:830; }","duration":"130.348605ms","start":"2026-09-10T11:08:20.859168Z","end":"2026-09-10T11:08:20.989517Z","steps":["trace[162669993] 'agreement among raft nodes before linearized reading' (duration: 130.12424ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:20.989543Z","caller":"traceutil/trace.go:171","msg":"trace[1851677228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:830; }","duration":"111.963728ms","start":"2026-09-10T11:08:20.877569Z","end":"2026-09-10T11:08:20.989533Z","steps":["trace[1851677228] 'agreement among raft nodes before linearized reading' (duration: 111.759884ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:39.103741Z","caller":"traceutil/trace.go:171","msg":"trace[583522180] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"303.0718ms","start":"2026-09-10T11:09:38.800642Z","end":"2026-09-10T11:09:39.103714Z","steps":["trace[583522180] 'read index received' (duration: 244.071377ms)","trace[583522180] 'applied index is now lower than readState.Index' (duration: 58.999453ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:09:39.103941Z","caller":"traceutil/trace.go:171","msg":"trace[1450189216] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"315.323175ms","start":"2026-09-10T11:09:38.788567Z","end":"2026-09-10T11:09:39.10389Z","steps":["trace[1450189216] 'process raft request' (duration: 256.270499ms)","trace[1450189216] 'compare' (duration: 58.746398ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:09:39.104065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.397767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T11:09:39.104145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:09:38.78853Z","time spent":"315.521059ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:09:39.104154Z","caller":"traceutil/trace.go:171","msg":"trace[2026296931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1895; }","duration":"303.52998ms","start":"2026-09-10T11:09:38.800599Z","end":"2026-09-10T11:09:39.104129Z","steps":["trace[2026296931] 'agreement among raft nodes before linearized reading' (duration: 303.379006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:39.104165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.860377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-10T11:09:39.104167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.49535ms","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-09-10T11:09:39.104206Z","caller":"traceutil/trace.go:171","msg":"trace[523395702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1895; }","duration":"106.950338ms","start":"2026-09-10T11:09:38.997244Z","end":"2026-09-10T11:09:39.104194Z","steps":["trace[523395702] 'agreement among raft nodes before linearized reading' (duration: 106.803025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:39.104202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:09:38.800551Z","time spent":"303.643192ms","remote":"127.0.0.1:39256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-10T11:09:39.104221Z","caller":"traceutil/trace.go:171","msg":"trace[2095027600] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1895; }","duration":"215.584812ms","start":"2026-09-10T11:09:38.888618Z","end":"2026-09-10T11:09:39.104203Z","steps":["trace[2095027600] 'agreement among raft nodes before linearized reading' (duration: 215.425719ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:39.293555Z","caller":"traceutil/trace.go:171","msg":"trace[2096926744] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"146.46835ms","start":"2026-09-10T11:09:39.147059Z","end":"2026-09-10T11:09:39.293527Z","steps":["trace[2096926744] 'read index received' (duration: 86.119998ms)","trace[2096926744] 'applied index is now lower than readState.Index' (duration: 60.347392ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:09:39.293662Z","caller":"traceutil/trace.go:171","msg":"trace[139130023] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"145.939929ms","start":"2026-09-10T11:09:39.147708Z","end":"2026-09-10T11:09:39.293648Z","steps":["trace[139130023] 'process raft request' (duration: 145.781976ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:39.293565Z","caller":"traceutil/trace.go:171","msg":"trace[1826698411] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"185.150515ms","start":"2026-09-10T11:09:39.10839Z","end":"2026-09-10T11:09:39.293541Z","steps":["trace[1826698411] 'process raft request' (duration: 124.773913ms)","trace[1826698411] 'compare' (duration: 60.199018ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:09:39.293798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.710016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:09:39.293954Z","caller":"traceutil/trace.go:171","msg":"trace[1846500338] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1898; }","duration":"146.951061ms","start":"2026-09-10T11:09:39.14696Z","end":"2026-09-10T11:09:39.293911Z","steps":["trace[1846500338] 'agreement among raft nodes before linearized reading' (duration: 146.700506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:39.294157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.627074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fb3b4ddf-b6a6-453b-987d-44a62df4d747\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-10T11:09:39.294259Z","caller":"traceutil/trace.go:171","msg":"trace[325472438] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fb3b4ddf-b6a6-453b-987d-44a62df4d747; range_end:; response_count:1; response_revision:1898; }","duration":"108.786757ms","start":"2026-09-10T11:09:39.185455Z","end":"2026-09-10T11:09:39.294242Z","steps":["trace[325472438] 'agreement among raft nodes before linearized reading' (duration: 108.402409ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:10:17.83402Z","caller":"traceutil/trace.go:171","msg":"trace[709511622] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"105.314772ms","start":"2026-09-10T11:10:17.728674Z","end":"2026-09-10T11:10:17.833989Z","steps":["trace[709511622] 'process raft request' (duration: 105.105957ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:10:17.866679Z","caller":"traceutil/trace.go:171","msg":"trace[432421714] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"136.333111ms","start":"2026-09-10T11:10:17.730323Z","end":"2026-09-10T11:10:17.866656Z","steps":["trace[432421714] 'process raft request' (duration: 126.760064ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:10:17.866855Z","caller":"traceutil/trace.go:171","msg":"trace[1672813289] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2247; }","duration":"133.805667ms","start":"2026-09-10T11:10:17.73302Z","end":"2026-09-10T11:10:17.866826Z","steps":["trace[1672813289] 'read index received' (duration: 100.707352ms)","trace[1672813289] 'applied index is now lower than readState.Index' (duration: 33.096415ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:10:17.867042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.011201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:10:17.867011Z","caller":"traceutil/trace.go:171","msg":"trace[1096360912] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"134.692915ms","start":"2026-09-10T11:10:17.732291Z","end":"2026-09-10T11:10:17.866984Z","steps":["trace[1096360912] 'process raft request' (duration: 134.330877ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:10:17.867083Z","caller":"traceutil/trace.go:171","msg":"trace[1518737388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2166; }","duration":"134.121713ms","start":"2026-09-10T11:10:17.732951Z","end":"2026-09-10T11:10:17.867072Z","steps":["trace[1518737388] 'agreement among raft nodes before linearized reading' (duration: 134.012471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:10:17.867264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.8151ms","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-09-10T11:10:17.867316Z","caller":"traceutil/trace.go:171","msg":"trace[551371720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2166; }","duration":"129.878472ms","start":"2026-09-10T11:10:17.737424Z","end":"2026-09-10T11:10:17.867303Z","steps":["trace[551371720] 'agreement among raft nodes before linearized reading' (duration: 129.722878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:10:23.656316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.215461ms","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-09-10T11:10:23.65642Z","caller":"traceutil/trace.go:171","msg":"trace[1992823833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2192; }","duration":"138.362234ms","start":"2026-09-10T11:10:23.518035Z","end":"2026-09-10T11:10:23.656398Z","steps":["trace[1992823833] 'range keys from in-memory index tree' (duration: 138.005096ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:10:29.158482Z","caller":"traceutil/trace.go:171","msg":"trace[916095192] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"101.704914ms","start":"2026-09-10T11:10:29.056755Z","end":"2026-09-10T11:10:29.15846Z","steps":["trace[916095192] 'process raft request' (duration: 101.502869ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:10:29.420259Z","caller":"traceutil/trace.go:171","msg":"trace[736861799] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"117.343831ms","start":"2026-09-10T11:10:29.30289Z","end":"2026-09-10T11:10:29.420234Z","steps":["trace[736861799] 'process raft request' (duration: 117.151457ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:52.658035Z","caller":"traceutil/trace.go:171","msg":"trace[2020487813] linearizableReadLoop","detail":"{readStateIndex:2977; appliedIndex:2975; }","duration":"179.123319ms","start":"2026-09-10T11:11:52.478879Z","end":"2026-09-10T11:11:52.658003Z","steps":["trace[2020487813] 'read index received' (duration: 28.526575ms)","trace[2020487813] 'applied index is now lower than readState.Index' (duration: 150.595364ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:11:52.658352Z","caller":"traceutil/trace.go:171","msg":"trace[1815684873] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"185.328083ms","start":"2026-09-10T11:11:52.473009Z","end":"2026-09-10T11:11:52.658337Z","steps":["trace[1815684873] 'process raft request' (duration: 184.767781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:52.658658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.765743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-10T11:11:52.658702Z","caller":"traceutil/trace.go:171","msg":"trace[13436577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2867; }","duration":"179.871355ms","start":"2026-09-10T11:11:52.478822Z","end":"2026-09-10T11:11:52.658693Z","steps":["trace[13436577] 'agreement among raft nodes before linearized reading' (duration: 179.707612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:52.658894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.173258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-10T11:11:52.658928Z","caller":"traceutil/trace.go:171","msg":"trace[596037727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2867; }","duration":"159.25144ms","start":"2026-09-10T11:11:52.499669Z","end":"2026-09-10T11:11:52.65892Z","steps":["trace[596037727] 'agreement among raft nodes before linearized reading' (duration: 159.164108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:52.909145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.945207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:11:52.909506Z","caller":"traceutil/trace.go:171","msg":"trace[504753749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2869; }","duration":"109.316775ms","start":"2026-09-10T11:11:52.800084Z","end":"2026-09-10T11:11:52.909401Z","steps":["trace[504753749] 'range keys from in-memory index tree' (duration: 108.747342ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.337395Z","caller":"traceutil/trace.go:171","msg":"trace[335796285] linearizableReadLoop","detail":"{readStateIndex:3010; appliedIndex:3009; }","duration":"117.134143ms","start":"2026-09-10T11:11:58.220226Z","end":"2026-09-10T11:11:58.33736Z","steps":["trace[335796285] 'read index received' (duration: 116.783906ms)","trace[335796285] 'applied index is now lower than readState.Index' (duration: 348.277µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:11:58.337543Z","caller":"traceutil/trace.go:171","msg":"trace[530287324] transaction","detail":"{read_only:false; response_revision:2899; number_of_response:1; }","duration":"242.089575ms","start":"2026-09-10T11:11:58.095405Z","end":"2026-09-10T11:11:58.337495Z","steps":["trace[530287324] 'process raft request' (duration: 241.747248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.337666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.41928ms","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-09-10T11:11:58.337733Z","caller":"traceutil/trace.go:171","msg":"trace[211185661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2899; }","duration":"117.557433ms","start":"2026-09-10T11:11:58.220159Z","end":"2026-09-10T11:11:58.337716Z","steps":["trace[211185661] 'agreement among raft nodes before linearized reading' (duration: 117.334357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:15:12.305188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.890344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-10T11:15:12.305287Z","caller":"traceutil/trace.go:171","msg":"trace[1776213592] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4119; }","duration":"140.051267ms","start":"2026-09-10T11:15:12.165218Z","end":"2026-09-10T11:15:12.305269Z","steps":["trace[1776213592] 'range keys from in-memory index tree' (duration: 139.675378ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:44.694957Z","caller":"traceutil/trace.go:171","msg":"trace[1000594751] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"130.424509ms","start":"2026-09-10T11:16:44.564509Z","end":"2026-09-10T11:16:44.694933Z","steps":["trace[1000594751] 'process raft request' (duration: 130.293447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:16:47.713865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.15858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-10T11:16:47.713907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.047569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f59w7\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-10T11:16:47.713987Z","caller":"traceutil/trace.go:171","msg":"trace[2004513282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5328; }","duration":"154.361654ms","start":"2026-09-10T11:16:47.559599Z","end":"2026-09-10T11:16:47.713961Z","steps":["trace[2004513282] 'count revisions from in-memory index tree' (duration: 154.087098ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:47.714006Z","caller":"traceutil/trace.go:171","msg":"trace[696950669] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f59w7; range_end:; response_count:1; response_revision:5328; }","duration":"188.212582ms","start":"2026-09-10T11:16:47.525771Z","end":"2026-09-10T11:16:47.713983Z","steps":["trace[696950669] 'range keys from in-memory index tree' (duration: 187.829114ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:50.221513Z","caller":"traceutil/trace.go:171","msg":"trace[966656818] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"178.705258ms","start":"2026-09-10T11:16:50.042778Z","end":"2026-09-10T11:16:50.221483Z","steps":["trace[966656818] 'process raft request' (duration: 146.028975ms)","trace[966656818] 'compare' (duration: 32.545601ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:16:50.241351Z","caller":"traceutil/trace.go:171","msg":"trace[1580731224] linearizableReadLoop","detail":"{readStateIndex:5579; appliedIndex:5577; }","duration":"160.803133ms","start":"2026-09-10T11:16:50.080521Z","end":"2026-09-10T11:16:50.241324Z","steps":["trace[1580731224] 'read index received' (duration: 108.436035ms)","trace[1580731224] 'applied index is now lower than readState.Index' (duration: 52.362117ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:16:50.24147Z","caller":"traceutil/trace.go:171","msg":"trace[11488586] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"198.233938ms","start":"2026-09-10T11:16:50.043225Z","end":"2026-09-10T11:16:50.241458Z","steps":["trace[11488586] 'process raft request' (duration: 197.925762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:16:50.241642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.992064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T11:16:50.241748Z","caller":"traceutil/trace.go:171","msg":"trace[333595418] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:5374; }","duration":"140.145768ms","start":"2026-09-10T11:16:50.101577Z","end":"2026-09-10T11:16:50.241722Z","steps":["trace[333595418] 'agreement among raft nodes before linearized reading' (duration: 139.962794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:16:50.242259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.728262ms","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-09-10T11:16:50.242319Z","caller":"traceutil/trace.go:171","msg":"trace[728806968] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5374; }","duration":"161.827914ms","start":"2026-09-10T11:16:50.080476Z","end":"2026-09-10T11:16:50.242304Z","steps":["trace[728806968] 'agreement among raft nodes before linearized reading' (duration: 161.674191ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:56.757306Z","caller":"traceutil/trace.go:171","msg":"trace[1670406783] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"112.217746ms","start":"2026-09-10T11:16:56.645058Z","end":"2026-09-10T11:16:56.757275Z","steps":["trace[1670406783] 'process raft request' (duration: 111.999641ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:56.757594Z","caller":"traceutil/trace.go:171","msg":"trace[1919558698] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"113.321889ms","start":"2026-09-10T11:16:56.644255Z","end":"2026-09-10T11:16:56.757577Z","steps":["trace[1919558698] 'process raft request' (duration: 93.257807ms)","trace[1919558698] 'compare' (duration: 19.365777ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:16:56.884728Z","caller":"traceutil/trace.go:171","msg":"trace[1730937490] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"105.279156ms","start":"2026-09-10T11:16:56.779412Z","end":"2026-09-10T11:16:56.884691Z","steps":["trace[1730937490] 'process raft request' (duration: 105.156153ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:56.88584Z","caller":"traceutil/trace.go:171","msg":"trace[128210449] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"108.370944ms","start":"2026-09-10T11:16:56.777435Z","end":"2026-09-10T11:16:56.885806Z","steps":["trace[128210449] 'process raft request' (duration: 106.140035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:17:02.629543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.822977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:17:02.629734Z","caller":"traceutil/trace.go:171","msg":"trace[1596996955] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"252.214211ms","start":"2026-09-10T11:17:02.377501Z","end":"2026-09-10T11:17:02.629715Z","steps":["trace[1596996955] 'process raft request' (duration: 141.004416ms)","trace[1596996955] 'compare' (duration: 110.588642ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:17:02.629932Z","caller":"traceutil/trace.go:171","msg":"trace[2073087337] linearizableReadLoop","detail":"{readStateIndex:5823; appliedIndex:5821; }","duration":"223.498053ms","start":"2026-09-10T11:17:02.4064Z","end":"2026-09-10T11:17:02.629898Z","steps":["trace[2073087337] 'read index received' (duration: 112.043473ms)","trace[2073087337] 'applied index is now lower than readState.Index' (duration: 111.45245ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:17:02.629948Z","caller":"traceutil/trace.go:171","msg":"trace[38473939] transaction","detail":"{read_only:false; response_revision:5611; number_of_response:1; }","duration":"251.808842ms","start":"2026-09-10T11:17:02.378102Z","end":"2026-09-10T11:17:02.629911Z","steps":["trace[38473939] 'process raft request' (duration: 251.547396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:17:02.63022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.808939ms","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-09-10T11:17:02.630309Z","caller":"traceutil/trace.go:171","msg":"trace[103633229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5611; }","duration":"223.886011ms","start":"2026-09-10T11:17:02.406379Z","end":"2026-09-10T11:17:02.630265Z","steps":["trace[103633229] 'agreement among raft nodes before linearized reading' (duration: 223.677276ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:17:10.649044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2966} {"level":"info","ts":"2026-09-10T11:17:10.729764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2966,"took":"79.181596ms","hash":3569690423,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":31813632,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-10T11:17:10.729847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3569690423,"revision":2966,"compact-revision":-1} {"level":"info","ts":"2026-09-10T11:17:19.646659Z","caller":"traceutil/trace.go:171","msg":"trace[153571839] transaction","detail":"{read_only:false; response_revision:5901; number_of_response:1; }","duration":"207.98473ms","start":"2026-09-10T11:17:19.438648Z","end":"2026-09-10T11:17:19.646633Z","steps":["trace[153571839] 'process raft request' (duration: 207.846997ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:22:10.656693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5747} {"level":"info","ts":"2026-09-10T11:22:10.753934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5747,"took":"95.326213ms","hash":1897364519,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":26742784,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-10T11:22:10.754016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1897364519,"revision":5747,"compact-revision":2966} {"level":"info","ts":"2026-09-10T11:23:35.077726Z","caller":"traceutil/trace.go:171","msg":"trace[344194098] linearizableReadLoop","detail":"{readStateIndex:9008; appliedIndex:9007; }","duration":"121.647099ms","start":"2026-09-10T11:23:34.956058Z","end":"2026-09-10T11:23:35.077705Z","steps":["trace[344194098] 'read index received' (duration: 121.540287ms)","trace[344194098] 'applied index is now lower than readState.Index' (duration: 105.832µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:23:35.077804Z","caller":"traceutil/trace.go:171","msg":"trace[1259726935] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"126.687268ms","start":"2026-09-10T11:23:34.951079Z","end":"2026-09-10T11:23:35.077766Z","steps":["trace[1259726935] 'process raft request' (duration: 126.514264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:23:35.077915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.840454ms","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-09-10T11:23:35.077959Z","caller":"traceutil/trace.go:171","msg":"trace[980286570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8698; }","duration":"121.913265ms","start":"2026-09-10T11:23:34.956025Z","end":"2026-09-10T11:23:35.077939Z","steps":["trace[980286570] 'agreement among raft nodes before linearized reading' (duration: 121.759652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:23:36.757443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.866318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:23:36.757524Z","caller":"traceutil/trace.go:171","msg":"trace[492380302] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8704; }","duration":"315.004421ms","start":"2026-09-10T11:23:36.442498Z","end":"2026-09-10T11:23:36.757502Z","steps":["trace[492380302] 'count revisions from in-memory index tree' (duration: 314.776146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:23:36.757595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:23:36.442476Z","time spent":"315.071172ms","remote":"127.0.0.1:39694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-09-10T11:23:36.757593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.858408ms","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-09-10T11:23:36.757606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.001799ms","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-09-10T11:23:36.757657Z","caller":"traceutil/trace.go:171","msg":"trace[207023471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8704; }","duration":"190.979731ms","start":"2026-09-10T11:23:36.56666Z","end":"2026-09-10T11:23:36.75764Z","steps":["trace[207023471] 'range keys from in-memory index tree' (duration: 190.686985ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:23:36.757671Z","caller":"traceutil/trace.go:171","msg":"trace[463514150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8704; }","duration":"170.112991ms","start":"2026-09-10T11:23:36.587542Z","end":"2026-09-10T11:23:36.757655Z","steps":["trace[463514150] 'range keys from in-memory index tree' (duration: 169.821225ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:24:12.889859Z","caller":"traceutil/trace.go:171","msg":"trace[1561863641] transaction","detail":"{read_only:false; response_revision:8949; number_of_response:1; }","duration":"284.183197ms","start":"2026-09-10T11:24:12.605643Z","end":"2026-09-10T11:24:12.889826Z","steps":["trace[1561863641] 'process raft request' (duration: 283.970372ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:24:12.904923Z","caller":"traceutil/trace.go:171","msg":"trace[1172925982] linearizableReadLoop","detail":"{readStateIndex:9270; appliedIndex:9269; }","duration":"103.544789ms","start":"2026-09-10T11:24:12.801344Z","end":"2026-09-10T11:24:12.904889Z","steps":["trace[1172925982] 'read index received' (duration: 88.949955ms)","trace[1172925982] 'applied index is now lower than readState.Index' (duration: 14.592854ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:24:12.904962Z","caller":"traceutil/trace.go:171","msg":"trace[2085578510] transaction","detail":"{read_only:false; response_revision:8950; number_of_response:1; }","duration":"253.1422ms","start":"2026-09-10T11:24:12.651795Z","end":"2026-09-10T11:24:12.904937Z","steps":["trace[2085578510] 'process raft request' (duration: 252.870304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:24:12.90518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.719022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:24:12.905253Z","caller":"traceutil/trace.go:171","msg":"trace[871569476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8950; }","duration":"103.928397ms","start":"2026-09-10T11:24:12.801295Z","end":"2026-09-10T11:24:12.905224Z","steps":["trace[871569476] 'agreement among raft nodes before linearized reading' (duration: 103.721092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:24:20.562813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.652977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-10T11:24:20.562904Z","caller":"traceutil/trace.go:171","msg":"trace[882950084] linearizableReadLoop","detail":"{readStateIndex:9353; appliedIndex:9352; }","duration":"182.591431ms","start":"2026-09-10T11:24:20.380299Z","end":"2026-09-10T11:24:20.56289Z","steps":["trace[882950084] 'read index received' (duration: 15.703908ms)","trace[882950084] 'applied index is now lower than readState.Index' (duration: 166.886402ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:24:20.562976Z","caller":"traceutil/trace.go:171","msg":"trace[985125510] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9029; }","duration":"184.047831ms","start":"2026-09-10T11:24:20.37892Z","end":"2026-09-10T11:24:20.562968Z","steps":["trace[985125510] 'compare' (duration: 166.228588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:24:20.563422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.084312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-f5fwl\" ","response":"range_response_count:1 size:4640"} {"level":"info","ts":"2026-09-10T11:24:20.563521Z","caller":"traceutil/trace.go:171","msg":"trace[92182641] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-f5fwl; range_end:; response_count:1; response_revision:9029; }","duration":"183.207964ms","start":"2026-09-10T11:24:20.380278Z","end":"2026-09-10T11:24:20.563485Z","steps":["trace[92182641] 'agreement among raft nodes before linearized reading' (duration: 183.00002ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:24:21.191245Z","caller":"traceutil/trace.go:171","msg":"trace[865984148] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"168.467026ms","start":"2026-09-10T11:24:21.02274Z","end":"2026-09-10T11:24:21.191207Z","steps":["trace[865984148] 'process raft request' (duration: 168.112949ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:24:21.298307Z","caller":"traceutil/trace.go:171","msg":"trace[1499412209] transaction","detail":"{read_only:false; response_revision:9036; number_of_response:1; }","duration":"101.871453ms","start":"2026-09-10T11:24:21.196409Z","end":"2026-09-10T11:24:21.29828Z","steps":["trace[1499412209] 'process raft request' (duration: 63.906755ms)","trace[1499412209] 'compare' (duration: 37.200172ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:24:21.390916Z","caller":"traceutil/trace.go:171","msg":"trace[172295062] transaction","detail":"{read_only:false; response_revision:9039; number_of_response:1; }","duration":"128.183049ms","start":"2026-09-10T11:24:21.262705Z","end":"2026-09-10T11:24:21.390888Z","steps":["trace[172295062] 'process raft request' (duration: 127.981055ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:24:21.391099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.404231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-f5fwl\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-09-10T11:24:21.391447Z","caller":"traceutil/trace.go:171","msg":"trace[415591] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-f5fwl; range_end:; response_count:1; response_revision:9039; }","duration":"139.553435ms","start":"2026-09-10T11:24:21.251633Z","end":"2026-09-10T11:24:21.391187Z","steps":["trace[415591] 'agreement among raft nodes before linearized reading' (duration: 139.262199ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:24:21.490008Z","caller":"traceutil/trace.go:171","msg":"trace[1751002579] transaction","detail":"{read_only:false; response_revision:9040; number_of_response:1; }","duration":"186.360641ms","start":"2026-09-10T11:24:21.303603Z","end":"2026-09-10T11:24:21.489964Z","steps":["trace[1751002579] 'process raft request' (duration: 114.328671ms)","trace[1751002579] 'compare' (duration: 71.703963ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:24:21.490919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.507566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-09-10T11:24:21.491205Z","caller":"traceutil/trace.go:171","msg":"trace[2017622] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:9040; }","duration":"122.236491ms","start":"2026-09-10T11:24:21.368946Z","end":"2026-09-10T11:24:21.491182Z","steps":["trace[2017622] 'agreement among raft nodes before linearized reading' (duration: 121.054465ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:24:33.927268Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002503.wal"} {"level":"info","ts":"2026-09-10T11:25:21.903776Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-10T11:25:21.914319Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-10T11:25:21.914436Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-10T11:25:41.639059Z","caller":"traceutil/trace.go:171","msg":"trace[1189541388] transaction","detail":"{read_only:false; response_revision:9807; number_of_response:1; }","duration":"139.272637ms","start":"2026-09-10T11:25:41.49975Z","end":"2026-09-10T11:25:41.639022Z","steps":["trace[1189541388] 'process raft request' (duration: 116.096309ms)","trace[1189541388] 'compare' (duration: 23.035055ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:27:10.664842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8021} {"level":"info","ts":"2026-09-10T11:27:10.742457Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8021,"took":"75.089315ms","hash":2427000602,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":28155904,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T11:27:10.74262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2427000602,"revision":8021,"compact-revision":5747} {"level":"info","ts":"2026-09-10T11:29:32.948366Z","caller":"traceutil/trace.go:171","msg":"trace[1140632690] transaction","detail":"{read_only:false; response_revision:11687; number_of_response:1; }","duration":"101.909652ms","start":"2026-09-10T11:29:32.846415Z","end":"2026-09-10T11:29:32.948325Z","steps":["trace[1140632690] 'process raft request' (duration: 45.653522ms)","trace[1140632690] 'compare' (duration: 55.548295ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:29:33.188541Z","caller":"traceutil/trace.go:171","msg":"trace[95789630] transaction","detail":"{read_only:false; response_revision:11688; number_of_response:1; }","duration":"115.226399ms","start":"2026-09-10T11:29:33.073267Z","end":"2026-09-10T11:29:33.188494Z","steps":["trace[95789630] 'process raft request' (duration: 60.263916ms)","trace[95789630] 'compare' (duration: 54.765809ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:32:10.673495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10417} {"level":"info","ts":"2026-09-10T11:32:10.783215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10417,"took":"105.748004ms","hash":110271827,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":27635712,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-10T11:32:10.783312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":110271827,"revision":10417,"compact-revision":8021} {"level":"warn","ts":"2026-09-10T11:36:12.910988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.892453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:36:12.911121Z","caller":"traceutil/trace.go:171","msg":"trace[762108323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14358; }","duration":"111.082608ms","start":"2026-09-10T11:36:12.800013Z","end":"2026-09-10T11:36:12.911096Z","steps":["trace[762108323] 'range keys from in-memory index tree' (duration: 110.798312ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:36:13.4029Z","caller":"traceutil/trace.go:171","msg":"trace[2093312592] linearizableReadLoop","detail":"{readStateIndex:14856; appliedIndex:14855; }","duration":"344.319199ms","start":"2026-09-10T11:36:13.058544Z","end":"2026-09-10T11:36:13.402863Z","steps":["trace[2093312592] 'read index received' (duration: 343.91184ms)","trace[2093312592] 'applied index is now lower than readState.Index' (duration: 406.219µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:36:13.402995Z","caller":"traceutil/trace.go:171","msg":"trace[1438481796] transaction","detail":"{read_only:false; response_revision:14359; number_of_response:1; }","duration":"435.201734ms","start":"2026-09-10T11:36:12.967727Z","end":"2026-09-10T11:36:13.402929Z","steps":["trace[1438481796] 'process raft request' (duration: 434.856877ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:36:13.403146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.678112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-10T11:36:13.403202Z","caller":"traceutil/trace.go:171","msg":"trace[672907680] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:14359; }","duration":"239.747563ms","start":"2026-09-10T11:36:13.163422Z","end":"2026-09-10T11:36:13.403169Z","steps":["trace[672907680] 'agreement among raft nodes before linearized reading' (duration: 239.644031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:36:13.403332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:36:12.967682Z","time spent":"435.443408ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12709,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:36:13.403417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.81541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f59w7\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-10T11:36:13.403644Z","caller":"traceutil/trace.go:171","msg":"trace[1393134149] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f59w7; range_end:; response_count:1; response_revision:14359; }","duration":"345.067565ms","start":"2026-09-10T11:36:13.058499Z","end":"2026-09-10T11:36:13.403567Z","steps":["trace[1393134149] 'agreement among raft nodes before linearized reading' (duration: 344.541684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:36:13.403741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:36:13.058474Z","time spent":"345.250159ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-f59w7\" "} {"level":"info","ts":"2026-09-10T11:37:10.68367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12762} {"level":"info","ts":"2026-09-10T11:37:10.77344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12762,"took":"86.522179ms","hash":808265229,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":30294016,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-10T11:37:10.77354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":808265229,"revision":12762,"compact-revision":10417} {"level":"info","ts":"2026-09-10T11:37:52.174369Z","caller":"traceutil/trace.go:171","msg":"trace[1263575814] transaction","detail":"{read_only:false; response_revision:15425; number_of_response:1; }","duration":"151.646649ms","start":"2026-09-10T11:37:52.022682Z","end":"2026-09-10T11:37:52.174329Z","steps":["trace[1263575814] 'process raft request' (duration: 151.106978ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:37:58.530243Z","caller":"traceutil/trace.go:171","msg":"trace[1482491998] linearizableReadLoop","detail":"{readStateIndex:15990; appliedIndex:15989; }","duration":"311.036815ms","start":"2026-09-10T11:37:58.219176Z","end":"2026-09-10T11:37:58.530213Z","steps":["trace[1482491998] 'read index received' (duration: 310.81947ms)","trace[1482491998] 'applied index is now lower than readState.Index' (duration: 216.115µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:37:58.530557Z","caller":"traceutil/trace.go:171","msg":"trace[1674625042] transaction","detail":"{read_only:false; response_revision:15457; number_of_response:1; }","duration":"327.220382ms","start":"2026-09-10T11:37:58.20332Z","end":"2026-09-10T11:37:58.530541Z","steps":["trace[1674625042] 'process raft request' (duration: 326.754473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:37:58.530675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:37:58.203295Z","time spent":"327.303115ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:37:58.530884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.702748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:37:58.530952Z","caller":"traceutil/trace.go:171","msg":"trace[994652688] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15457; }","duration":"311.77705ms","start":"2026-09-10T11:37:58.219138Z","end":"2026-09-10T11:37:58.530915Z","steps":["trace[994652688] 'agreement among raft nodes before linearized reading' (duration: 311.707509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:37:58.531002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:37:58.219093Z","time spent":"311.900013ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-09-10T11:37:58.531204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.896252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:37:58.531243Z","caller":"traceutil/trace.go:171","msg":"trace[568826157] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15457; }","duration":"311.981784ms","start":"2026-09-10T11:37:58.219251Z","end":"2026-09-10T11:37:58.531233Z","steps":["trace[568826157] 'agreement among raft nodes before linearized reading' (duration: 311.924863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:37:58.531274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:37:58.219212Z","time spent":"312.056466ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-09-10T11:37:58.532252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.865293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:37:58.532301Z","caller":"traceutil/trace.go:171","msg":"trace[1400462105] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15457; }","duration":"312.933255ms","start":"2026-09-10T11:37:58.219355Z","end":"2026-09-10T11:37:58.532288Z","steps":["trace[1400462105] 'agreement among raft nodes before linearized reading' (duration: 312.842682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:37:58.532337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:37:58.219253Z","time spent":"313.076278ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-09-10T11:37:58.532689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.654868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-10T11:37:58.532726Z","caller":"traceutil/trace.go:171","msg":"trace[2093545470] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15457; }","duration":"203.713958ms","start":"2026-09-10T11:37:58.329002Z","end":"2026-09-10T11:37:58.532716Z","steps":["trace[2093545470] 'agreement among raft nodes before linearized reading' (duration: 203.610706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:37:58.532902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.213815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:37:58.532968Z","caller":"traceutil/trace.go:171","msg":"trace[977748665] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15457; }","duration":"208.296017ms","start":"2026-09-10T11:37:58.324659Z","end":"2026-09-10T11:37:58.532955Z","steps":["trace[977748665] 'agreement among raft nodes before linearized reading' (duration: 208.218786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:37:58.533153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.342542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-10T11:37:58.533187Z","caller":"traceutil/trace.go:171","msg":"trace[162490337] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15457; }","duration":"312.412254ms","start":"2026-09-10T11:37:58.220767Z","end":"2026-09-10T11:37:58.533179Z","steps":["trace[162490337] 'agreement among raft nodes before linearized reading' (duration: 312.329682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:37:58.53321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:37:58.220745Z","time spent":"312.458656ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-09-10T11:37:58.533297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.946141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:37:58.533325Z","caller":"traceutil/trace.go:171","msg":"trace[510439331] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15457; }","duration":"208.985732ms","start":"2026-09-10T11:37:58.324332Z","end":"2026-09-10T11:37:58.533317Z","steps":["trace[510439331] 'agreement among raft nodes before linearized reading' (duration: 208.929541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:37:58.533464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.125974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:37:58.533562Z","caller":"traceutil/trace.go:171","msg":"trace[609819262] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15457; }","duration":"209.293548ms","start":"2026-09-10T11:37:58.324249Z","end":"2026-09-10T11:37:58.533542Z","steps":["trace[609819262] 'agreement among raft nodes before linearized reading' (duration: 209.072123ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:37:58.707904Z","caller":"traceutil/trace.go:171","msg":"trace[262356770] transaction","detail":"{read_only:false; response_revision:15459; number_of_response:1; }","duration":"168.896069ms","start":"2026-09-10T11:37:58.538992Z","end":"2026-09-10T11:37:58.707888Z","steps":["trace[262356770] 'process raft request' (duration: 168.798287ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:37:58.707938Z","caller":"traceutil/trace.go:171","msg":"trace[1182173373] transaction","detail":"{read_only:false; response_revision:15458; number_of_response:1; }","duration":"169.299558ms","start":"2026-09-10T11:37:58.538578Z","end":"2026-09-10T11:37:58.707878Z","steps":["trace[1182173373] 'process raft request' (duration: 163.921582ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:37:58.708181Z","caller":"traceutil/trace.go:171","msg":"trace[493317488] transaction","detail":"{read_only:false; response_revision:15460; number_of_response:1; }","duration":"168.842198ms","start":"2026-09-10T11:37:58.539036Z","end":"2026-09-10T11:37:58.707879Z","steps":["trace[493317488] 'process raft request' (duration: 168.790107ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:37:58.708453Z","caller":"traceutil/trace.go:171","msg":"trace[200783765] transaction","detail":"{read_only:false; response_revision:15461; number_of_response:1; }","duration":"152.934596ms","start":"2026-09-10T11:37:58.55551Z","end":"2026-09-10T11:37:58.708444Z","steps":["trace[200783765] 'process raft request' (duration: 152.343343ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:42:10.692954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15155} {"level":"info","ts":"2026-09-10T11:42:10.797733Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15155,"took":"101.851755ms","hash":3386952417,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":31391744,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-10T11:42:10.797799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3386952417,"revision":15155,"compact-revision":12762} {"level":"info","ts":"2026-09-10T11:43:54.591932Z","caller":"traceutil/trace.go:171","msg":"trace[75422082] transaction","detail":"{read_only:false; response_revision:18053; number_of_response:1; }","duration":"133.870693ms","start":"2026-09-10T11:43:54.458024Z","end":"2026-09-10T11:43:54.591894Z","steps":["trace[75422082] 'process raft request' (duration: 117.463261ms)","trace[75422082] 'compare' (duration: 16.031744ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:44:00.358347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.316151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lcqjj\" ","response":"range_response_count:1 size:20759"} {"level":"info","ts":"2026-09-10T11:44:00.358473Z","caller":"traceutil/trace.go:171","msg":"trace[1412146617] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lcqjj; range_end:; response_count:1; response_revision:18103; }","duration":"228.504905ms","start":"2026-09-10T11:44:00.129939Z","end":"2026-09-10T11:44:00.358444Z","steps":["trace[1412146617] 'range keys from in-memory index tree' (duration: 228.124727ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:44:00.720148Z","caller":"traceutil/trace.go:171","msg":"trace[118112112] transaction","detail":"{read_only:false; response_revision:18104; number_of_response:1; }","duration":"335.884539ms","start":"2026-09-10T11:44:00.383858Z","end":"2026-09-10T11:44:00.719742Z","steps":["trace[118112112] 'process raft request' (duration: 335.743906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:44:00.720304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:44:00.38384Z","time spent":"336.380479ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:44:00.877107Z","caller":"traceutil/trace.go:171","msg":"trace[867393970] transaction","detail":"{read_only:false; response_revision:18105; number_of_response:1; }","duration":"264.577438ms","start":"2026-09-10T11:44:00.612502Z","end":"2026-09-10T11:44:00.877079Z","steps":["trace[867393970] 'process raft request' (duration: 200.054623ms)","trace[867393970] 'compare' (duration: 64.324841ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:44:00.877684Z","caller":"traceutil/trace.go:171","msg":"trace[1959967865] linearizableReadLoop","detail":"{readStateIndex:18726; appliedIndex:18723; }","duration":"195.25739ms","start":"2026-09-10T11:44:00.682405Z","end":"2026-09-10T11:44:00.877663Z","steps":["trace[1959967865] 'read index received' (duration: 37.172168ms)","trace[1959967865] 'applied index is now lower than readState.Index' (duration: 158.084072ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:44:00.877767Z","caller":"traceutil/trace.go:171","msg":"trace[405400102] transaction","detail":"{read_only:false; response_revision:18107; number_of_response:1; }","duration":"182.598179ms","start":"2026-09-10T11:44:00.695146Z","end":"2026-09-10T11:44:00.877744Z","steps":["trace[405400102] 'process raft request' (duration: 182.425335ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:44:00.877943Z","caller":"traceutil/trace.go:171","msg":"trace[1123322035] transaction","detail":"{read_only:false; response_revision:18106; number_of_response:1; }","duration":"212.668934ms","start":"2026-09-10T11:44:00.665232Z","end":"2026-09-10T11:44:00.877901Z","steps":["trace[1123322035] 'process raft request' (duration: 211.805346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:44:00.878021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.590277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T11:44:00.878217Z","caller":"traceutil/trace.go:171","msg":"trace[163967] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:18107; }","duration":"195.772691ms","start":"2026-09-10T11:44:00.682364Z","end":"2026-09-10T11:44:00.878137Z","steps":["trace[163967] 'agreement among raft nodes before linearized reading' (duration: 195.586737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:44:00.878785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.617035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-dmjf6\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-09-10T11:44:00.878828Z","caller":"traceutil/trace.go:171","msg":"trace[135959457] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-dmjf6; range_end:; response_count:1; response_revision:18107; }","duration":"124.698596ms","start":"2026-09-10T11:44:00.754119Z","end":"2026-09-10T11:44:00.878817Z","steps":["trace[135959457] 'agreement among raft nodes before linearized reading' (duration: 123.853738ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:45:30.135849Z","caller":"traceutil/trace.go:171","msg":"trace[2046262331] linearizableReadLoop","detail":"{readStateIndex:19668; appliedIndex:19667; }","duration":"385.132267ms","start":"2026-09-10T11:45:29.750699Z","end":"2026-09-10T11:45:30.135831Z","steps":["trace[2046262331] 'read index received' (duration: 384.959333ms)","trace[2046262331] 'applied index is now lower than readState.Index' (duration: 172.124µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:45:30.136071Z","caller":"traceutil/trace.go:171","msg":"trace[984411674] transaction","detail":"{read_only:false; response_revision:19021; number_of_response:1; }","duration":"420.536453ms","start":"2026-09-10T11:45:29.715527Z","end":"2026-09-10T11:45:30.136064Z","steps":["trace[984411674] 'process raft request' (duration: 420.187226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:45:30.136243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:45:29.715512Z","time spent":"420.578344ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:45:30.136493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.7889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T11:45:30.136563Z","caller":"traceutil/trace.go:171","msg":"trace[1111625178] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:19021; }","duration":"385.821931ms","start":"2026-09-10T11:45:29.750692Z","end":"2026-09-10T11:45:30.136514Z","steps":["trace[1111625178] 'agreement among raft nodes before linearized reading' (duration: 385.76706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:45:30.136591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:45:29.750628Z","time spent":"385.955963ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-09-10T11:45:30.136805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.78815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-10T11:45:30.136841Z","caller":"traceutil/trace.go:171","msg":"trace[1096276988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19021; }","duration":"378.859691ms","start":"2026-09-10T11:45:29.757967Z","end":"2026-09-10T11:45:30.136826Z","steps":["trace[1096276988] 'agreement among raft nodes before linearized reading' (duration: 378.752009ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:45:30.136866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:45:29.757939Z","time spent":"378.920023ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-10T11:45:30.137052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.30708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-10T11:45:30.13708Z","caller":"traceutil/trace.go:171","msg":"trace[1494668751] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19021; }","duration":"291.383372ms","start":"2026-09-10T11:45:29.845688Z","end":"2026-09-10T11:45:30.137072Z","steps":["trace[1494668751] 'agreement among raft nodes before linearized reading' (duration: 291.31348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:45:30.137226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.73561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-10T11:45:30.137248Z","caller":"traceutil/trace.go:171","msg":"trace[1648576067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19021; }","duration":"364.76684ms","start":"2026-09-10T11:45:29.772475Z","end":"2026-09-10T11:45:30.137242Z","steps":["trace[1648576067] 'agreement among raft nodes before linearized reading' (duration: 364.711849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:45:30.13726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:45:29.772471Z","time spent":"364.78611ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-10T11:45:30.137392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.378373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-10T11:45:30.137418Z","caller":"traceutil/trace.go:171","msg":"trace[1541401840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19021; }","duration":"365.445125ms","start":"2026-09-10T11:45:29.771967Z","end":"2026-09-10T11:45:30.137413Z","steps":["trace[1541401840] 'agreement among raft nodes before linearized reading' (duration: 365.377793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:45:30.137436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:45:29.771944Z","time spent":"365.487155ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-10T11:45:31.558374Z","caller":"traceutil/trace.go:171","msg":"trace[1186209005] transaction","detail":"{read_only:false; response_revision:19033; number_of_response:1; }","duration":"157.395475ms","start":"2026-09-10T11:45:31.400939Z","end":"2026-09-10T11:45:31.558334Z","steps":["trace[1186209005] 'process raft request' (duration: 157.15069ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:45:33.4862Z","caller":"traceutil/trace.go:171","msg":"trace[780728799] transaction","detail":"{read_only:false; response_revision:19044; number_of_response:1; }","duration":"104.149658ms","start":"2026-09-10T11:45:33.38203Z","end":"2026-09-10T11:45:33.48618Z","steps":["trace[780728799] 'process raft request' (duration: 104.000575ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:45:33.606602Z","caller":"traceutil/trace.go:171","msg":"trace[2121527229] linearizableReadLoop","detail":"{readStateIndex:19692; appliedIndex:19690; }","duration":"142.363004ms","start":"2026-09-10T11:45:33.464203Z","end":"2026-09-10T11:45:33.606565Z","steps":["trace[2121527229] 'read index received' (duration: 21.945279ms)","trace[2121527229] 'applied index is now lower than readState.Index' (duration: 120.416005ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:45:33.606939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.719952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-10T11:45:33.606995Z","caller":"traceutil/trace.go:171","msg":"trace[681276706] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:19045; }","duration":"142.854925ms","start":"2026-09-10T11:45:33.464122Z","end":"2026-09-10T11:45:33.606977Z","steps":["trace[681276706] 'agreement among raft nodes before linearized reading' (duration: 142.561509ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:45:33.607363Z","caller":"traceutil/trace.go:171","msg":"trace[1890553132] transaction","detail":"{read_only:false; response_revision:19045; number_of_response:1; }","duration":"216.93691ms","start":"2026-09-10T11:45:33.390407Z","end":"2026-09-10T11:45:33.607343Z","steps":["trace[1890553132] 'process raft request' (duration: 195.45234ms)","trace[1890553132] 'compare' (duration: 20.602981ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:46:04.967688Z","caller":"traceutil/trace.go:171","msg":"trace[1273389597] transaction","detail":"{read_only:false; response_revision:19272; number_of_response:1; }","duration":"104.055936ms","start":"2026-09-10T11:46:04.86359Z","end":"2026-09-10T11:46:04.967646Z","steps":["trace[1273389597] 'process raft request' (duration: 70.926388ms)","trace[1273389597] 'compare' (duration: 32.944133ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:46:05.172842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.838431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:1 size:11072"} {"level":"info","ts":"2026-09-10T11:46:05.172936Z","caller":"traceutil/trace.go:171","msg":"trace[2042488021] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:1; response_revision:19277; }","duration":"110.969814ms","start":"2026-09-10T11:46:05.061946Z","end":"2026-09-10T11:46:05.172916Z","steps":["trace[2042488021] 'agreement among raft nodes before linearized reading' (duration: 110.77682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:46:05.173478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.979274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T11:46:05.173793Z","caller":"traceutil/trace.go:171","msg":"trace[670458964] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19277; }","duration":"111.358912ms","start":"2026-09-10T11:46:05.062408Z","end":"2026-09-10T11:46:05.173767Z","steps":["trace[670458964] 'agreement among raft nodes before linearized reading' (duration: 110.941273ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:46:15.940027Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"54232768b5552f01","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-10T11:46:15.949137Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-10T11:46:15.949365Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-10T11:47:10.700502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17369} {"level":"info","ts":"2026-09-10T11:47:10.80208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17369,"took":"97.561786ms","hash":317115222,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":34394112,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-10T11:47:10.802138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":317115222,"revision":17369,"compact-revision":15155} {"level":"info","ts":"2026-09-10T11:49:59.233189Z","caller":"traceutil/trace.go:171","msg":"trace[679493994] transaction","detail":"{read_only:false; response_revision:20845; number_of_response:1; }","duration":"146.493239ms","start":"2026-09-10T11:49:59.086669Z","end":"2026-09-10T11:49:59.233162Z","steps":["trace[679493994] 'process raft request' (duration: 146.334066ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:50:03.490687Z","caller":"traceutil/trace.go:171","msg":"trace[2125302927] transaction","detail":"{read_only:false; response_revision:20881; number_of_response:1; }","duration":"245.466771ms","start":"2026-09-10T11:50:03.245172Z","end":"2026-09-10T11:50:03.490638Z","steps":["trace[2125302927] 'process raft request' (duration: 245.198235ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:50:03.658922Z","caller":"traceutil/trace.go:171","msg":"trace[425721308] transaction","detail":"{read_only:false; response_revision:20882; number_of_response:1; }","duration":"163.027784ms","start":"2026-09-10T11:50:03.495855Z","end":"2026-09-10T11:50:03.658882Z","steps":["trace[425721308] 'process raft request' (duration: 65.930132ms)","trace[425721308] 'compare' (duration: 96.767915ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:50:04.098544Z","caller":"traceutil/trace.go:171","msg":"trace[2093914426] linearizableReadLoop","detail":"{readStateIndex:21595; appliedIndex:21595; }","duration":"225.734997ms","start":"2026-09-10T11:50:03.872776Z","end":"2026-09-10T11:50:04.098511Z","steps":["trace[2093914426] 'read index received' (duration: 225.720547ms)","trace[2093914426] 'applied index is now lower than readState.Index' (duration: 11.79µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:50:04.098751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.141438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T11:50:04.098803Z","caller":"traceutil/trace.go:171","msg":"trace[1038761080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20883; }","duration":"199.26499ms","start":"2026-09-10T11:50:03.899526Z","end":"2026-09-10T11:50:04.098791Z","steps":["trace[1038761080] 'agreement among raft nodes before linearized reading' (duration: 199.138498ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:50:04.098552Z","caller":"traceutil/trace.go:171","msg":"trace[410008342] transaction","detail":"{read_only:false; response_revision:20883; number_of_response:1; }","duration":"294.462319ms","start":"2026-09-10T11:50:03.804051Z","end":"2026-09-10T11:50:04.098514Z","steps":["trace[410008342] 'process raft request' (duration: 294.247765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:50:04.099297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.491513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-10T11:50:04.099339Z","caller":"traceutil/trace.go:171","msg":"trace[193444255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20883; }","duration":"226.599996ms","start":"2026-09-10T11:50:03.872726Z","end":"2026-09-10T11:50:04.099326Z","steps":["trace[193444255] 'agreement among raft nodes before linearized reading' (duration: 226.419502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:50:04.468853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.737308ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-09-10T11:50:04.469137Z","caller":"traceutil/trace.go:171","msg":"trace[115965586] linearizableReadLoop","detail":"{readStateIndex:21598; appliedIndex:21595; }","duration":"260.692497ms","start":"2026-09-10T11:50:04.208427Z","end":"2026-09-10T11:50:04.469119Z","steps":["trace[115965586] 'read index received' (duration: 224.868609ms)","trace[115965586] 'applied index is now lower than readState.Index' (duration: 35.823038ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:50:04.469222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.10027Z","time spent":"368.949516ms","remote":"127.0.0.1:39312","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-10T11:50:04.469572Z","caller":"traceutil/trace.go:171","msg":"trace[849622154] transaction","detail":"{read_only:false; response_revision:20884; number_of_response:1; }","duration":"366.051184ms","start":"2026-09-10T11:50:04.103508Z","end":"2026-09-10T11:50:04.469559Z","steps":["trace[849622154] 'process raft request' (duration: 365.446301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:50:04.469671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.103488Z","time spent":"366.112246ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:50:04.470223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.775149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-10T11:50:04.470293Z","caller":"traceutil/trace.go:171","msg":"trace[1515033623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20885; }","duration":"261.894881ms","start":"2026-09-10T11:50:04.208384Z","end":"2026-09-10T11:50:04.470279Z","steps":["trace[1515033623] 'agreement among raft nodes before linearized reading' (duration: 261.653606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:50:04.470379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.631301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-10T11:50:04.47038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.235352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-10T11:50:04.470445Z","caller":"traceutil/trace.go:171","msg":"trace[1163390794] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20885; }","duration":"257.722322ms","start":"2026-09-10T11:50:04.212705Z","end":"2026-09-10T11:50:04.470427Z","steps":["trace[1163390794] 'agreement among raft nodes before linearized reading' (duration: 257.517058ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:50:04.470446Z","caller":"traceutil/trace.go:171","msg":"trace[1520701613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20885; }","duration":"208.344124ms","start":"2026-09-10T11:50:04.262088Z","end":"2026-09-10T11:50:04.470432Z","steps":["trace[1520701613] 'agreement among raft nodes before linearized reading' (duration: 208.1526ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:50:04.469794Z","caller":"traceutil/trace.go:171","msg":"trace[1769568303] transaction","detail":"{read_only:false; response_revision:20885; number_of_response:1; }","duration":"365.681476ms","start":"2026-09-10T11:50:04.104103Z","end":"2026-09-10T11:50:04.469785Z","steps":["trace[1769568303] 'process raft request' (duration: 364.974171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:50:04.472585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.104094Z","time spent":"368.459815ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:50:04.793753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.062114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-10T11:50:04.79402Z","caller":"traceutil/trace.go:171","msg":"trace[800695644] transaction","detail":"{read_only:false; response_revision:20886; number_of_response:1; }","duration":"322.797987ms","start":"2026-09-10T11:50:04.471197Z","end":"2026-09-10T11:50:04.793995Z","steps":["trace[800695644] 'process raft request' (duration: 181.384926ms)","trace[800695644] 'compare' (duration: 140.922371ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:50:04.794106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.471171Z","time spent":"322.891809ms","remote":"127.0.0.1:39312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:50:04.833654Z","caller":"traceutil/trace.go:171","msg":"trace[857998256] transaction","detail":"{read_only:false; response_revision:20887; number_of_response:1; }","duration":"357.544932ms","start":"2026-09-10T11:50:04.476068Z","end":"2026-09-10T11:50:04.833613Z","steps":["trace[857998256] 'process raft request' (duration: 356.926659ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:50:04.833678Z","caller":"traceutil/trace.go:171","msg":"trace[774477939] transaction","detail":"{read_only:false; response_revision:20889; number_of_response:1; }","duration":"357.348757ms","start":"2026-09-10T11:50:04.476293Z","end":"2026-09-10T11:50:04.833642Z","steps":["trace[774477939] 'process raft request' (duration: 356.911018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:50:04.833904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.476043Z","time spent":"357.693805ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:50:04.833896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.476259Z","time spent":"357.494051ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:50:04.833943Z","caller":"traceutil/trace.go:171","msg":"trace[1902969151] transaction","detail":"{read_only:false; response_revision:20888; number_of_response:1; }","duration":"357.686706ms","start":"2026-09-10T11:50:04.476176Z","end":"2026-09-10T11:50:04.833863Z","steps":["trace[1902969151] 'process raft request' (duration: 356.987801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:50:04.834134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.476168Z","time spent":"357.877679ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:50:05.142663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.215962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-10T11:50:05.1429Z","caller":"traceutil/trace.go:171","msg":"trace[1535642639] transaction","detail":"{read_only:false; response_revision:20891; number_of_response:1; }","duration":"298.838933ms","start":"2026-09-10T11:50:04.844043Z","end":"2026-09-10T11:50:05.142882Z","steps":["trace[1535642639] 'process raft request' (duration: 298.738181ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:50:05.142892Z","caller":"traceutil/trace.go:171","msg":"trace[947885237] transaction","detail":"{read_only:false; response_revision:20890; number_of_response:1; }","duration":"326.504566ms","start":"2026-09-10T11:50:04.816302Z","end":"2026-09-10T11:50:05.142807Z","steps":["trace[947885237] 'process raft request' (duration: 145.838815ms)","trace[947885237] 'compare' (duration: 179.760792ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:50:05.143006Z","caller":"traceutil/trace.go:171","msg":"trace[796601085] linearizableReadLoop","detail":"{readStateIndex:21603; appliedIndex:21602; }","duration":"307.703293ms","start":"2026-09-10T11:50:04.835279Z","end":"2026-09-10T11:50:05.142982Z","steps":["trace[796601085] 'read index received' (duration: 127.804968ms)","trace[796601085] 'applied index is now lower than readState.Index' (duration: 179.895635ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:50:05.143067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.816275Z","time spent":"326.7045ms","remote":"127.0.0.1:39538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:50:05.143285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.98107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-10T11:50:05.143331Z","caller":"traceutil/trace.go:171","msg":"trace[160705715] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:20891; }","duration":"308.083732ms","start":"2026-09-10T11:50:04.835236Z","end":"2026-09-10T11:50:05.143319Z","steps":["trace[160705715] 'agreement among raft nodes before linearized reading' (duration: 307.864098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:50:05.143367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:50:04.835219Z","time spent":"308.138773ms","remote":"127.0.0.1:39312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-09-10T11:50:05.143399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.916669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-10T11:50:05.143444Z","caller":"traceutil/trace.go:171","msg":"trace[1250906939] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:20891; }","duration":"147.011161ms","start":"2026-09-10T11:50:04.99642Z","end":"2026-09-10T11:50:05.143432Z","steps":["trace[1250906939] 'agreement among raft nodes before linearized reading' (duration: 146.851557ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:52:10.713324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19687} {"level":"info","ts":"2026-09-10T11:52:10.87343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19687,"took":"156.181476ms","hash":265457644,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":29310976,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-10T11:52:10.873536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":265457644,"revision":19687,"compact-revision":17369} {"level":"info","ts":"2026-09-10T11:54:10.154224Z","caller":"traceutil/trace.go:171","msg":"trace[368161653] transaction","detail":"{read_only:false; response_revision:23385; number_of_response:1; }","duration":"267.650525ms","start":"2026-09-10T11:54:09.886552Z","end":"2026-09-10T11:54:10.154203Z","steps":["trace[368161653] 'process raft request' (duration: 267.333229ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:54:10.154131Z","caller":"traceutil/trace.go:171","msg":"trace[633283895] linearizableReadLoop","detail":"{readStateIndex:24174; appliedIndex:24173; }","duration":"250.56369ms","start":"2026-09-10T11:54:09.903534Z","end":"2026-09-10T11:54:10.154098Z","steps":["trace[633283895] 'read index received' (duration: 250.204382ms)","trace[633283895] 'applied index is now lower than readState.Index' (duration: 357.978µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:54:10.154688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.991971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-10T11:54:10.154714Z","caller":"traceutil/trace.go:171","msg":"trace[1003636501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23385; }","duration":"141.084613ms","start":"2026-09-10T11:54:10.013621Z","end":"2026-09-10T11:54:10.154706Z","steps":["trace[1003636501] 'agreement among raft nodes before linearized reading' (duration: 140.921649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:54:10.154822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.384016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-10T11:54:10.154841Z","caller":"traceutil/trace.go:171","msg":"trace[1025708815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23385; }","duration":"230.446937ms","start":"2026-09-10T11:54:09.924385Z","end":"2026-09-10T11:54:10.154832Z","steps":["trace[1025708815] 'agreement among raft nodes before linearized reading' (duration: 230.10781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:54:10.155177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.969048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-10T11:54:10.155275Z","caller":"traceutil/trace.go:171","msg":"trace[432878319] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:23385; }","duration":"251.758355ms","start":"2026-09-10T11:54:09.903485Z","end":"2026-09-10T11:54:10.155244Z","steps":["trace[432878319] 'agreement among raft nodes before linearized reading' (duration: 250.835436ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:57:10.721216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21904} {"level":"info","ts":"2026-09-10T11:57:10.845566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21904,"took":"120.044453ms","hash":3020783831,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":43155456,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-10T11:57:10.845653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3020783831,"revision":21904,"compact-revision":19687} {"level":"info","ts":"2026-09-10T12:00:03.767701Z","caller":"traceutil/trace.go:171","msg":"trace[1770781049] transaction","detail":"{read_only:false; response_revision:26365; number_of_response:1; }","duration":"140.089513ms","start":"2026-09-10T12:00:03.627588Z","end":"2026-09-10T12:00:03.767678Z","steps":["trace[1770781049] 'process raft request' (duration: 139.935508ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T12:02:10.729458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25046} {"level":"info","ts":"2026-09-10T12:02:10.888533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25046,"took":"154.77562ms","hash":4003456457,"current-db-size-bytes":55922688,"current-db-size":"56 MB","current-db-size-in-use-bytes":30539776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-10T12:02:10.888712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4003456457,"revision":25046,"compact-revision":21904} {"level":"info","ts":"2026-09-10T12:04:12.006514Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000071ef.wal"}