{"level":"warn","ts":"2026-08-22T10:35:13.578625Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:35:13.578727Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-08-22T10:35:13.578799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:35:13.578813Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-08-22T10:35:13.578831Z","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-08-22T10:35:13.579476Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-08-22T10:35:13.579571Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-08-22T10:35:13.581817Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.045106ms"} {"level":"info","ts":"2026-08-22T10:35:13.586768Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-08-22T10:35:13.586852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:35:13.58688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-08-22T10:35:13.586891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:35:13.586909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-08-22T10:35:13.586972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-08-22T10:35:13.597288Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:35:13.598883Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:35:13.600266Z","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-08-22T10:35:13.602601Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:35:13.602853Z","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-08-22T10:35:13.602971Z","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-08-22T10:35:13.602985Z","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-08-22T10:35:13.602787Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:35:13.610543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-08-22T10:35:13.611726Z","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-08-22T10:35:13.611909Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-08-22T10:35:13.611957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-08-22T10:35:13.612087Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:35:13.612149Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:35:13.612729Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-08-22T10:35:13.78814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:35:13.78822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:35:13.788253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-08-22T10:35:13.788274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:35:13.788286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-08-22T10:35:13.788302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-08-22T10:35:13.788318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-08-22T10:35:13.789425Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:35:13.789424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:35:13.789445Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:35:13.789511Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:35:13.791079Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:35:13.791108Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:35:13.792684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-08-22T10:35:13.793Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:35:13.793207Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:35:13.793369Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:35:13.793928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:35:51.933796Z","caller":"traceutil/trace.go:171","msg":"trace[773836414] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"176.14747ms","start":"2026-08-22T10:35:51.757607Z","end":"2026-08-22T10:35:51.933754Z","steps":["trace[773836414] 'read index received' (duration: 175.849131ms)","trace[773836414] 'applied index is now lower than readState.Index' (duration: 297.439µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:51.93393Z","caller":"traceutil/trace.go:171","msg":"trace[1461450490] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"176.88279ms","start":"2026-08-22T10:35:51.757004Z","end":"2026-08-22T10:35:51.933887Z","steps":["trace[1461450490] 'process raft request' (duration: 176.604642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:51.934059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.436118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:18056"} {"level":"info","ts":"2026-08-22T10:35:51.934143Z","caller":"traceutil/trace.go:171","msg":"trace[234732745] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backends.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:576; }","duration":"176.584112ms","start":"2026-08-22T10:35:51.75754Z","end":"2026-08-22T10:35:51.934124Z","steps":["trace[234732745] 'agreement among raft nodes before linearized reading' (duration: 176.356826ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:12.297764Z","caller":"traceutil/trace.go:171","msg":"trace[386695551] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"104.088063ms","start":"2026-08-22T10:36:12.193642Z","end":"2026-08-22T10:36:12.29773Z","steps":["trace[386695551] 'read index received' (duration: 19.668451ms)","trace[386695551] 'applied index is now lower than readState.Index' (duration: 84.417932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:12.297843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.179496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:36:12.297938Z","caller":"traceutil/trace.go:171","msg":"trace[960988933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"104.284189ms","start":"2026-08-22T10:36:12.193629Z","end":"2026-08-22T10:36:12.297913Z","steps":["trace[960988933] 'agreement among raft nodes before linearized reading' (duration: 104.128094ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:15.258694Z","caller":"traceutil/trace.go:171","msg":"trace[1908312153] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"254.963296ms","start":"2026-08-22T10:36:15.003698Z","end":"2026-08-22T10:36:15.258662Z","steps":["trace[1908312153] 'process raft request' (duration: 254.666757ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:42.24798Z","caller":"traceutil/trace.go:171","msg":"trace[607382726] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"140.598297ms","start":"2026-08-22T10:36:42.107343Z","end":"2026-08-22T10:36:42.247941Z","steps":["trace[607382726] 'process raft request' (duration: 41.866182ms)","trace[607382726] 'compare' (duration: 98.54359ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:37:26.458014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.691822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-22T10:37:26.458037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.107096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:37:26.458122Z","caller":"traceutil/trace.go:171","msg":"trace[747505981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1812; }","duration":"199.831616ms","start":"2026-08-22T10:37:26.258271Z","end":"2026-08-22T10:37:26.458103Z","steps":["trace[747505981] 'range keys from in-memory index tree' (duration: 199.471046ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:26.458146Z","caller":"traceutil/trace.go:171","msg":"trace[1062845150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1812; }","duration":"264.283711ms","start":"2026-08-22T10:37:26.193834Z","end":"2026-08-22T10:37:26.458118Z","steps":["trace[1062845150] 'range keys from in-memory index tree' (duration: 263.852979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:26.458037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.014582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-08-22T10:37:26.458163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.735815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-733b93ae-b122-4406-9671-6a52bb403507\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T10:37:26.458305Z","caller":"traceutil/trace.go:171","msg":"trace[1037827086] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1812; }","duration":"265.341731ms","start":"2026-08-22T10:37:26.192926Z","end":"2026-08-22T10:37:26.458268Z","steps":["trace[1037827086] 'range keys from in-memory index tree' (duration: 264.795695ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:26.458338Z","caller":"traceutil/trace.go:171","msg":"trace[77639663] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-733b93ae-b122-4406-9671-6a52bb403507; range_end:; response_count:1; response_revision:1812; }","duration":"108.946291ms","start":"2026-08-22T10:37:26.349341Z","end":"2026-08-22T10:37:26.458287Z","steps":["trace[77639663] 'range keys from in-memory index tree' (duration: 108.486579ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:26.600685Z","caller":"traceutil/trace.go:171","msg":"trace[1183651201] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1886; }","duration":"125.430763ms","start":"2026-08-22T10:37:26.475222Z","end":"2026-08-22T10:37:26.600653Z","steps":["trace[1183651201] 'read index received' (duration: 4.481986ms)","trace[1183651201] 'applied index is now lower than readState.Index' (duration: 120.947417ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:37:26.60099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.734281ms","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-08-22T10:37:26.600985Z","caller":"traceutil/trace.go:171","msg":"trace[1092137824] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"129.678282ms","start":"2026-08-22T10:37:26.471121Z","end":"2026-08-22T10:37:26.600799Z","steps":["trace[1092137824] 'process raft request' (duration: 123.681704ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:26.601044Z","caller":"traceutil/trace.go:171","msg":"trace[1598701545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1815; }","duration":"125.844804ms","start":"2026-08-22T10:37:26.475182Z","end":"2026-08-22T10:37:26.601027Z","steps":["trace[1598701545] 'agreement among raft nodes before linearized reading' (duration: 125.593597ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:10.979082Z","caller":"traceutil/trace.go:171","msg":"trace[133164096] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"142.174869ms","start":"2026-08-22T10:38:10.836852Z","end":"2026-08-22T10:38:10.979027Z","steps":["trace[133164096] 'process raft request' (duration: 105.921584ms)","trace[133164096] 'compare' (duration: 36.06243ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:38:16.11879Z","caller":"traceutil/trace.go:171","msg":"trace[89360703] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"108.682742ms","start":"2026-08-22T10:38:16.010077Z","end":"2026-08-22T10:38:16.11876Z","steps":["trace[89360703] 'process raft request' (duration: 108.458356ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:05.92493Z","caller":"traceutil/trace.go:171","msg":"trace[699709530] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"154.488768ms","start":"2026-08-22T10:39:05.770413Z","end":"2026-08-22T10:39:05.924902Z","steps":["trace[699709530] 'process raft request' (duration: 154.313824ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:30.370686Z","caller":"traceutil/trace.go:171","msg":"trace[479176917] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"166.09397ms","start":"2026-08-22T10:43:30.204566Z","end":"2026-08-22T10:43:30.37066Z","steps":["trace[479176917] 'process raft request' (duration: 165.928136ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:30.398314Z","caller":"traceutil/trace.go:171","msg":"trace[180385077] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"188.367137ms","start":"2026-08-22T10:43:30.209922Z","end":"2026-08-22T10:43:30.398289Z","steps":["trace[180385077] 'process raft request' (duration: 188.12441ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:30.398486Z","caller":"traceutil/trace.go:171","msg":"trace[1329812404] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5113; }","duration":"114.060097ms","start":"2026-08-22T10:43:30.284398Z","end":"2026-08-22T10:43:30.398458Z","steps":["trace[1329812404] 'read index received' (duration: 86.102541ms)","trace[1329812404] 'applied index is now lower than readState.Index' (duration: 27.955616ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:43:30.398707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.297314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T10:43:30.398698Z","caller":"traceutil/trace.go:171","msg":"trace[2029349321] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"136.508259ms","start":"2026-08-22T10:43:30.26216Z","end":"2026-08-22T10:43:30.398668Z","steps":["trace[2029349321] 'process raft request' (duration: 136.038036ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:30.398746Z","caller":"traceutil/trace.go:171","msg":"trace[1420916248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4926; }","duration":"114.387617ms","start":"2026-08-22T10:43:30.284348Z","end":"2026-08-22T10:43:30.398735Z","steps":["trace[1420916248] 'agreement among raft nodes before linearized reading' (duration: 114.216812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.475314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.222884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:43:44.475545Z","caller":"traceutil/trace.go:171","msg":"trace[1040531025] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"262.621195ms","start":"2026-08-22T10:43:44.212884Z","end":"2026-08-22T10:43:44.475505Z","steps":["trace[1040531025] 'process raft request' (duration: 15.734122ms)","trace[1040531025] 'compare' (duration: 245.967377ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:44.475598Z","caller":"traceutil/trace.go:171","msg":"trace[1424794200] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"262.189774ms","start":"2026-08-22T10:43:44.213374Z","end":"2026-08-22T10:43:44.475564Z","steps":["trace[1424794200] 'process raft request' (duration: 262.04905ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:44.475606Z","caller":"traceutil/trace.go:171","msg":"trace[979816797] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"249.712362ms","start":"2026-08-22T10:43:44.225858Z","end":"2026-08-22T10:43:44.47557Z","steps":["trace[979816797] 'process raft request' (duration: 249.64917ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:44.475631Z","caller":"traceutil/trace.go:171","msg":"trace[155495972] linearizableReadLoop","detail":"{readStateIndex:5405; appliedIndex:5402; }","duration":"137.499827ms","start":"2026-08-22T10:43:44.338108Z","end":"2026-08-22T10:43:44.475608Z","steps":["trace[155495972] 'read index received' (duration: 35.361µs)","trace[155495972] 'applied index is now lower than readState.Index' (duration: 137.461836ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:43:44.475833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.708773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-tkvh8\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-22T10:43:44.475893Z","caller":"traceutil/trace.go:171","msg":"trace[575228451] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-tkvh8; range_end:; response_count:1; response_revision:5207; }","duration":"137.807756ms","start":"2026-08-22T10:43:44.338071Z","end":"2026-08-22T10:43:44.475878Z","steps":["trace[575228451] 'agreement among raft nodes before linearized reading' (duration: 137.62571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.475979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.989863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:43:44.476022Z","caller":"traceutil/trace.go:171","msg":"trace[76064531] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5207; }","duration":"126.091746ms","start":"2026-08-22T10:43:44.349918Z","end":"2026-08-22T10:43:44.47601Z","steps":["trace[76064531] 'agreement among raft nodes before linearized reading' (duration: 126.002764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.928931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.252688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:43:44.929144Z","caller":"traceutil/trace.go:171","msg":"trace[2007781100] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"399.533715ms","start":"2026-08-22T10:43:44.52958Z","end":"2026-08-22T10:43:44.929114Z","steps":["trace[2007781100] 'process raft request' (duration: 90.884585ms)","trace[2007781100] 'compare' (duration: 308.122705ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:44.929208Z","caller":"traceutil/trace.go:171","msg":"trace[133975597] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"395.285836ms","start":"2026-08-22T10:43:44.533905Z","end":"2026-08-22T10:43:44.929191Z","steps":["trace[133975597] 'process raft request' (duration: 395.202094ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:44.929191Z","caller":"traceutil/trace.go:171","msg":"trace[421488324] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"395.292906ms","start":"2026-08-22T10:43:44.533871Z","end":"2026-08-22T10:43:44.929163Z","steps":["trace[421488324] 'process raft request' (duration: 395.159563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.929281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:44.533886Z","time spent":"395.358298ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:43:44.929303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:44.529565Z","time spent":"399.654889ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:43:44.929434Z","caller":"traceutil/trace.go:171","msg":"trace[781351552] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"395.44912ms","start":"2026-08-22T10:43:44.533962Z","end":"2026-08-22T10:43:44.929412Z","steps":["trace[781351552] 'process raft request' (duration: 395.188453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.929519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:44.533954Z","time spent":"395.525523ms","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:43:44.929688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:44.533861Z","time spent":"395.394519ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:43:44.930024Z","caller":"traceutil/trace.go:171","msg":"trace[575433518] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"395.681267ms","start":"2026-08-22T10:43:44.534313Z","end":"2026-08-22T10:43:44.929995Z","steps":["trace[575433518] 'process raft request' (duration: 395.058379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.930163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.96026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:43:44.930235Z","caller":"traceutil/trace.go:171","msg":"trace[1897712867] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5215; }","duration":"303.038872ms","start":"2026-08-22T10:43:44.627164Z","end":"2026-08-22T10:43:44.930203Z","steps":["trace[1897712867] 'agreement among raft nodes before linearized reading' (duration: 302.941279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.930267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:44.627151Z","time spent":"303.105124ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:43:44.930356Z","caller":"traceutil/trace.go:171","msg":"trace[1712663725] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"387.570449ms","start":"2026-08-22T10:43:44.542767Z","end":"2026-08-22T10:43:44.930337Z","steps":["trace[1712663725] 'process raft request' (duration: 387.156518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.930452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:44.54276Z","time spent":"387.648711ms","remote":"127.0.0.1:37360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":812,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:43:44.930761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:44.534282Z","time spent":"395.847492ms","remote":"127.0.0.1:37554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:43:44.930076Z","caller":"traceutil/trace.go:171","msg":"trace[1243835715] linearizableReadLoop","detail":"{readStateIndex:5414; appliedIndex:5408; }","duration":"302.865467ms","start":"2026-08-22T10:43:44.627195Z","end":"2026-08-22T10:43:44.930061Z","steps":["trace[1243835715] 'read index received' (duration: 285.812177ms)","trace[1243835715] 'applied index is now lower than readState.Index' (duration: 17.05234ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:43:44.9312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.250376ms","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-08-22T10:43:44.931254Z","caller":"traceutil/trace.go:171","msg":"trace[446179036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5215; }","duration":"229.347339ms","start":"2026-08-22T10:43:44.701894Z","end":"2026-08-22T10:43:44.931241Z","steps":["trace[446179036] 'agreement among raft nodes before linearized reading' (duration: 229.183774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:44.931381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.789521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T10:43:44.93145Z","caller":"traceutil/trace.go:171","msg":"trace[1781182294] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5215; }","duration":"192.879424ms","start":"2026-08-22T10:43:44.738552Z","end":"2026-08-22T10:43:44.931432Z","steps":["trace[1781182294] 'agreement among raft nodes before linearized reading' (duration: 192.689009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:52.70083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.114673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-22T10:43:52.700949Z","caller":"traceutil/trace.go:171","msg":"trace[516116756] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"197.669619ms","start":"2026-08-22T10:43:52.503254Z","end":"2026-08-22T10:43:52.700924Z","steps":["trace[516116756] 'process raft request' (duration: 117.153355ms)","trace[516116756] 'compare' (duration: 80.35921ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:52.700949Z","caller":"traceutil/trace.go:171","msg":"trace[1659902811] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5396; }","duration":"234.268578ms","start":"2026-08-22T10:43:52.466657Z","end":"2026-08-22T10:43:52.700926Z","steps":["trace[1659902811] 'range keys from in-memory index tree' (duration: 233.901758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:52.70083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.584207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-503f2043-a962-49a5-b480-1b3e4247f078\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-22T10:43:52.701217Z","caller":"traceutil/trace.go:171","msg":"trace[1895357855] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-503f2043-a962-49a5-b480-1b3e4247f078; range_end:; response_count:1; response_revision:5396; }","duration":"256.008069ms","start":"2026-08-22T10:43:52.445177Z","end":"2026-08-22T10:43:52.701185Z","steps":["trace[1895357855] 'range keys from in-memory index tree' (duration: 255.423503ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:52.833179Z","caller":"traceutil/trace.go:171","msg":"trace[1326304106] linearizableReadLoop","detail":"{readStateIndex:5602; appliedIndex:5601; }","duration":"118.43521ms","start":"2026-08-22T10:43:52.714715Z","end":"2026-08-22T10:43:52.83315Z","steps":["trace[1326304106] 'read index received' (duration: 108.363477ms)","trace[1326304106] 'applied index is now lower than readState.Index' (duration: 10.070473ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:52.833284Z","caller":"traceutil/trace.go:171","msg":"trace[723608957] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"124.144221ms","start":"2026-08-22T10:43:52.709107Z","end":"2026-08-22T10:43:52.833251Z","steps":["trace[723608957] 'process raft request' (duration: 114.034587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:52.833344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.590655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:43:52.833391Z","caller":"traceutil/trace.go:171","msg":"trace[574714128] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5398; }","duration":"118.688228ms","start":"2026-08-22T10:43:52.714688Z","end":"2026-08-22T10:43:52.833376Z","steps":["trace[574714128] 'agreement among raft nodes before linearized reading' (duration: 118.586595ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:45:13.850909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2975} {"level":"info","ts":"2026-08-22T10:45:13.920315Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2975,"took":"67.863347ms","hash":148619097,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":34406400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T10:45:13.920378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":148619097,"revision":2975,"compact-revision":-1} {"level":"info","ts":"2026-08-22T10:49:40.876019Z","caller":"traceutil/trace.go:171","msg":"trace[1571199173] transaction","detail":"{read_only:false; response_revision:8098; number_of_response:1; }","duration":"148.433262ms","start":"2026-08-22T10:49:40.727564Z","end":"2026-08-22T10:49:40.875997Z","steps":["trace[1571199173] 'process raft request' (duration: 148.327799ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:41.06425Z","caller":"traceutil/trace.go:171","msg":"trace[494087174] linearizableReadLoop","detail":"{readStateIndex:8386; appliedIndex:8385; }","duration":"321.551437ms","start":"2026-08-22T10:49:40.742675Z","end":"2026-08-22T10:49:41.064227Z","steps":["trace[494087174] 'read index received' (duration: 133.42702ms)","trace[494087174] 'applied index is now lower than readState.Index' (duration: 188.123297ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:41.064298Z","caller":"traceutil/trace.go:171","msg":"trace[375071631] transaction","detail":"{read_only:false; response_revision:8099; number_of_response:1; }","duration":"332.779733ms","start":"2026-08-22T10:49:40.731487Z","end":"2026-08-22T10:49:41.064267Z","steps":["trace[375071631] 'process raft request' (duration: 332.541246ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:41.064315Z","caller":"traceutil/trace.go:171","msg":"trace[1178282510] transaction","detail":"{read_only:false; response_revision:8100; number_of_response:1; }","duration":"222.252676ms","start":"2026-08-22T10:49:40.842035Z","end":"2026-08-22T10:49:41.064288Z","steps":["trace[1178282510] 'process raft request' (duration: 222.147444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:41.064493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:40.731465Z","time spent":"332.920157ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:49:41.066137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.451701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T10:49:41.066185Z","caller":"traceutil/trace.go:171","msg":"trace[537941865] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8100; }","duration":"323.532052ms","start":"2026-08-22T10:49:40.742638Z","end":"2026-08-22T10:49:41.066171Z","steps":["trace[537941865] 'agreement among raft nodes before linearized reading' (duration: 323.372308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:41.066253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:40.742627Z","time spent":"323.583905ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-22T10:49:41.067654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.101938ms","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-08-22T10:49:41.067716Z","caller":"traceutil/trace.go:171","msg":"trace[1813769809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8100; }","duration":"319.211441ms","start":"2026-08-22T10:49:40.748492Z","end":"2026-08-22T10:49:41.067703Z","steps":["trace[1813769809] 'agreement among raft nodes before linearized reading' (duration: 318.987755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:41.067764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:49:40.74848Z","time spent":"319.272573ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T10:50:13.856327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6108} {"level":"info","ts":"2026-08-22T10:50:13.962917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6108,"took":"104.693233ms","hash":2690338494,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":23212032,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-22T10:50:13.96298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2690338494,"revision":6108,"compact-revision":2975} {"level":"warn","ts":"2026-08-22T10:50:21.724173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.791479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T10:50:21.72424Z","caller":"traceutil/trace.go:171","msg":"trace[1780116383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8373; }","duration":"118.900692ms","start":"2026-08-22T10:50:21.605326Z","end":"2026-08-22T10:50:21.724227Z","steps":["trace[1780116383] 'range keys from in-memory index tree' (duration: 118.628614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:21.724538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.879526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228555"} {"level":"info","ts":"2026-08-22T10:50:21.724615Z","caller":"traceutil/trace.go:171","msg":"trace[186793758] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8373; }","duration":"216.987328ms","start":"2026-08-22T10:50:21.50761Z","end":"2026-08-22T10:50:21.724597Z","steps":["trace[186793758] 'range keys from in-memory index tree' (duration: 216.470764ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:27.295455Z","caller":"traceutil/trace.go:171","msg":"trace[110997441] linearizableReadLoop","detail":"{readStateIndex:8704; appliedIndex:8703; }","duration":"102.1163ms","start":"2026-08-22T10:50:27.19332Z","end":"2026-08-22T10:50:27.295436Z","steps":["trace[110997441] 'read index received' (duration: 102.003897ms)","trace[110997441] 'applied index is now lower than readState.Index' (duration: 111.573µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:27.295763Z","caller":"traceutil/trace.go:171","msg":"trace[116716484] transaction","detail":"{read_only:false; response_revision:8403; number_of_response:1; }","duration":"167.711274ms","start":"2026-08-22T10:50:27.128033Z","end":"2026-08-22T10:50:27.295744Z","steps":["trace[116716484] 'process raft request' (duration: 167.294662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:27.296057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.716547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:50:27.29611Z","caller":"traceutil/trace.go:171","msg":"trace[2122992430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8403; }","duration":"102.806409ms","start":"2026-08-22T10:50:27.19329Z","end":"2026-08-22T10:50:27.296096Z","steps":["trace[2122992430] 'agreement among raft nodes before linearized reading' (duration: 102.715366ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:27.358919Z","caller":"traceutil/trace.go:171","msg":"trace[60779853] transaction","detail":"{read_only:false; response_revision:8404; number_of_response:1; }","duration":"135.253371ms","start":"2026-08-22T10:50:27.223638Z","end":"2026-08-22T10:50:27.358891Z","steps":["trace[60779853] 'process raft request' (duration: 134.713826ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:27.359113Z","caller":"traceutil/trace.go:171","msg":"trace[25529301] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"105.287749ms","start":"2026-08-22T10:50:27.253811Z","end":"2026-08-22T10:50:27.359099Z","steps":["trace[25529301] 'process raft request' (duration: 104.766984ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:27.517025Z","caller":"traceutil/trace.go:171","msg":"trace[1871596102] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"154.145802ms","start":"2026-08-22T10:50:27.362846Z","end":"2026-08-22T10:50:27.516991Z","steps":["trace[1871596102] 'process raft request' (duration: 79.598477ms)","trace[1871596102] 'compare' (duration: 74.37243ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:27.517161Z","caller":"traceutil/trace.go:171","msg":"trace[1743892907] linearizableReadLoop","detail":"{readStateIndex:8709; appliedIndex:8706; }","duration":"128.731298ms","start":"2026-08-22T10:50:27.388405Z","end":"2026-08-22T10:50:27.517137Z","steps":["trace[1743892907] 'read index received' (duration: 54.051989ms)","trace[1743892907] 'applied index is now lower than readState.Index' (duration: 74.678209ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:27.517397Z","caller":"traceutil/trace.go:171","msg":"trace[2087618768] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"152.434614ms","start":"2026-08-22T10:50:27.364943Z","end":"2026-08-22T10:50:27.517377Z","steps":["trace[2087618768] 'process raft request' (duration: 152.016992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:27.517415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.964854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:50:27.517507Z","caller":"traceutil/trace.go:171","msg":"trace[426537455] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8408; }","duration":"129.125029ms","start":"2026-08-22T10:50:27.388362Z","end":"2026-08-22T10:50:27.517487Z","steps":["trace[426537455] 'agreement among raft nodes before linearized reading' (duration: 128.932693ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:27.517019Z","caller":"traceutil/trace.go:171","msg":"trace[1686808570] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"152.177387ms","start":"2026-08-22T10:50:27.364818Z","end":"2026-08-22T10:50:27.516996Z","steps":["trace[1686808570] 'process raft request' (duration: 152.099835ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:34.300798Z","caller":"traceutil/trace.go:171","msg":"trace[1627616826] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"162.337492ms","start":"2026-08-22T10:50:34.138413Z","end":"2026-08-22T10:50:34.300751Z","steps":["trace[1627616826] 'process raft request' (duration: 162.132966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:34.301017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.685164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-tpxx4\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-08-22T10:50:34.301103Z","caller":"traceutil/trace.go:171","msg":"trace[1313421879] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-tpxx4; range_end:; response_count:1; response_revision:8479; }","duration":"161.816608ms","start":"2026-08-22T10:50:34.139274Z","end":"2026-08-22T10:50:34.301091Z","steps":["trace[1313421879] 'agreement among raft nodes before linearized reading' (duration: 161.54987ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:34.300789Z","caller":"traceutil/trace.go:171","msg":"trace[1507481046] linearizableReadLoop","detail":"{readStateIndex:8784; appliedIndex:8783; }","duration":"161.465268ms","start":"2026-08-22T10:50:34.139296Z","end":"2026-08-22T10:50:34.300761Z","steps":["trace[1507481046] 'read index received' (duration: 161.330524ms)","trace[1507481046] 'applied index is now lower than readState.Index' (duration: 133.394µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:34.379676Z","caller":"traceutil/trace.go:171","msg":"trace[1157466975] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"157.167567ms","start":"2026-08-22T10:50:34.222482Z","end":"2026-08-22T10:50:34.37965Z","steps":["trace[1157466975] 'process raft request' (duration: 156.980062ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:35.302763Z","caller":"traceutil/trace.go:171","msg":"trace[1445814870] linearizableReadLoop","detail":"{readStateIndex:8789; appliedIndex:8788; }","duration":"107.233764ms","start":"2026-08-22T10:50:35.195481Z","end":"2026-08-22T10:50:35.302715Z","steps":["trace[1445814870] 'read index received' (duration: 107.002217ms)","trace[1445814870] 'applied index is now lower than readState.Index' (duration: 230.407µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:35.302874Z","caller":"traceutil/trace.go:171","msg":"trace[79940254] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"182.213371ms","start":"2026-08-22T10:50:35.120623Z","end":"2026-08-22T10:50:35.302836Z","steps":["trace[79940254] 'process raft request' (duration: 181.928353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:35.302915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.406288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:50:35.303234Z","caller":"traceutil/trace.go:171","msg":"trace[889562128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8484; }","duration":"107.764348ms","start":"2026-08-22T10:50:35.195438Z","end":"2026-08-22T10:50:35.303202Z","steps":["trace[889562128] 'agreement among raft nodes before linearized reading' (duration: 107.413819ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:59.474078Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000236a.wal"} {"level":"info","ts":"2026-08-22T10:51:42.020302Z","caller":"traceutil/trace.go:171","msg":"trace[1129087999] transaction","detail":"{read_only:false; response_revision:9181; number_of_response:1; }","duration":"109.300232ms","start":"2026-08-22T10:51:41.91098Z","end":"2026-08-22T10:51:42.02028Z","steps":["trace[1129087999] 'process raft request' (duration: 109.25257ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:51:42.020674Z","caller":"traceutil/trace.go:171","msg":"trace[2000369024] transaction","detail":"{read_only:false; response_revision:9180; number_of_response:1; }","duration":"111.895535ms","start":"2026-08-22T10:51:41.90867Z","end":"2026-08-22T10:51:42.020565Z","steps":["trace[2000369024] 'process raft request' (duration: 21.34129ms)","trace[2000369024] 'compare' (duration: 90.108052ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:51:52.40023Z","caller":"traceutil/trace.go:171","msg":"trace[316369514] linearizableReadLoop","detail":"{readStateIndex:9566; appliedIndex:9565; }","duration":"119.577971ms","start":"2026-08-22T10:51:52.280625Z","end":"2026-08-22T10:51:52.400203Z","steps":["trace[316369514] 'read index received' (duration: 27.873094ms)","trace[316369514] 'applied index is now lower than readState.Index' (duration: 91.703177ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:51:52.400421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.780676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-47jch\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-22T10:51:52.40047Z","caller":"traceutil/trace.go:171","msg":"trace[328674237] range","detail":"{range_begin:/registry/pods/openstack/keepalived-47jch; range_end:; response_count:1; response_revision:9241; }","duration":"119.903529ms","start":"2026-08-22T10:51:52.280559Z","end":"2026-08-22T10:51:52.400462Z","steps":["trace[328674237] 'agreement among raft nodes before linearized reading' (duration: 119.697704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:51:52.400703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.701057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:51:52.400962Z","caller":"traceutil/trace.go:171","msg":"trace[549392273] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9241; }","duration":"107.984215ms","start":"2026-08-22T10:51:52.292945Z","end":"2026-08-22T10:51:52.400929Z","steps":["trace[549392273] 'agreement among raft nodes before linearized reading' (duration: 107.678896ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:52:50.947084Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T10:52:50.952942Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T10:52:50.953015Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T10:55:13.862826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8283} {"level":"info","ts":"2026-08-22T10:55:13.939323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8283,"took":"73.924529ms","hash":1226823105,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":26652672,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-22T10:55:13.939434Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1226823105,"revision":8283,"compact-revision":6108} {"level":"info","ts":"2026-08-22T10:55:31.344965Z","caller":"traceutil/trace.go:171","msg":"trace[954776757] linearizableReadLoop","detail":"{readStateIndex:11435; appliedIndex:11433; }","duration":"206.630844ms","start":"2026-08-22T10:55:31.138304Z","end":"2026-08-22T10:55:31.344935Z","steps":["trace[954776757] 'read index received' (duration: 7.735086ms)","trace[954776757] 'applied index is now lower than readState.Index' (duration: 198.894178ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:55:31.345114Z","caller":"traceutil/trace.go:171","msg":"trace[1109446865] transaction","detail":"{read_only:false; response_revision:11056; number_of_response:1; }","duration":"215.891364ms","start":"2026-08-22T10:55:31.129166Z","end":"2026-08-22T10:55:31.345058Z","steps":["trace[1109446865] 'process raft request' (duration: 179.232578ms)","trace[1109446865] 'compare' (duration: 36.258975ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:55:31.345249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.920522ms","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-08-22T10:55:31.345294Z","caller":"traceutil/trace.go:171","msg":"trace[135640542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11056; }","duration":"207.010745ms","start":"2026-08-22T10:55:31.138269Z","end":"2026-08-22T10:55:31.345279Z","steps":["trace[135640542] 'agreement among raft nodes before linearized reading' (duration: 206.83237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:55:31.345285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.897148ms","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-08-22T10:55:31.345373Z","caller":"traceutil/trace.go:171","msg":"trace[305846460] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11056; }","duration":"199.011982ms","start":"2026-08-22T10:55:31.146338Z","end":"2026-08-22T10:55:31.34535Z","steps":["trace[305846460] 'agreement among raft nodes before linearized reading' (duration: 198.791356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:55:31.345507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.94794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:55:31.345671Z","caller":"traceutil/trace.go:171","msg":"trace[1712460041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11056; }","duration":"154.108774ms","start":"2026-08-22T10:55:31.191506Z","end":"2026-08-22T10:55:31.345615Z","steps":["trace[1712460041] 'agreement among raft nodes before linearized reading' (duration: 153.904769ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:13.870896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10959} {"level":"info","ts":"2026-08-22T11:00:13.949391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10959,"took":"74.639515ms","hash":2974601974,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":27774976,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T11:00:13.94945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2974601974,"revision":10959,"compact-revision":8283} {"level":"info","ts":"2026-08-22T11:02:22.874119Z","caller":"traceutil/trace.go:171","msg":"trace[708259492] transaction","detail":"{read_only:false; response_revision:13790; number_of_response:1; }","duration":"439.299512ms","start":"2026-08-22T11:02:22.4348Z","end":"2026-08-22T11:02:22.874099Z","steps":["trace[708259492] 'process raft request' (duration: 439.166888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:22.874272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:22.434775Z","time spent":"439.410134ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12713,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:02:23.208657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.423839ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-22T11:02:23.208995Z","caller":"traceutil/trace.go:171","msg":"trace[1982106976] transaction","detail":"{read_only:false; response_revision:13791; number_of_response:1; }","duration":"348.559434ms","start":"2026-08-22T11:02:22.86041Z","end":"2026-08-22T11:02:23.208969Z","steps":["trace[1982106976] 'process raft request' (duration: 348.356708ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:23.209012Z","caller":"traceutil/trace.go:171","msg":"trace[463941881] transaction","detail":"{read_only:false; response_revision:13792; number_of_response:1; }","duration":"223.250132ms","start":"2026-08-22T11:02:22.985739Z","end":"2026-08-22T11:02:23.208989Z","steps":["trace[463941881] 'process raft request' (duration: 223.16015ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:23.209002Z","caller":"traceutil/trace.go:171","msg":"trace[513759826] linearizableReadLoop","detail":"{readStateIndex:14266; appliedIndex:14264; }","duration":"477.443316ms","start":"2026-08-22T11:02:22.731538Z","end":"2026-08-22T11:02:23.208981Z","steps":["trace[513759826] 'read index received' (duration: 142.473905ms)","trace[513759826] 'applied index is now lower than readState.Index' (duration: 334.967961ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:02:23.209123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:22.860389Z","time spent":"348.667037ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:02:23.209142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.605741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T11:02:23.20917Z","caller":"traceutil/trace.go:171","msg":"trace[1793361994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13792; }","duration":"477.692533ms","start":"2026-08-22T11:02:22.731469Z","end":"2026-08-22T11:02:23.209162Z","steps":["trace[1793361994] 'agreement among raft nodes before linearized reading' (duration: 477.57931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:23.209191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:22.731442Z","time spent":"477.744365ms","remote":"127.0.0.1:37550","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":"warn","ts":"2026-08-22T11:02:23.209304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.891344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-47jch\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-22T11:02:23.209356Z","caller":"traceutil/trace.go:171","msg":"trace[17908938] range","detail":"{range_begin:/registry/pods/openstack/keepalived-47jch; range_end:; response_count:1; response_revision:13792; }","duration":"100.985726ms","start":"2026-08-22T11:02:23.108358Z","end":"2026-08-22T11:02:23.209344Z","steps":["trace[17908938] 'agreement among raft nodes before linearized reading' (duration: 100.824432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:47.845453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030385141,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T11:02:48.076457Z","caller":"traceutil/trace.go:171","msg":"trace[485123953] linearizableReadLoop","detail":"{readStateIndex:14626; appliedIndex:14625; }","duration":"731.355372ms","start":"2026-08-22T11:02:47.34508Z","end":"2026-08-22T11:02:48.076436Z","steps":["trace[485123953] 'read index received' (duration: 731.218128ms)","trace[485123953] 'applied index is now lower than readState.Index' (duration: 136.214µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:02:48.0765Z","caller":"traceutil/trace.go:171","msg":"trace[1363311032] transaction","detail":"{read_only:false; response_revision:14133; number_of_response:1; }","duration":"741.120008ms","start":"2026-08-22T11:02:47.335351Z","end":"2026-08-22T11:02:48.076471Z","steps":["trace[1363311032] 'process raft request' (duration: 740.947003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.076662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.558808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T11:02:48.076698Z","caller":"traceutil/trace.go:171","msg":"trace[1555432072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14133; }","duration":"731.64494ms","start":"2026-08-22T11:02:47.345043Z","end":"2026-08-22T11:02:48.076688Z","steps":["trace[1555432072] 'agreement among raft nodes before linearized reading' (duration: 731.508307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.076647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.335326Z","time spent":"741.238681ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:02:48.076724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.345024Z","time spent":"731.692952ms","remote":"127.0.0.1:37550","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":"warn","ts":"2026-08-22T11:02:48.078268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.992463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-47jch\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-22T11:02:48.078345Z","caller":"traceutil/trace.go:171","msg":"trace[1604939596] range","detail":"{range_begin:/registry/pods/openstack/keepalived-47jch; range_end:; response_count:1; response_revision:14135; }","duration":"659.109607ms","start":"2026-08-22T11:02:47.419218Z","end":"2026-08-22T11:02:48.078327Z","steps":["trace[1604939596] 'agreement among raft nodes before linearized reading' (duration: 658.572481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.078393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.419194Z","time spent":"659.185978ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-47jch\" "} {"level":"warn","ts":"2026-08-22T11:02:48.0785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.464735ms","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-08-22T11:02:48.078564Z","caller":"traceutil/trace.go:171","msg":"trace[562956573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14135; }","duration":"572.556417ms","start":"2026-08-22T11:02:47.505992Z","end":"2026-08-22T11:02:48.078549Z","steps":["trace[562956573] 'agreement among raft nodes before linearized reading' (duration: 572.399792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.078595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.505983Z","time spent":"572.605908ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T11:02:48.078651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.440768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T11:02:48.078703Z","caller":"traceutil/trace.go:171","msg":"trace[2123371447] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:14135; }","duration":"140.517ms","start":"2026-08-22T11:02:47.938174Z","end":"2026-08-22T11:02:48.078691Z","steps":["trace[2123371447] 'agreement among raft nodes before linearized reading' (duration: 140.437108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.078704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.132757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-22T11:02:48.078787Z","caller":"traceutil/trace.go:171","msg":"trace[2107856646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14135; }","duration":"571.24105ms","start":"2026-08-22T11:02:47.507529Z","end":"2026-08-22T11:02:48.07877Z","steps":["trace[2107856646] 'agreement among raft nodes before linearized reading' (duration: 571.002233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.078847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.507516Z","time spent":"571.318442ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T11:02:48.07883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.039413ms","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-08-22T11:02:48.0785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.654387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-22T11:02:48.078969Z","caller":"traceutil/trace.go:171","msg":"trace[1801261414] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14135; }","duration":"393.165721ms","start":"2026-08-22T11:02:47.685787Z","end":"2026-08-22T11:02:48.078952Z","steps":["trace[1801261414] 'agreement among raft nodes before linearized reading' (duration: 392.576245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.079033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.685749Z","time spent":"393.255274ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T11:02:48.079042Z","caller":"traceutil/trace.go:171","msg":"trace[1539444288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14135; }","duration":"583.244788ms","start":"2026-08-22T11:02:47.495673Z","end":"2026-08-22T11:02:48.078918Z","steps":["trace[1539444288] 'agreement among raft nodes before linearized reading' (duration: 582.867628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.079144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.495644Z","time spent":"583.483475ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T11:02:48.081061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.620867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T11:02:48.081093Z","caller":"traceutil/trace.go:171","msg":"trace[838809240] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:14135; }","duration":"281.691049ms","start":"2026-08-22T11:02:47.799395Z","end":"2026-08-22T11:02:48.081086Z","steps":["trace[838809240] 'agreement among raft nodes before linearized reading' (duration: 279.335573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.081152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.685231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T11:02:48.081188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.307225ms","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-08-22T11:02:48.081251Z","caller":"traceutil/trace.go:171","msg":"trace[837284444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14135; }","duration":"580.429888ms","start":"2026-08-22T11:02:47.500806Z","end":"2026-08-22T11:02:48.081236Z","steps":["trace[837284444] 'agreement among raft nodes before linearized reading' (duration: 577.851846ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:02:48.081247Z","caller":"traceutil/trace.go:171","msg":"trace[332945902] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:14135; }","duration":"434.808204ms","start":"2026-08-22T11:02:47.646419Z","end":"2026-08-22T11:02:48.081227Z","steps":["trace[332945902] 'agreement among raft nodes before linearized reading' (duration: 432.093268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:02:48.08129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.500786Z","time spent":"580.496261ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T11:02:48.081307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:02:47.646372Z","time spent":"434.922758ms","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:05:13.880825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12998} {"level":"info","ts":"2026-08-22T11:05:13.953888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12998,"took":"70.644302ms","hash":497162485,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":30560256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:05:13.953946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":497162485,"revision":12998,"compact-revision":10959} {"level":"info","ts":"2026-08-22T11:06:55.14015Z","caller":"traceutil/trace.go:171","msg":"trace[1789574534] linearizableReadLoop","detail":"{readStateIndex:16899; appliedIndex:16899; }","duration":"121.242487ms","start":"2026-08-22T11:06:55.017789Z","end":"2026-08-22T11:06:55.139031Z","steps":["trace[1789574534] 'read index received' (duration: 217.396µs)","trace[1789574534] 'applied index is now lower than readState.Index' (duration: 121.021031ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:06:55.141846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.004365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-08-22T11:06:55.141925Z","caller":"traceutil/trace.go:171","msg":"trace[1287034028] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16342; }","duration":"124.17909ms","start":"2026-08-22T11:06:55.017726Z","end":"2026-08-22T11:06:55.141905Z","steps":["trace[1287034028] 'agreement among raft nodes before linearized reading' (duration: 122.526923ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:20.997653Z","caller":"traceutil/trace.go:171","msg":"trace[437465156] linearizableReadLoop","detail":"{readStateIndex:17916; appliedIndex:17915; }","duration":"149.73191ms","start":"2026-08-22T11:09:20.847897Z","end":"2026-08-22T11:09:20.997629Z","steps":["trace[437465156] 'read index received' (duration: 149.536545ms)","trace[437465156] 'applied index is now lower than readState.Index' (duration: 194.485µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:09:20.997876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.928726ms","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-08-22T11:09:20.997722Z","caller":"traceutil/trace.go:171","msg":"trace[2040456524] transaction","detail":"{read_only:false; response_revision:17325; number_of_response:1; }","duration":"165.185786ms","start":"2026-08-22T11:09:20.832494Z","end":"2026-08-22T11:09:20.99768Z","steps":["trace[2040456524] 'process raft request' (duration: 164.98932ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:20.997949Z","caller":"traceutil/trace.go:171","msg":"trace[335555263] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:17325; }","duration":"150.048209ms","start":"2026-08-22T11:09:20.847864Z","end":"2026-08-22T11:09:20.997912Z","steps":["trace[335555263] 'agreement among raft nodes before linearized reading' (duration: 149.926826ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:21.713442Z","caller":"traceutil/trace.go:171","msg":"trace[644370264] linearizableReadLoop","detail":"{readStateIndex:17918; appliedIndex:17917; }","duration":"232.77564ms","start":"2026-08-22T11:09:21.480643Z","end":"2026-08-22T11:09:21.713418Z","steps":["trace[644370264] 'read index received' (duration: 232.684207ms)","trace[644370264] 'applied index is now lower than readState.Index' (duration: 90.183µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:09:21.713491Z","caller":"traceutil/trace.go:171","msg":"trace[1517424812] transaction","detail":"{read_only:false; response_revision:17327; number_of_response:1; }","duration":"510.379534ms","start":"2026-08-22T11:09:21.203076Z","end":"2026-08-22T11:09:21.713456Z","steps":["trace[1517424812] 'process raft request' (duration: 510.191809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:21.713579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.917845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/clustering\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:09:21.713613Z","caller":"traceutil/trace.go:171","msg":"trace[598198479] range","detail":"{range_begin:/registry/ingress/openstack/clustering; range_end:; response_count:0; response_revision:17327; }","duration":"232.992016ms","start":"2026-08-22T11:09:21.480611Z","end":"2026-08-22T11:09:21.713603Z","steps":["trace[598198479] 'agreement among raft nodes before linearized reading' (duration: 232.921534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:21.713651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:21.203059Z","time spent":"510.505458ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:09:21.831327Z","caller":"traceutil/trace.go:171","msg":"trace[1595297549] transaction","detail":"{read_only:false; response_revision:17329; number_of_response:1; }","duration":"332.994965ms","start":"2026-08-22T11:09:21.498313Z","end":"2026-08-22T11:09:21.831308Z","steps":["trace[1595297549] 'process raft request' (duration: 332.927703ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:21.831489Z","caller":"traceutil/trace.go:171","msg":"trace[765724951] linearizableReadLoop","detail":"{readStateIndex:17921; appliedIndex:17918; }","duration":"117.965234ms","start":"2026-08-22T11:09:21.713509Z","end":"2026-08-22T11:09:21.831474Z","steps":["trace[765724951] 'read index received' (duration: 111.274566ms)","trace[765724951] 'applied index is now lower than readState.Index' (duration: 6.689768ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:09:21.8315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:21.498302Z","time spent":"333.132629ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:09:21.831374Z","caller":"traceutil/trace.go:171","msg":"trace[1004795764] transaction","detail":"{read_only:false; response_revision:17328; number_of_response:1; }","duration":"335.328442ms","start":"2026-08-22T11:09:21.496007Z","end":"2026-08-22T11:09:21.831336Z","steps":["trace[1004795764] 'process raft request' (duration: 328.801867ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:21.831671Z","caller":"traceutil/trace.go:171","msg":"trace[793892524] transaction","detail":"{read_only:false; response_revision:17330; number_of_response:1; }","duration":"327.402398ms","start":"2026-08-22T11:09:21.504262Z","end":"2026-08-22T11:09:21.831664Z","steps":["trace[793892524] 'process raft request' (duration: 327.014127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:21.831723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:21.504254Z","time spent":"327.441329ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:09:21.831618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.473127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-jncdx\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-08-22T11:09:21.831858Z","caller":"traceutil/trace.go:171","msg":"trace[1362258094] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-jncdx; range_end:; response_count:1; response_revision:17330; }","duration":"196.751596ms","start":"2026-08-22T11:09:21.635098Z","end":"2026-08-22T11:09:21.831849Z","steps":["trace[1362258094] 'agreement among raft nodes before linearized reading' (duration: 196.434057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:21.83191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:21.495992Z","time spent":"335.664761ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:09:24.012409Z","caller":"traceutil/trace.go:171","msg":"trace[499859316] linearizableReadLoop","detail":"{readStateIndex:17934; appliedIndex:17933; }","duration":"316.102809ms","start":"2026-08-22T11:09:23.696285Z","end":"2026-08-22T11:09:24.012388Z","steps":["trace[499859316] 'read index received' (duration: 315.943494ms)","trace[499859316] 'applied index is now lower than readState.Index' (duration: 158.295µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:09:24.012515Z","caller":"traceutil/trace.go:171","msg":"trace[650082615] transaction","detail":"{read_only:false; response_revision:17342; number_of_response:1; }","duration":"409.979255ms","start":"2026-08-22T11:09:23.602514Z","end":"2026-08-22T11:09:24.012494Z","steps":["trace[650082615] 'process raft request' (duration: 409.705157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:24.012632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.602488Z","time spent":"410.077238ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:09:24.01376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.448507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:953155"} {"level":"info","ts":"2026-08-22T11:09:24.013849Z","caller":"traceutil/trace.go:171","msg":"trace[2027878223] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17342; }","duration":"317.57987ms","start":"2026-08-22T11:09:23.696252Z","end":"2026-08-22T11:09:24.013831Z","steps":["trace[2027878223] 'agreement among raft nodes before linearized reading' (duration: 316.322555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:24.013904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.696234Z","time spent":"317.651423ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":953178,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-22T11:09:24.513192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030397361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T11:09:25.018294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030397361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T11:09:25.519103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030397361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T11:09:26.019528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030397361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T11:09:26.808567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030397361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T11:09:27.193956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999846713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-22T11:09:27.194038Z","caller":"traceutil/trace.go:171","msg":"trace[1484918377] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999985676s","start":"2026-08-22T11:09:25.194036Z","end":"2026-08-22T11:09:27.194022Z","steps":["trace[1484918377] 'agreement among raft nodes before linearized reading' (duration: 1.999887684s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:27.194085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:25.194013Z","time spent":"2.000058689s","remote":"127.0.0.1:37284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T11:09:27.309353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030397361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T11:09:27.809951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030397361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T11:09:28.310393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356767587030397361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T11:09:28.421064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.408794657s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T11:09:28.421838Z","caller":"traceutil/trace.go:171","msg":"trace[1968871268] transaction","detail":"{read_only:false; response_revision:17346; number_of_response:1; }","duration":"4.584957463s","start":"2026-08-22T11:09:23.836853Z","end":"2026-08-22T11:09:28.421811Z","steps":["trace[1968871268] 'process raft request' (duration: 4.584912941s)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:28.421873Z","caller":"traceutil/trace.go:171","msg":"trace[907159826] transaction","detail":"{read_only:false; response_revision:17343; number_of_response:1; }","duration":"4.691226127s","start":"2026-08-22T11:09:23.730613Z","end":"2026-08-22T11:09:28.421839Z","steps":["trace[907159826] 'process raft request' (duration: 4.690900538s)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:28.421875Z","caller":"traceutil/trace.go:171","msg":"trace[1416571180] transaction","detail":"{read_only:false; response_revision:17344; number_of_response:1; }","duration":"4.586151046s","start":"2026-08-22T11:09:23.835696Z","end":"2026-08-22T11:09:28.421847Z","steps":["trace[1416571180] 'process raft request' (duration: 4.58595394s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.422002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.730596Z","time spent":"4.691340861s","remote":"127.0.0.1:37360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T11:09:28.42202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.835679Z","time spent":"4.58627152s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:09:28.422049Z","caller":"traceutil/trace.go:171","msg":"trace[1114165111] transaction","detail":"{read_only:false; response_revision:17345; number_of_response:1; }","duration":"4.585697613s","start":"2026-08-22T11:09:23.836341Z","end":"2026-08-22T11:09:28.422038Z","steps":["trace[1114165111] 'process raft request' (duration: 4.585385924s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.421999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.836817Z","time spent":"4.585104547s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:09:28.4221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.836288Z","time spent":"4.585784585s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:09:28.422582Z","caller":"traceutil/trace.go:171","msg":"trace[1245076221] linearizableReadLoop","detail":"{readStateIndex:17939; appliedIndex:17934; }","duration":"4.410024972s","start":"2026-08-22T11:09:24.012527Z","end":"2026-08-22T11:09:28.422552Z","steps":["trace[1245076221] 'read index received' (duration: 4.409085205s)","trace[1245076221] 'applied index is now lower than readState.Index' (duration: 938.567µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:09:28.42268Z","caller":"traceutil/trace.go:171","msg":"trace[2130154716] transaction","detail":"{read_only:false; response_revision:17347; number_of_response:1; }","duration":"2.741404083s","start":"2026-08-22T11:09:25.681265Z","end":"2026-08-22T11:09:28.42267Z","steps":["trace[2130154716] 'process raft request' (duration: 2.741156476s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.422823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:25.68124Z","time spent":"2.741475205s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:09:28.422854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.627488991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-08-22T11:09:28.422884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.52206589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:09:28.422896Z","caller":"traceutil/trace.go:171","msg":"trace[1609709325] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:17347; }","duration":"4.627566813s","start":"2026-08-22T11:09:23.795317Z","end":"2026-08-22T11:09:28.422883Z","steps":["trace[1609709325] 'agreement among raft nodes before linearized reading' (duration: 4.627407698s)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:28.42293Z","caller":"traceutil/trace.go:171","msg":"trace[310582912] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:17347; }","duration":"4.522141492s","start":"2026-08-22T11:09:23.900776Z","end":"2026-08-22T11:09:28.422918Z","steps":["trace[310582912] 'agreement among raft nodes before linearized reading' (duration: 4.522055189s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.422934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.795295Z","time spent":"4.627631865s","remote":"127.0.0.1:37442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"warn","ts":"2026-08-22T11:09:28.422962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.900762Z","time spent":"4.522194023s","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:09:28.42294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.853752019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:09:28.422998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.248198067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:09:28.423035Z","caller":"traceutil/trace.go:171","msg":"trace[342565376] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:17347; }","duration":"2.853884063s","start":"2026-08-22T11:09:25.569139Z","end":"2026-08-22T11:09:28.423023Z","steps":["trace[342565376] 'agreement among raft nodes before linearized reading' (duration: 2.853763779s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.423067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:25.569118Z","time spent":"2.853940245s","remote":"127.0.0.1:37620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:09:28.423018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.629333819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-22T11:09:28.425036Z","caller":"traceutil/trace.go:171","msg":"trace[1197387519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17347; }","duration":"3.631358026s","start":"2026-08-22T11:09:24.793663Z","end":"2026-08-22T11:09:28.425021Z","steps":["trace[1197387519] 'agreement among raft nodes before linearized reading' (duration: 3.629283478s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.425088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.793653Z","time spent":"3.631420528s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T11:09:28.423071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.630768869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T11:09:28.425255Z","caller":"traceutil/trace.go:171","msg":"trace[1205683997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17347; }","duration":"3.632972852s","start":"2026-08-22T11:09:24.792272Z","end":"2026-08-22T11:09:28.425244Z","steps":["trace[1205683997] 'agreement among raft nodes before linearized reading' (duration: 3.630759689s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.425293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.792256Z","time spent":"3.633028083s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T11:09:28.422928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.316481946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:09:28.42547Z","caller":"traceutil/trace.go:171","msg":"trace[18728981] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17347; }","duration":"4.319063059s","start":"2026-08-22T11:09:24.106396Z","end":"2026-08-22T11:09:28.425459Z","steps":["trace[18728981] 'agreement among raft nodes before linearized reading' (duration: 4.316504256s)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:28.4231Z","caller":"traceutil/trace.go:171","msg":"trace[1674249756] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:17347; }","duration":"3.24830152s","start":"2026-08-22T11:09:25.174744Z","end":"2026-08-22T11:09:28.423046Z","steps":["trace[1674249756] 'agreement among raft nodes before linearized reading' (duration: 3.248189016s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.425645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.106375Z","time spent":"4.319252813s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:09:28.425655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:25.174722Z","time spent":"3.250911884s","remote":"127.0.0.1:49740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:09:28.42312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.639127085s","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-08-22T11:09:28.425751Z","caller":"traceutil/trace.go:171","msg":"trace[1286729182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17347; }","duration":"3.641788501s","start":"2026-08-22T11:09:24.783947Z","end":"2026-08-22T11:09:28.425736Z","steps":["trace[1286729182] 'agreement among raft nodes before linearized reading' (duration: 3.639134766s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.42579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.783931Z","time spent":"3.641848962s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T11:09:28.423152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.979197149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:09:28.425893Z","caller":"traceutil/trace.go:171","msg":"trace[2031109896] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:17347; }","duration":"3.981937657s","start":"2026-08-22T11:09:24.443906Z","end":"2026-08-22T11:09:28.425844Z","steps":["trace[2031109896] 'agreement among raft nodes before linearized reading' (duration: 3.97922685s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.423185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.168248197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:09:28.425952Z","caller":"traceutil/trace.go:171","msg":"trace[1009911648] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:17347; }","duration":"4.171028366s","start":"2026-08-22T11:09:24.254904Z","end":"2026-08-22T11:09:28.425933Z","steps":["trace[1009911648] 'agreement among raft nodes before linearized reading' (duration: 4.168260778s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.426017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.25489Z","time spent":"4.171104429s","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:09:28.425924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.443887Z","time spent":"3.98202847s","remote":"127.0.0.1:37664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:09:28.423066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.624556694s","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-08-22T11:09:28.426132Z","caller":"traceutil/trace.go:171","msg":"trace[381267835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17347; }","duration":"3.62761685s","start":"2026-08-22T11:09:24.798471Z","end":"2026-08-22T11:09:28.426088Z","steps":["trace[381267835] 'agreement among raft nodes before linearized reading' (duration: 3.624434821s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.423214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.237739966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:09:28.423134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.402955899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:09:28.423227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.61234922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-22T11:09:28.423239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.387940425s","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-08-22T11:09:28.423291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.103568596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:09:28.423289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458209128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:09:28.42332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614491683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:09:28.423322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.408747417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:09:28.42335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614633097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:09:28.423369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.203297941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:09:28.423381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.875666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"warn","ts":"2026-08-22T11:09:28.423386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.484587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:09:28.42338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.420277193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:09:28.423403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.692661618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-l8wwv\" ","response":"range_response_count:1 size:20980"} {"level":"warn","ts":"2026-08-22T11:09:28.423406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.387904291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ts7lw\" ","response":"range_response_count:1 size:22133"} {"level":"warn","ts":"2026-08-22T11:09:28.423417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228878625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:09:28.423457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.57173529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-jncdx\" ","response":"range_response_count:1 size:9800"} {"level":"warn","ts":"2026-08-22T11:09:28.423472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.007112742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:09:28.423667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355914635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-22T11:09:28.426888Z","caller":"traceutil/trace.go:171","msg":"trace[1022800518] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:17347; }","duration":"1.359161347s","start":"2026-08-22T11:09:27.067718Z","end":"2026-08-22T11:09:28.42688Z","steps":["trace[1022800518] 'agreement among raft nodes before linearized reading' (duration: 1.355702259s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.426962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:27.067707Z","time spent":"1.359221668s","remote":"127.0.0.1:37322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-08-22T11:09:28.42715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.798465Z","time spent":"3.62867374s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T11:09:28.427313Z","caller":"traceutil/trace.go:171","msg":"trace[1236454678] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17347; }","duration":"4.241860913s","start":"2026-08-22T11:09:24.185443Z","end":"2026-08-22T11:09:28.427304Z","steps":["trace[1236454678] 'agreement among raft nodes before linearized reading' (duration: 4.237753527s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.427379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.185426Z","time spent":"4.241942004s","remote":"127.0.0.1:43216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.42753Z","caller":"traceutil/trace.go:171","msg":"trace[1480028705] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:17347; }","duration":"3.407369283s","start":"2026-08-22T11:09:25.020129Z","end":"2026-08-22T11:09:28.427499Z","steps":["trace[1480028705] 'agreement among raft nodes before linearized reading' (duration: 3.402815695s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.427619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:25.020115Z","time spent":"3.407455595s","remote":"127.0.0.1:49576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.427806Z","caller":"traceutil/trace.go:171","msg":"trace[377654543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17347; }","duration":"3.61694687s","start":"2026-08-22T11:09:24.810851Z","end":"2026-08-22T11:09:28.427797Z","steps":["trace[377654543] 'agreement among raft nodes before linearized reading' (duration: 3.612322459s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.427879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:24.81084Z","time spent":"3.617030313s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T11:09:28.428046Z","caller":"traceutil/trace.go:171","msg":"trace[1575358186] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17347; }","duration":"3.392789112s","start":"2026-08-22T11:09:25.035247Z","end":"2026-08-22T11:09:28.428037Z","steps":["trace[1575358186] 'agreement among raft nodes before linearized reading' (duration: 3.387924175s)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:28.428281Z","caller":"traceutil/trace.go:171","msg":"trace[797047020] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-l8wwv; range_end:; response_count:1; response_revision:17347; }","duration":"4.697547476s","start":"2026-08-22T11:09:23.730721Z","end":"2026-08-22T11:09:28.428268Z","steps":["trace[797047020] 'agreement among raft nodes before linearized reading' (duration: 4.692599876s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.428321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.730716Z","time spent":"4.697595277s","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":21003,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-l8wwv\" "} {"level":"info","ts":"2026-08-22T11:09:28.428333Z","caller":"traceutil/trace.go:171","msg":"trace[2070112012] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:17347; }","duration":"1.619625608s","start":"2026-08-22T11:09:26.808682Z","end":"2026-08-22T11:09:28.428307Z","steps":["trace[2070112012] 'agreement among raft nodes before linearized reading' (duration: 1.614649428s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.428406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:26.808667Z","time spent":"1.61971575s","remote":"127.0.0.1:37762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.428451Z","caller":"traceutil/trace.go:171","msg":"trace[1333198249] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:17347; }","duration":"3.208414886s","start":"2026-08-22T11:09:25.220024Z","end":"2026-08-22T11:09:28.428439Z","steps":["trace[1333198249] 'agreement among raft nodes before linearized reading' (duration: 3.203305732s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.428483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:25.220007Z","time spent":"3.208467438s","remote":"127.0.0.1:37632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.428572Z","caller":"traceutil/trace.go:171","msg":"trace[825622053] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17347; }","duration":"892.099403ms","start":"2026-08-22T11:09:27.536466Z","end":"2026-08-22T11:09:28.428566Z","steps":["trace[825622053] 'agreement among raft nodes before linearized reading' (duration: 886.866126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.428598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:27.536446Z","time spent":"892.145484ms","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-22T11:09:28.428676Z","caller":"traceutil/trace.go:171","msg":"trace[954167625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17347; }","duration":"273.846928ms","start":"2026-08-22T11:09:28.154823Z","end":"2026-08-22T11:09:28.42867Z","steps":["trace[954167625] 'agreement among raft nodes before linearized reading' (duration: 268.547749ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:28.428893Z","caller":"traceutil/trace.go:171","msg":"trace[922635255] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:17347; }","duration":"2.425824149s","start":"2026-08-22T11:09:26.003051Z","end":"2026-08-22T11:09:28.428875Z","steps":["trace[922635255] 'agreement among raft nodes before linearized reading' (duration: 2.420265882s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.428949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:26.003031Z","time spent":"2.425900602s","remote":"127.0.0.1:37424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.429046Z","caller":"traceutil/trace.go:171","msg":"trace[591791858] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:17347; }","duration":"1.464013612s","start":"2026-08-22T11:09:26.965023Z","end":"2026-08-22T11:09:28.429037Z","steps":["trace[591791858] 'agreement among raft nodes before linearized reading' (duration: 1.458230399s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.429084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:26.965002Z","time spent":"1.464072274s","remote":"127.0.0.1:35722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.429174Z","caller":"traceutil/trace.go:171","msg":"trace[261102439] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:17347; }","duration":"2.109479073s","start":"2026-08-22T11:09:26.319687Z","end":"2026-08-22T11:09:28.429166Z","steps":["trace[261102439] 'agreement among raft nodes before linearized reading' (duration: 2.103584477s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.4292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:26.319653Z","time spent":"2.109539615s","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.429417Z","caller":"traceutil/trace.go:171","msg":"trace[63010401] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ts7lw; range_end:; response_count:1; response_revision:17347; }","duration":"2.3939461s","start":"2026-08-22T11:09:26.035464Z","end":"2026-08-22T11:09:28.42941Z","steps":["trace[63010401] 'agreement among raft nodes before linearized reading' (duration: 2.387879909s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.429447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:26.035449Z","time spent":"2.393990982s","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22156,"request content":"key:\"/registry/pods/openstack/nova-compute-default-ts7lw\" "} {"level":"info","ts":"2026-08-22T11:09:28.429532Z","caller":"traceutil/trace.go:171","msg":"trace[120114150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17347; }","duration":"1.235033558s","start":"2026-08-22T11:09:27.194493Z","end":"2026-08-22T11:09:28.429527Z","steps":["trace[120114150] 'agreement among raft nodes before linearized reading' (duration: 1.228909366s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.429558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:27.194483Z","time spent":"1.23506888s","remote":"127.0.0.1:37294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T11:09:28.429639Z","caller":"traceutil/trace.go:171","msg":"trace[1941775945] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-jncdx; range_end:; response_count:1; response_revision:17347; }","duration":"4.577955005s","start":"2026-08-22T11:09:23.851677Z","end":"2026-08-22T11:09:28.429632Z","steps":["trace[1941775945] 'agreement among raft nodes before linearized reading' (duration: 4.571176324s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.429664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:23.851663Z","time spent":"4.577994446s","remote":"127.0.0.1:37456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-jncdx\" "} {"level":"info","ts":"2026-08-22T11:09:28.429909Z","caller":"traceutil/trace.go:171","msg":"trace[754433630] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:17347; }","duration":"2.415360564s","start":"2026-08-22T11:09:26.014536Z","end":"2026-08-22T11:09:28.429896Z","steps":["trace[754433630] 'agreement among raft nodes before linearized reading' (duration: 2.408759848s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.429947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:26.014497Z","time spent":"2.415439636s","remote":"127.0.0.1:37756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.429927Z","caller":"traceutil/trace.go:171","msg":"trace[959739242] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:17347; }","duration":"1.62110525s","start":"2026-08-22T11:09:26.808793Z","end":"2026-08-22T11:09:28.429898Z","steps":["trace[959739242] 'agreement among raft nodes before linearized reading' (duration: 1.614511484s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.430019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:26.808778Z","time spent":"1.621205412s","remote":"127.0.0.1:49588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:09:28.430287Z","caller":"traceutil/trace.go:171","msg":"trace[83918699] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:17347; }","duration":"3.013963075s","start":"2026-08-22T11:09:25.416314Z","end":"2026-08-22T11:09:28.430277Z","steps":["trace[83918699] 'agreement among raft nodes before linearized reading' (duration: 3.006809574s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:28.430323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:25.416298Z","time spent":"3.014014826s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:09:28.430769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:25.035226Z","time spent":"3.395532009s","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-22T11:10:13.891345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15378} {"level":"info","ts":"2026-08-22T11:10:13.989469Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15378,"took":"95.608434ms","hash":3930297047,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":33886208,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T11:10:13.989546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3930297047,"revision":15378,"compact-revision":12998} {"level":"info","ts":"2026-08-22T11:13:21.864119Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:13:21.869423Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T11:13:21.869541Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T11:14:42.141414Z","caller":"traceutil/trace.go:171","msg":"trace[205847166] transaction","detail":"{read_only:false; response_revision:19819; number_of_response:1; }","duration":"181.732675ms","start":"2026-08-22T11:14:41.959654Z","end":"2026-08-22T11:14:42.141386Z","steps":["trace[205847166] 'process raft request' (duration: 180.056769ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:14:42.141562Z","caller":"traceutil/trace.go:171","msg":"trace[509378488] transaction","detail":"{read_only:false; response_revision:19820; number_of_response:1; }","duration":"181.790927ms","start":"2026-08-22T11:14:41.959718Z","end":"2026-08-22T11:14:42.141509Z","steps":["trace[509378488] 'process raft request' (duration: 181.590981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:14:42.141621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.500529ms","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-08-22T11:14:42.141396Z","caller":"traceutil/trace.go:171","msg":"trace[1847772288] linearizableReadLoop","detail":"{readStateIndex:20492; appliedIndex:20489; }","duration":"181.263702ms","start":"2026-08-22T11:14:41.960106Z","end":"2026-08-22T11:14:42.14137Z","steps":["trace[1847772288] 'read index received' (duration: 75.0759ms)","trace[1847772288] 'applied index is now lower than readState.Index' (duration: 106.186562ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:14:42.141686Z","caller":"traceutil/trace.go:171","msg":"trace[83680477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19820; }","duration":"181.624292ms","start":"2026-08-22T11:14:41.960052Z","end":"2026-08-22T11:14:42.141676Z","steps":["trace[83680477] 'agreement among raft nodes before linearized reading' (duration: 181.411196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:14:42.141925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.488439ms","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-08-22T11:14:42.141973Z","caller":"traceutil/trace.go:171","msg":"trace[1733966028] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19820; }","duration":"166.55868ms","start":"2026-08-22T11:14:41.975402Z","end":"2026-08-22T11:14:42.141961Z","steps":["trace[1733966028] 'agreement among raft nodes before linearized reading' (duration: 166.438827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:14:42.142037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.006965ms","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-08-22T11:14:42.142115Z","caller":"traceutil/trace.go:171","msg":"trace[128034541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19820; }","duration":"171.147619ms","start":"2026-08-22T11:14:41.970946Z","end":"2026-08-22T11:14:42.142094Z","steps":["trace[128034541] 'agreement among raft nodes before linearized reading' (duration: 170.880612ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:14:42.458663Z","caller":"traceutil/trace.go:171","msg":"trace[877938503] transaction","detail":"{read_only:false; response_revision:19823; number_of_response:1; }","duration":"310.18445ms","start":"2026-08-22T11:14:42.148454Z","end":"2026-08-22T11:14:42.458638Z","steps":["trace[877938503] 'process raft request' (duration: 310.135979ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:14:42.458822Z","caller":"traceutil/trace.go:171","msg":"trace[1125510891] transaction","detail":"{read_only:false; response_revision:19822; number_of_response:1; }","duration":"311.488116ms","start":"2026-08-22T11:14:42.147202Z","end":"2026-08-22T11:14:42.458691Z","steps":["trace[1125510891] 'process raft request' (duration: 311.25361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:14:42.45882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:14:42.148433Z","time spent":"310.304973ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:14:42.459044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:14:42.147169Z","time spent":"311.784375ms","remote":"127.0.0.1:37550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:14:42.645588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.973644ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T11:14:42.645682Z","caller":"traceutil/trace.go:171","msg":"trace[1040949464] linearizableReadLoop","detail":"{readStateIndex:20496; appliedIndex:20495; }","duration":"145.906974ms","start":"2026-08-22T11:14:42.499759Z","end":"2026-08-22T11:14:42.645666Z","steps":["trace[1040949464] 'read index received' (duration: 23.850058ms)","trace[1040949464] 'applied index is now lower than readState.Index' (duration: 122.055505ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:14:42.645788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.023786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:14:42.645818Z","caller":"traceutil/trace.go:171","msg":"trace[1887412219] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:19823; }","duration":"146.064297ms","start":"2026-08-22T11:14:42.499742Z","end":"2026-08-22T11:14:42.645806Z","steps":["trace[1887412219] 'agreement among raft nodes before linearized reading' (duration: 145.990655ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:15:13.901685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17936} {"level":"info","ts":"2026-08-22T11:15:14.008886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17936,"took":"103.485256ms","hash":2466896295,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":29433856,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-22T11:15:14.00894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2466896295,"revision":17936,"compact-revision":15378} {"level":"info","ts":"2026-08-22T11:18:25.397148Z","caller":"traceutil/trace.go:171","msg":"trace[275213962] linearizableReadLoop","detail":"{readStateIndex:22939; appliedIndex:22936; }","duration":"133.282289ms","start":"2026-08-22T11:18:25.263841Z","end":"2026-08-22T11:18:25.397123Z","steps":["trace[275213962] 'read index received' (duration: 132.94537ms)","trace[275213962] 'applied index is now lower than readState.Index' (duration: 335.859µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:18:25.397246Z","caller":"traceutil/trace.go:171","msg":"trace[869228324] transaction","detail":"{read_only:false; response_revision:22197; number_of_response:1; }","duration":"170.406438ms","start":"2026-08-22T11:18:25.226818Z","end":"2026-08-22T11:18:25.397224Z","steps":["trace[869228324] 'process raft request' (duration: 170.154381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:18:25.397385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.533737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:87332"} {"level":"info","ts":"2026-08-22T11:18:25.397406Z","caller":"traceutil/trace.go:171","msg":"trace[811958526] transaction","detail":"{read_only:false; response_revision:22196; number_of_response:1; }","duration":"170.686736ms","start":"2026-08-22T11:18:25.226712Z","end":"2026-08-22T11:18:25.397399Z","steps":["trace[811958526] 'process raft request' (duration: 170.100299ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:18:25.397434Z","caller":"traceutil/trace.go:171","msg":"trace[2127866056] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:22198; }","duration":"133.6352ms","start":"2026-08-22T11:18:25.263787Z","end":"2026-08-22T11:18:25.397422Z","steps":["trace[2127866056] 'agreement among raft nodes before linearized reading' (duration: 133.444984ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:18:25.397667Z","caller":"traceutil/trace.go:171","msg":"trace[309936275] transaction","detail":"{read_only:false; response_revision:22198; number_of_response:1; }","duration":"166.805707ms","start":"2026-08-22T11:18:25.230853Z","end":"2026-08-22T11:18:25.397659Z","steps":["trace[309936275] 'process raft request' (duration: 166.19363ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:18:25.690395Z","caller":"traceutil/trace.go:171","msg":"trace[2127837372] linearizableReadLoop","detail":"{readStateIndex:22940; appliedIndex:22939; }","duration":"228.876794ms","start":"2026-08-22T11:18:25.461469Z","end":"2026-08-22T11:18:25.690346Z","steps":["trace[2127837372] 'read index received' (duration: 228.763621ms)","trace[2127837372] 'applied index is now lower than readState.Index' (duration: 111.463µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:18:25.690573Z","caller":"traceutil/trace.go:171","msg":"trace[1604401408] transaction","detail":"{read_only:false; response_revision:22199; number_of_response:1; }","duration":"267.162566ms","start":"2026-08-22T11:18:25.423395Z","end":"2026-08-22T11:18:25.690557Z","steps":["trace[1604401408] 'process raft request' (duration: 266.749965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:18:25.690859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.367598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackservers.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:40665"} {"level":"info","ts":"2026-08-22T11:18:25.690942Z","caller":"traceutil/trace.go:171","msg":"trace[1013612307] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackservers.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:22199; }","duration":"229.499521ms","start":"2026-08-22T11:18:25.461426Z","end":"2026-08-22T11:18:25.690925Z","steps":["trace[1013612307] 'agreement among raft nodes before linearized reading' (duration: 229.212683ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:18:25.692397Z","caller":"traceutil/trace.go:171","msg":"trace[411132469] transaction","detail":"{read_only:false; response_revision:22200; number_of_response:1; }","duration":"115.324847ms","start":"2026-08-22T11:18:25.577045Z","end":"2026-08-22T11:18:25.69237Z","steps":["trace[411132469] 'process raft request' (duration: 114.807632ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:20:13.907162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20097} {"level":"info","ts":"2026-08-22T11:20:14.013191Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20097,"took":"101.211312ms","hash":3387255742,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":43425792,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-22T11:20:14.01327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3387255742,"revision":20097,"compact-revision":17936} {"level":"info","ts":"2026-08-22T11:25:13.916719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23321} {"level":"info","ts":"2026-08-22T11:25:14.058957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23321,"took":"138.911607ms","hash":1921786033,"current-db-size-bytes":58232832,"current-db-size":"58 MB","current-db-size-in-use-bytes":31105024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:25:14.059041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1921786033,"revision":23321,"compact-revision":20097}