{"level":"warn","ts":"2026-08-26T05:06:29.042174Z","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-26T05:06:29.042268Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.92:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.92:2380","--initial-cluster=instance=https://162.253.55.92:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.92:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.92: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-26T05:06:29.042339Z","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-26T05:06:29.042352Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-08-26T05:06:29.042368Z","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-26T05:06:29.042991Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"]} {"level":"info","ts":"2026-08-26T05:06:29.04307Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.92: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-26T05:06:29.045597Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.274867ms"} {"level":"info","ts":"2026-08-26T05:06:29.052965Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39a4f14f6507253d","cluster-id":"22d06cab78b8ace5"} {"level":"info","ts":"2026-08-26T05:06:29.053067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T05:06:29.053094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 0"} {"level":"info","ts":"2026-08-26T05:06:29.053108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39a4f14f6507253d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T05:06:29.053123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 1"} {"level":"info","ts":"2026-08-26T05:06:29.053167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"warn","ts":"2026-08-26T05:06:29.057258Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T05:06:29.059012Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T05:06:29.060054Z","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-26T05:06:29.062439Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39a4f14f6507253d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T05:06:29.062695Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39a4f14f6507253d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T05:06:29.062874Z","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-26T05:06:29.062991Z","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-26T05:06:29.063002Z","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-26T05:06:29.06399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"info","ts":"2026-08-26T05:06:29.064588Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","added-peer-id":"39a4f14f6507253d","added-peer-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-08-26T05:06:29.066915Z","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-26T05:06:29.067087Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-08-26T05:06:29.067375Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-08-26T05:06:29.067413Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39a4f14f6507253d","initial-advertise-peer-urls":["https://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T05:06:29.067484Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T05:06:30.05337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T05:06:30.053442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T05:06:30.053481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgPreVoteResp from 39a4f14f6507253d at term 1"} {"level":"info","ts":"2026-08-26T05:06:30.0535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became candidate at term 2"} {"level":"info","ts":"2026-08-26T05:06:30.053511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgVoteResp from 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-08-26T05:06:30.053526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became leader at term 2"} {"level":"info","ts":"2026-08-26T05:06:30.053539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39a4f14f6507253d elected leader 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-08-26T05:06:30.054431Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T05:06:30.055164Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39a4f14f6507253d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.92:2379]}","request-path":"/0/members/39a4f14f6507253d/attributes","cluster-id":"22d06cab78b8ace5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T05:06:30.055176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T05:06:30.055247Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T05:06:30.05545Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T05:06:30.055485Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T05:06:30.055936Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T05:06:30.056064Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T05:06:30.056107Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T05:06:30.058875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.92:2379"} {"level":"info","ts":"2026-08-26T05:06:30.059392Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T05:06:49.135336Z","caller":"traceutil/trace.go:171","msg":"trace[1259782068] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"133.900321ms","start":"2026-08-26T05:06:49.001404Z","end":"2026-08-26T05:06:49.135305Z","steps":["trace[1259782068] 'process raft request' (duration: 133.690776ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:07:12.252365Z","caller":"traceutil/trace.go:171","msg":"trace[76688089] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"156.276068ms","start":"2026-08-26T05:07:12.096065Z","end":"2026-08-26T05:07:12.252341Z","steps":["trace[76688089] 'process raft request' (duration: 156.045232ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:07:12.647746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.538947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:07:12.647916Z","caller":"traceutil/trace.go:171","msg":"trace[1741967107] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"301.68107ms","start":"2026-08-26T05:07:12.3462Z","end":"2026-08-26T05:07:12.647881Z","steps":["trace[1741967107] 'process raft request' (duration: 153.494027ms)","trace[1741967107] 'compare' (duration: 147.395663ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:07:12.647999Z","caller":"traceutil/trace.go:171","msg":"trace[643788046] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"101.115741ms","start":"2026-08-26T05:07:12.546865Z","end":"2026-08-26T05:07:12.647981Z","steps":["trace[643788046] 'process raft request' (duration: 100.999838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:07:12.648062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:07:12.346163Z","time spent":"301.852415ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T05:07:12.648046Z","caller":"traceutil/trace.go:171","msg":"trace[1963041807] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"259.902232ms","start":"2026-08-26T05:07:12.388083Z","end":"2026-08-26T05:07:12.647985Z","steps":["trace[1963041807] 'read index received' (duration: 111.580236ms)","trace[1963041807] 'applied index is now lower than readState.Index' (duration: 148.319096ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:07:12.648251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.153409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:07:12.648348Z","caller":"traceutil/trace.go:171","msg":"trace[1652687051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"260.295443ms","start":"2026-08-26T05:07:12.388023Z","end":"2026-08-26T05:07:12.648319Z","steps":["trace[1652687051] 'agreement among raft nodes before linearized reading' (duration: 260.147189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:07:13.220848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.082359ms","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-26T05:07:13.220913Z","caller":"traceutil/trace.go:171","msg":"trace[537967679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"448.215402ms","start":"2026-08-26T05:07:12.772683Z","end":"2026-08-26T05:07:13.220898Z","steps":["trace[537967679] 'range keys from in-memory index tree' (duration: 447.868243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:07:13.220944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:07:12.77265Z","time spent":"448.287804ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-26T05:07:13.245371Z","caller":"traceutil/trace.go:171","msg":"trace[727040411] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"192.878865ms","start":"2026-08-26T05:07:13.05242Z","end":"2026-08-26T05:07:13.245299Z","steps":["trace[727040411] 'process raft request' (duration: 188.135925ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:08:40.654805Z","caller":"traceutil/trace.go:171","msg":"trace[1521621417] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"178.777348ms","start":"2026-08-26T05:08:40.476Z","end":"2026-08-26T05:08:40.654778Z","steps":["trace[1521621417] 'read index received' (duration: 178.595483ms)","trace[1521621417] 'applied index is now lower than readState.Index' (duration: 180.525µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:08:40.654862Z","caller":"traceutil/trace.go:171","msg":"trace[911761406] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"262.720104ms","start":"2026-08-26T05:08:40.392107Z","end":"2026-08-26T05:08:40.654827Z","steps":["trace[911761406] 'process raft request' (duration: 262.478778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:08:40.655047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.036564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T05:08:40.655123Z","caller":"traceutil/trace.go:171","msg":"trace[80931660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1799; }","duration":"179.161387ms","start":"2026-08-26T05:08:40.475944Z","end":"2026-08-26T05:08:40.655105Z","steps":["trace[80931660] 'agreement among raft nodes before linearized reading' (duration: 178.982243ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:08:40.706853Z","caller":"traceutil/trace.go:171","msg":"trace[1959641015] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"174.9156ms","start":"2026-08-26T05:08:40.531918Z","end":"2026-08-26T05:08:40.706833Z","steps":["trace[1959641015] 'process raft request' (duration: 174.815198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:08:40.707003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.319673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-10f4e66d-3eec-4087-b2ad-9bfdb5ac43f7\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-26T05:08:40.707117Z","caller":"traceutil/trace.go:171","msg":"trace[1698057571] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-10f4e66d-3eec-4087-b2ad-9bfdb5ac43f7; range_end:; response_count:1; response_revision:1800; }","duration":"153.479797ms","start":"2026-08-26T05:08:40.553623Z","end":"2026-08-26T05:08:40.707103Z","steps":["trace[1698057571] 'agreement among raft nodes before linearized reading' (duration: 153.226101ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:08:40.963218Z","caller":"traceutil/trace.go:171","msg":"trace[139807433] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1866; }","duration":"186.651506ms","start":"2026-08-26T05:08:40.776541Z","end":"2026-08-26T05:08:40.963192Z","steps":["trace[139807433] 'read index received' (duration: 106.185299ms)","trace[139807433] 'applied index is now lower than readState.Index' (duration: 80.464507ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:08:40.963302Z","caller":"traceutil/trace.go:171","msg":"trace[1226511585] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"250.973306ms","start":"2026-08-26T05:08:40.712282Z","end":"2026-08-26T05:08:40.963255Z","steps":["trace[1226511585] 'process raft request' (duration: 170.399546ms)","trace[1226511585] 'compare' (duration: 80.275932ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:08:40.96342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.865572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-26T05:08:40.963482Z","caller":"traceutil/trace.go:171","msg":"trace[1431171784] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1802; }","duration":"186.968934ms","start":"2026-08-26T05:08:40.776498Z","end":"2026-08-26T05:08:40.963467Z","steps":["trace[1431171784] 'agreement among raft nodes before linearized reading' (duration: 186.781679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:09:25.11889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.585148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-26T05:09:25.118994Z","caller":"traceutil/trace.go:171","msg":"trace[1726686511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2097; }","duration":"102.759473ms","start":"2026-08-26T05:09:25.016213Z","end":"2026-08-26T05:09:25.118973Z","steps":["trace[1726686511] 'range keys from in-memory index tree' (duration: 102.346042ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:09:25.884202Z","caller":"traceutil/trace.go:171","msg":"trace[1343759570] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"119.687741ms","start":"2026-08-26T05:09:25.764489Z","end":"2026-08-26T05:09:25.884177Z","steps":["trace[1343759570] 'process raft request' (duration: 119.532737ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:09:31.264301Z","caller":"traceutil/trace.go:171","msg":"trace[1163917956] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"101.572503ms","start":"2026-08-26T05:09:31.162693Z","end":"2026-08-26T05:09:31.264266Z","steps":["trace[1163917956] 'process raft request' (duration: 101.373318ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:10:48.48343Z","caller":"traceutil/trace.go:171","msg":"trace[172572373] linearizableReadLoop","detail":"{readStateIndex:2841; appliedIndex:2840; }","duration":"128.539455ms","start":"2026-08-26T05:10:48.354864Z","end":"2026-08-26T05:10:48.483403Z","steps":["trace[172572373] 'read index received' (duration: 128.398192ms)","trace[172572373] 'applied index is now lower than readState.Index' (duration: 140.203µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:10:48.483601Z","caller":"traceutil/trace.go:171","msg":"trace[765077637] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"151.744203ms","start":"2026-08-26T05:10:48.331843Z","end":"2026-08-26T05:10:48.483587Z","steps":["trace[765077637] 'process raft request' (duration: 151.378564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:10:48.483842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.964696ms","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-26T05:10:48.483902Z","caller":"traceutil/trace.go:171","msg":"trace[1270078878] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2741; }","duration":"129.090069ms","start":"2026-08-26T05:10:48.354795Z","end":"2026-08-26T05:10:48.483885Z","steps":["trace[1270078878] 'agreement among raft nodes before linearized reading' (duration: 128.931795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:10:48.553533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.619856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-26T05:10:48.553623Z","caller":"traceutil/trace.go:171","msg":"trace[278672097] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2742; }","duration":"126.75712ms","start":"2026-08-26T05:10:48.426845Z","end":"2026-08-26T05:10:48.553602Z","steps":["trace[278672097] 'agreement among raft nodes before linearized reading' (duration: 126.521644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:10:48.554027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.962483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:10:48.554129Z","caller":"traceutil/trace.go:171","msg":"trace[601563708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2742; }","duration":"166.146328ms","start":"2026-08-26T05:10:48.387958Z","end":"2026-08-26T05:10:48.554105Z","steps":["trace[601563708] 'agreement among raft nodes before linearized reading' (duration: 165.298636ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:10:53.784968Z","caller":"traceutil/trace.go:171","msg":"trace[337804063] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"115.190427ms","start":"2026-08-26T05:10:53.669719Z","end":"2026-08-26T05:10:53.784909Z","steps":["trace[337804063] 'process raft request' (duration: 114.794227ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:12:58.337372Z","caller":"traceutil/trace.go:171","msg":"trace[882442557] transaction","detail":"{read_only:false; response_revision:3578; number_of_response:1; }","duration":"104.066445ms","start":"2026-08-26T05:12:58.233264Z","end":"2026-08-26T05:12:58.33733Z","steps":["trace[882442557] 'process raft request' (duration: 98.036742ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:12:58.337526Z","caller":"traceutil/trace.go:171","msg":"trace[418470081] linearizableReadLoop","detail":"{readStateIndex:3711; appliedIndex:3709; }","duration":"104.125997ms","start":"2026-08-26T05:12:58.233377Z","end":"2026-08-26T05:12:58.337503Z","steps":["trace[418470081] 'read index received' (duration: 20.640172ms)","trace[418470081] 'applied index is now lower than readState.Index' (duration: 83.484644ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:12:58.337689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.287271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/secretgen-controller-cluster-role\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:12:58.33774Z","caller":"traceutil/trace.go:171","msg":"trace[901521601] range","detail":"{range_begin:/registry/clusterroles/secretgen-controller-cluster-role; range_end:; response_count:0; response_revision:3579; }","duration":"104.375273ms","start":"2026-08-26T05:12:58.233354Z","end":"2026-08-26T05:12:58.337729Z","steps":["trace[901521601] 'agreement among raft nodes before linearized reading' (duration: 104.285191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:13:30.803001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.591497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-26T05:13:30.803176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.603367ms","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-26T05:13:30.803218Z","caller":"traceutil/trace.go:171","msg":"trace[1844170796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3794; }","duration":"228.70505ms","start":"2026-08-26T05:13:30.574497Z","end":"2026-08-26T05:13:30.803202Z","steps":["trace[1844170796] 'range keys from in-memory index tree' (duration: 228.448283ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:13:30.803306Z","caller":"traceutil/trace.go:171","msg":"trace[279370755] linearizableReadLoop","detail":"{readStateIndex:3935; appliedIndex:3934; }","duration":"121.057494ms","start":"2026-08-26T05:13:30.68222Z","end":"2026-08-26T05:13:30.803277Z","steps":["trace[279370755] 'read index received' (duration: 120.343916ms)","trace[279370755] 'applied index is now lower than readState.Index' (duration: 711.808µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:13:30.803378Z","caller":"traceutil/trace.go:171","msg":"trace[664718619] transaction","detail":"{read_only:false; response_revision:3795; number_of_response:1; }","duration":"121.151696ms","start":"2026-08-26T05:13:30.682163Z","end":"2026-08-26T05:13:30.803315Z","steps":["trace[664718619] 'process raft request' (duration: 120.311584ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:13:30.803477Z","caller":"traceutil/trace.go:171","msg":"trace[2069945759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3794; }","duration":"234.132751ms","start":"2026-08-26T05:13:30.569335Z","end":"2026-08-26T05:13:30.803467Z","steps":["trace[2069945759] 'range keys from in-memory index tree' (duration: 233.426153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:13:30.803446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.202568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:13:30.803585Z","caller":"traceutil/trace.go:171","msg":"trace[1875666022] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3795; }","duration":"121.381963ms","start":"2026-08-26T05:13:30.682182Z","end":"2026-08-26T05:13:30.803564Z","steps":["trace[1875666022] 'agreement among raft nodes before linearized reading' (duration: 121.201678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:14:54.912291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.97783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:14:54.912458Z","caller":"traceutil/trace.go:171","msg":"trace[422704060] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"233.432993ms","start":"2026-08-26T05:14:54.678992Z","end":"2026-08-26T05:14:54.912425Z","steps":["trace[422704060] 'process raft request' (duration: 32.221816ms)","trace[422704060] 'compare' (duration: 200.847107ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:14:54.912568Z","caller":"traceutil/trace.go:171","msg":"trace[2074336906] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"129.513514ms","start":"2026-08-26T05:14:54.783036Z","end":"2026-08-26T05:14:54.91255Z","steps":["trace[2074336906] 'process raft request' (duration: 129.398291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:14:54.913133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.115266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-08-26T05:14:54.913304Z","caller":"traceutil/trace.go:171","msg":"trace[1259843728] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4844; }","duration":"230.326352ms","start":"2026-08-26T05:14:54.682956Z","end":"2026-08-26T05:14:54.913282Z","steps":["trace[1259843728] 'agreement among raft nodes before linearized reading' (duration: 229.965092ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:14:54.912876Z","caller":"traceutil/trace.go:171","msg":"trace[1904007977] linearizableReadLoop","detail":"{readStateIndex:5026; appliedIndex:5025; }","duration":"229.812279ms","start":"2026-08-26T05:14:54.683003Z","end":"2026-08-26T05:14:54.912815Z","steps":["trace[1904007977] 'read index received' (duration: 28.205632ms)","trace[1904007977] 'applied index is now lower than readState.Index' (duration: 201.603957ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:14:54.913839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.678417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-26T05:14:54.913903Z","caller":"traceutil/trace.go:171","msg":"trace[227195844] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4844; }","duration":"159.79212ms","start":"2026-08-26T05:14:54.754085Z","end":"2026-08-26T05:14:54.913877Z","steps":["trace[227195844] 'agreement among raft nodes before linearized reading' (duration: 159.562124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:14:59.485877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.082522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:14:59.486118Z","caller":"traceutil/trace.go:171","msg":"trace[1306001495] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5009; }","duration":"164.328168ms","start":"2026-08-26T05:14:59.321723Z","end":"2026-08-26T05:14:59.486052Z","steps":["trace[1306001495] 'count revisions from in-memory index tree' (duration: 163.960428ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:14:59.486119Z","caller":"traceutil/trace.go:171","msg":"trace[2080165691] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"137.125191ms","start":"2026-08-26T05:14:59.34896Z","end":"2026-08-26T05:14:59.486085Z","steps":["trace[2080165691] 'process raft request' (duration: 132.934082ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:14:59.691152Z","caller":"traceutil/trace.go:171","msg":"trace[1784488735] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"162.803028ms","start":"2026-08-26T05:14:59.528328Z","end":"2026-08-26T05:14:59.691131Z","steps":["trace[1784488735] 'process raft request' (duration: 162.694705ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:14:59.717361Z","caller":"traceutil/trace.go:171","msg":"trace[1865184215] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"180.9269ms","start":"2026-08-26T05:14:59.5364Z","end":"2026-08-26T05:14:59.717327Z","steps":["trace[1865184215] 'process raft request' (duration: 180.738245ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:00.123494Z","caller":"traceutil/trace.go:171","msg":"trace[39054957] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"226.153404ms","start":"2026-08-26T05:14:59.897317Z","end":"2026-08-26T05:15:00.12347Z","steps":["trace[39054957] 'process raft request' (duration: 225.979389ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:00.128146Z","caller":"traceutil/trace.go:171","msg":"trace[856488246] linearizableReadLoop","detail":"{readStateIndex:5204; appliedIndex:5202; }","duration":"227.188621ms","start":"2026-08-26T05:14:59.900942Z","end":"2026-08-26T05:15:00.128131Z","steps":["trace[856488246] 'read index received' (duration: 222.449458ms)","trace[856488246] 'applied index is now lower than readState.Index' (duration: 4.737863ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:15:00.128202Z","caller":"traceutil/trace.go:171","msg":"trace[892116563] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"228.854804ms","start":"2026-08-26T05:14:59.899306Z","end":"2026-08-26T05:15:00.128161Z","steps":["trace[892116563] 'process raft request' (duration: 228.709501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:15:00.128363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.407316ms","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-26T05:15:00.128407Z","caller":"traceutil/trace.go:171","msg":"trace[1008516788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5018; }","duration":"227.496869ms","start":"2026-08-26T05:14:59.900901Z","end":"2026-08-26T05:15:00.128398Z","steps":["trace[1008516788] 'agreement among raft nodes before linearized reading' (duration: 227.333984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:15:00.128641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.468676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-08-26T05:15:00.128786Z","caller":"traceutil/trace.go:171","msg":"trace[1207585079] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5018; }","duration":"148.647321ms","start":"2026-08-26T05:14:59.980107Z","end":"2026-08-26T05:15:00.128754Z","steps":["trace[1207585079] 'agreement among raft nodes before linearized reading' (duration: 148.293662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:15:11.160971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.111669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T05:15:11.161087Z","caller":"traceutil/trace.go:171","msg":"trace[749365523] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"273.855962ms","start":"2026-08-26T05:15:10.887213Z","end":"2026-08-26T05:15:11.161069Z","steps":["trace[749365523] 'process raft request' (duration: 71.435965ms)","trace[749365523] 'compare' (duration: 202.016017ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:15:11.279089Z","caller":"traceutil/trace.go:171","msg":"trace[1435599282] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"387.847303ms","start":"2026-08-26T05:15:10.891213Z","end":"2026-08-26T05:15:11.27906Z","steps":["trace[1435599282] 'process raft request' (duration: 381.538559ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:11.279113Z","caller":"traceutil/trace.go:171","msg":"trace[685071959] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"387.691248ms","start":"2026-08-26T05:15:10.891399Z","end":"2026-08-26T05:15:11.27909Z","steps":["trace[685071959] 'process raft request' (duration: 387.581275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:15:11.27924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:15:10.891393Z","time spent":"387.774741ms","remote":"127.0.0.1:34508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:15:11.279239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:15:10.8912Z","time spent":"387.944086ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:15:11.279388Z","caller":"traceutil/trace.go:171","msg":"trace[1436054826] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5445; }","duration":"203.045533ms","start":"2026-08-26T05:15:11.076319Z","end":"2026-08-26T05:15:11.279364Z","steps":["trace[1436054826] 'read index received' (duration: 196.442512ms)","trace[1436054826] 'applied index is now lower than readState.Index' (duration: 6.602111ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:15:11.279421Z","caller":"traceutil/trace.go:171","msg":"trace[790313071] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"307.175648ms","start":"2026-08-26T05:15:10.972237Z","end":"2026-08-26T05:15:11.279413Z","steps":["trace[790313071] 'process raft request' (duration: 307.024384ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:11.279465Z","caller":"traceutil/trace.go:171","msg":"trace[1655543452] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"387.916005ms","start":"2026-08-26T05:15:10.891544Z","end":"2026-08-26T05:15:11.27946Z","steps":["trace[1655543452] 'process raft request' (duration: 387.497644ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:15:11.279504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:15:10.891538Z","time spent":"387.943725ms","remote":"127.0.0.1:34300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:15:11.279546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:15:10.972223Z","time spent":"307.247059ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:15:11.279608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.270719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-26T05:15:11.279677Z","caller":"traceutil/trace.go:171","msg":"trace[745942393] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5253; }","duration":"203.375862ms","start":"2026-08-26T05:15:11.076283Z","end":"2026-08-26T05:15:11.279659Z","steps":["trace[745942393] 'agreement among raft nodes before linearized reading' (duration: 203.204128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:15:18.83319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.718309ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-26T05:15:18.833321Z","caller":"traceutil/trace.go:171","msg":"trace[1598004558] linearizableReadLoop","detail":"{readStateIndex:5625; appliedIndex:5624; }","duration":"127.348677ms","start":"2026-08-26T05:15:18.705951Z","end":"2026-08-26T05:15:18.833299Z","steps":["trace[1598004558] 'read index received' (duration: 22.440472ms)","trace[1598004558] 'applied index is now lower than readState.Index' (duration: 104.906215ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:15:18.833562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.615884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T05:15:18.83362Z","caller":"traceutil/trace.go:171","msg":"trace[986811904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5423; }","duration":"127.715667ms","start":"2026-08-26T05:15:18.70589Z","end":"2026-08-26T05:15:18.833605Z","steps":["trace[986811904] 'agreement among raft nodes before linearized reading' (duration: 127.526082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:15:18.833656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.464877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2011a85c-d6e7-4904-b55b-f4b025506469\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-26T05:15:18.833744Z","caller":"traceutil/trace.go:171","msg":"trace[266342875] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2011a85c-d6e7-4904-b55b-f4b025506469; range_end:; response_count:1; response_revision:5423; }","duration":"118.630591ms","start":"2026-08-26T05:15:18.715094Z","end":"2026-08-26T05:15:18.833725Z","steps":["trace[266342875] 'agreement among raft nodes before linearized reading' (duration: 118.361244ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:19.069887Z","caller":"traceutil/trace.go:171","msg":"trace[1640681119] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"228.454164ms","start":"2026-08-26T05:15:18.841403Z","end":"2026-08-26T05:15:19.069857Z","steps":["trace[1640681119] 'process raft request' (duration: 194.335057ms)","trace[1640681119] 'compare' (duration: 33.974213ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:15:19.267527Z","caller":"traceutil/trace.go:171","msg":"trace[576133815] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"149.699198ms","start":"2026-08-26T05:15:19.117802Z","end":"2026-08-26T05:15:19.267501Z","steps":["trace[576133815] 'process raft request' (duration: 145.483508ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:19.267583Z","caller":"traceutil/trace.go:171","msg":"trace[1856077357] linearizableReadLoop","detail":"{readStateIndex:5634; appliedIndex:5629; }","duration":"123.538029ms","start":"2026-08-26T05:15:19.144027Z","end":"2026-08-26T05:15:19.267565Z","steps":["trace[1856077357] 'read index received' (duration: 119.283188ms)","trace[1856077357] 'applied index is now lower than readState.Index' (duration: 4.253981ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:15:19.267631Z","caller":"traceutil/trace.go:171","msg":"trace[930415092] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"149.394051ms","start":"2026-08-26T05:15:19.118209Z","end":"2026-08-26T05:15:19.267603Z","steps":["trace[930415092] 'process raft request' (duration: 149.262117ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:19.267727Z","caller":"traceutil/trace.go:171","msg":"trace[80673307] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"149.77628ms","start":"2026-08-26T05:15:19.117922Z","end":"2026-08-26T05:15:19.267699Z","steps":["trace[80673307] 'process raft request' (duration: 149.508553ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:19.2678Z","caller":"traceutil/trace.go:171","msg":"trace[1923395816] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"149.36158ms","start":"2026-08-26T05:15:19.118409Z","end":"2026-08-26T05:15:19.26777Z","steps":["trace[1923395816] 'process raft request' (duration: 149.089053ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:15:19.267805Z","caller":"traceutil/trace.go:171","msg":"trace[1385856860] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"147.879051ms","start":"2026-08-26T05:15:19.119894Z","end":"2026-08-26T05:15:19.267773Z","steps":["trace[1385856860] 'process raft request' (duration: 147.631965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:15:19.267683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.642511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:15:19.26821Z","caller":"traceutil/trace.go:171","msg":"trace[519670852] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5432; }","duration":"124.221846ms","start":"2026-08-26T05:15:19.143978Z","end":"2026-08-26T05:15:19.2682Z","steps":["trace[519670852] 'agreement among raft nodes before linearized reading' (duration: 123.658451ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:16:08.657965Z","caller":"traceutil/trace.go:171","msg":"trace[1678145574] transaction","detail":"{read_only:false; response_revision:5901; number_of_response:1; }","duration":"125.846669ms","start":"2026-08-26T05:16:08.532081Z","end":"2026-08-26T05:16:08.657928Z","steps":["trace[1678145574] 'process raft request' (duration: 100.85511ms)","trace[1678145574] 'compare' (duration: 24.740163ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:16:30.081946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-08-26T05:16:30.155466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"71.932438ms","hash":2949782288,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":34029568,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T05:16:30.155573Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2949782288,"revision":2969,"compact-revision":-1} {"level":"info","ts":"2026-08-26T05:21:13.217952Z","caller":"traceutil/trace.go:171","msg":"trace[1882628677] linearizableReadLoop","detail":"{readStateIndex:8460; appliedIndex:8459; }","duration":"264.909962ms","start":"2026-08-26T05:21:12.953018Z","end":"2026-08-26T05:21:13.217928Z","steps":["trace[1882628677] 'read index received' (duration: 264.711247ms)","trace[1882628677] 'applied index is now lower than readState.Index' (duration: 197.785µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:21:13.21801Z","caller":"traceutil/trace.go:171","msg":"trace[675762069] transaction","detail":"{read_only:false; response_revision:8175; number_of_response:1; }","duration":"400.892516ms","start":"2026-08-26T05:21:12.817087Z","end":"2026-08-26T05:21:13.21798Z","steps":["trace[675762069] 'process raft request' (duration: 400.694011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:21:13.21813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.082907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T05:21:13.218161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:21:12.817056Z","time spent":"401.02748ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:21:13.218169Z","caller":"traceutil/trace.go:171","msg":"trace[958135113] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8175; }","duration":"265.145478ms","start":"2026-08-26T05:21:12.953012Z","end":"2026-08-26T05:21:13.218157Z","steps":["trace[958135113] 'agreement among raft nodes before linearized reading' (duration: 265.052506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:21:13.218243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.073024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-08-26T05:21:13.218276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.890017ms","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-26T05:21:13.218315Z","caller":"traceutil/trace.go:171","msg":"trace[379832096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8175; }","duration":"146.9505ms","start":"2026-08-26T05:21:13.071356Z","end":"2026-08-26T05:21:13.218306Z","steps":["trace[379832096] 'agreement among raft nodes before linearized reading' (duration: 146.821996ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:21:13.218312Z","caller":"traceutil/trace.go:171","msg":"trace[222017840] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8175; }","duration":"211.183467ms","start":"2026-08-26T05:21:13.007112Z","end":"2026-08-26T05:21:13.218295Z","steps":["trace[222017840] 'agreement among raft nodes before linearized reading' (duration: 210.983702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:21:13.218322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.640369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T05:21:13.21839Z","caller":"traceutil/trace.go:171","msg":"trace[1818836134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8175; }","duration":"147.739001ms","start":"2026-08-26T05:21:13.070631Z","end":"2026-08-26T05:21:13.21837Z","steps":["trace[1818836134] 'agreement among raft nodes before linearized reading' (duration: 147.568027ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:21:25.565269Z","caller":"traceutil/trace.go:171","msg":"trace[841577579] linearizableReadLoop","detail":"{readStateIndex:8529; appliedIndex:8528; }","duration":"289.395034ms","start":"2026-08-26T05:21:25.275845Z","end":"2026-08-26T05:21:25.56524Z","steps":["trace[841577579] 'read index received' (duration: 289.093225ms)","trace[841577579] 'applied index is now lower than readState.Index' (duration: 300.709µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:21:25.565411Z","caller":"traceutil/trace.go:171","msg":"trace[1807230383] transaction","detail":"{read_only:false; response_revision:8240; number_of_response:1; }","duration":"312.072875ms","start":"2026-08-26T05:21:25.253323Z","end":"2026-08-26T05:21:25.565395Z","steps":["trace[1807230383] 'process raft request' (duration: 311.716285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:21:25.565549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:21:25.253295Z","time spent":"312.153028ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:21:25.565597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.729453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T05:21:25.565692Z","caller":"traceutil/trace.go:171","msg":"trace[1560350025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8240; }","duration":"289.850817ms","start":"2026-08-26T05:21:25.27581Z","end":"2026-08-26T05:21:25.565661Z","steps":["trace[1560350025] 'agreement among raft nodes before linearized reading' (duration: 289.662871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:21:25.582422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.298405ms","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":"warn","ts":"2026-08-26T05:21:25.582443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.703764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:21:25.582463Z","caller":"traceutil/trace.go:171","msg":"trace[783470260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8241; }","duration":"306.374386ms","start":"2026-08-26T05:21:25.276078Z","end":"2026-08-26T05:21:25.582453Z","steps":["trace[783470260] 'agreement among raft nodes before linearized reading' (duration: 306.260853ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:21:25.582475Z","caller":"traceutil/trace.go:171","msg":"trace[40489551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8241; }","duration":"196.775336ms","start":"2026-08-26T05:21:25.385691Z","end":"2026-08-26T05:21:25.582466Z","steps":["trace[40489551] 'agreement among raft nodes before linearized reading' (duration: 196.719345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:21:25.582488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:21:25.276056Z","time spent":"306.426048ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-26T05:21:26.328751Z","caller":"traceutil/trace.go:171","msg":"trace[14404744] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"149.966343ms","start":"2026-08-26T05:21:26.178753Z","end":"2026-08-26T05:21:26.328719Z","steps":["trace[14404744] 'process raft request' (duration: 116.941244ms)","trace[14404744] 'compare' (duration: 32.770032ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:21:30.089504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6073} {"level":"info","ts":"2026-08-26T05:21:30.180297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6073,"took":"88.804251ms","hash":1596841724,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":23220224,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-26T05:21:30.18035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1596841724,"revision":6073,"compact-revision":2969} {"level":"info","ts":"2026-08-26T05:21:53.933758Z","caller":"traceutil/trace.go:171","msg":"trace[890247683] linearizableReadLoop","detail":"{readStateIndex:8745; appliedIndex:8744; }","duration":"209.551054ms","start":"2026-08-26T05:21:53.724175Z","end":"2026-08-26T05:21:53.933726Z","steps":["trace[890247683] 'read index received' (duration: 2.59386ms)","trace[890247683] 'applied index is now lower than readState.Index' (duration: 206.955664ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:21:53.934163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.971125ms","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-26T05:21:53.93425Z","caller":"traceutil/trace.go:171","msg":"trace[84056807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8447; }","duration":"210.073948ms","start":"2026-08-26T05:21:53.724156Z","end":"2026-08-26T05:21:53.93423Z","steps":["trace[84056807] 'agreement among raft nodes before linearized reading' (duration: 209.79797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:21:53.934273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.064076ms","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-26T05:21:53.934261Z","caller":"traceutil/trace.go:171","msg":"trace[431921062] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"263.145638ms","start":"2026-08-26T05:21:53.671053Z","end":"2026-08-26T05:21:53.934199Z","steps":["trace[431921062] 'process raft request' (duration: 163.199646ms)","trace[431921062] 'compare' (duration: 99.201882ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:21:53.934345Z","caller":"traceutil/trace.go:171","msg":"trace[1469915484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8447; }","duration":"178.179689ms","start":"2026-08-26T05:21:53.756146Z","end":"2026-08-26T05:21:53.934325Z","steps":["trace[1469915484] 'agreement among raft nodes before linearized reading' (duration: 177.995924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:21:53.934388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.631951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T05:21:53.934494Z","caller":"traceutil/trace.go:171","msg":"trace[822438065] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8447; }","duration":"152.785905ms","start":"2026-08-26T05:21:53.781686Z","end":"2026-08-26T05:21:53.934472Z","steps":["trace[822438065] 'agreement among raft nodes before linearized reading' (duration: 152.58277ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:21:54.130757Z","caller":"traceutil/trace.go:171","msg":"trace[1931449223] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"191.56955ms","start":"2026-08-26T05:21:53.939163Z","end":"2026-08-26T05:21:54.130733Z","steps":["trace[1931449223] 'process raft request' (duration: 133.783683ms)","trace[1931449223] 'compare' (duration: 57.684144ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:21:59.659855Z","caller":"traceutil/trace.go:171","msg":"trace[1689240956] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"179.508175ms","start":"2026-08-26T05:21:59.480324Z","end":"2026-08-26T05:21:59.659832Z","steps":["trace[1689240956] 'process raft request' (duration: 179.34218ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:21:59.895138Z","caller":"traceutil/trace.go:171","msg":"trace[1311899300] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"151.48515ms","start":"2026-08-26T05:21:59.743619Z","end":"2026-08-26T05:21:59.895104Z","steps":["trace[1311899300] 'process raft request' (duration: 114.234946ms)","trace[1311899300] 'compare' (duration: 37.09944ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:22:06.716776Z","caller":"traceutil/trace.go:171","msg":"trace[1761035701] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"103.597771ms","start":"2026-08-26T05:22:06.613157Z","end":"2026-08-26T05:22:06.716755Z","steps":["trace[1761035701] 'process raft request' (duration: 103.547549ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:22:06.717012Z","caller":"traceutil/trace.go:171","msg":"trace[1090143925] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"107.330961ms","start":"2026-08-26T05:22:06.609653Z","end":"2026-08-26T05:22:06.716984Z","steps":["trace[1090143925] 'process raft request' (duration: 75.314358ms)","trace[1090143925] 'compare' (duration: 31.586471ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:22:06.717078Z","caller":"traceutil/trace.go:171","msg":"trace[1739224949] linearizableReadLoop","detail":"{readStateIndex:8855; appliedIndex:8854; }","duration":"104.081923ms","start":"2026-08-26T05:22:06.61295Z","end":"2026-08-26T05:22:06.717032Z","steps":["trace[1739224949] 'read index received' (duration: 72.009239ms)","trace[1739224949] 'applied index is now lower than readState.Index' (duration: 32.068694ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:22:06.717304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.335261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-chzr9\" ","response":"range_response_count:1 size:4667"} {"level":"info","ts":"2026-08-26T05:22:06.717407Z","caller":"traceutil/trace.go:171","msg":"trace[1410562301] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-chzr9; range_end:; response_count:1; response_revision:8553; }","duration":"104.492815ms","start":"2026-08-26T05:22:06.612897Z","end":"2026-08-26T05:22:06.71739Z","steps":["trace[1410562301] 'agreement among raft nodes before linearized reading' (duration: 104.295139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:22:06.717407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.412262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-chzr9\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-08-26T05:22:06.717545Z","caller":"traceutil/trace.go:171","msg":"trace[1535999438] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-chzr9; range_end:; response_count:1; response_revision:8553; }","duration":"104.634998ms","start":"2026-08-26T05:22:06.612892Z","end":"2026-08-26T05:22:06.717527Z","steps":["trace[1535999438] 'agreement among raft nodes before linearized reading' (duration: 104.341451ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:22:07.258319Z","caller":"traceutil/trace.go:171","msg":"trace[734942740] linearizableReadLoop","detail":"{readStateIndex:8861; appliedIndex:8860; }","duration":"140.33845ms","start":"2026-08-26T05:22:07.117957Z","end":"2026-08-26T05:22:07.258296Z","steps":["trace[734942740] 'read index received' (duration: 40.936793ms)","trace[734942740] 'applied index is now lower than readState.Index' (duration: 99.400747ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:22:07.258356Z","caller":"traceutil/trace.go:171","msg":"trace[456392771] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"141.09678ms","start":"2026-08-26T05:22:07.117244Z","end":"2026-08-26T05:22:07.258341Z","steps":["trace[456392771] 'process raft request' (duration: 41.633721ms)","trace[456392771] 'compare' (duration: 99.295155ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:22:07.258512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.539085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-chzr9\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-08-26T05:22:07.258563Z","caller":"traceutil/trace.go:171","msg":"trace[2076708708] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-chzr9; range_end:; response_count:1; response_revision:8558; }","duration":"140.633678ms","start":"2026-08-26T05:22:07.117916Z","end":"2026-08-26T05:22:07.25855Z","steps":["trace[2076708708] 'agreement among raft nodes before linearized reading' (duration: 140.450663ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:22:07.660968Z","caller":"traceutil/trace.go:171","msg":"trace[1078364242] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"165.382995ms","start":"2026-08-26T05:22:07.49557Z","end":"2026-08-26T05:22:07.660953Z","steps":["trace[1078364242] 'process raft request' (duration: 165.293842ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:22:07.679958Z","caller":"traceutil/trace.go:171","msg":"trace[42713383] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"103.093527ms","start":"2026-08-26T05:22:07.576841Z","end":"2026-08-26T05:22:07.679935Z","steps":["trace[42713383] 'process raft request' (duration: 102.960753ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:23:00.588688Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249f.wal"} {"level":"info","ts":"2026-08-26T05:23:23.875628Z","caller":"traceutil/trace.go:171","msg":"trace[1127913146] transaction","detail":"{read_only:false; response_revision:9246; number_of_response:1; }","duration":"219.348318ms","start":"2026-08-26T05:23:23.656251Z","end":"2026-08-26T05:23:23.875599Z","steps":["trace[1127913146] 'process raft request' (duration: 219.153833ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:24:08.729547Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39a4f14f6507253d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T05:24:08.734285Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-26T05:24:08.734413Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-26T05:26:30.096028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8272} {"level":"info","ts":"2026-08-26T05:26:30.17462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8272,"took":"76.217964ms","hash":1400182298,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":25718784,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-26T05:26:30.174705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1400182298,"revision":8272,"compact-revision":6073} {"level":"info","ts":"2026-08-26T05:31:30.103014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10796} {"level":"info","ts":"2026-08-26T05:31:30.183367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10796,"took":"77.650131ms","hash":950550028,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":27471872,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-26T05:31:30.183431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":950550028,"revision":10796,"compact-revision":8272} {"level":"info","ts":"2026-08-26T05:33:08.149192Z","caller":"traceutil/trace.go:171","msg":"trace[454213362] linearizableReadLoop","detail":"{readStateIndex:14103; appliedIndex:14102; }","duration":"131.732728ms","start":"2026-08-26T05:33:08.017437Z","end":"2026-08-26T05:33:08.14917Z","steps":["trace[454213362] 'read index received' (duration: 131.539093ms)","trace[454213362] 'applied index is now lower than readState.Index' (duration: 192.685µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:33:08.149292Z","caller":"traceutil/trace.go:171","msg":"trace[1108287204] transaction","detail":"{read_only:false; response_revision:13635; number_of_response:1; }","duration":"139.08312ms","start":"2026-08-26T05:33:08.01018Z","end":"2026-08-26T05:33:08.149263Z","steps":["trace[1108287204] 'process raft request' (duration: 138.859845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:33:08.149391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.95741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T05:33:08.149412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.965674ms","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-26T05:33:08.149437Z","caller":"traceutil/trace.go:171","msg":"trace[871765206] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:13635; }","duration":"123.052422ms","start":"2026-08-26T05:33:08.026373Z","end":"2026-08-26T05:33:08.149425Z","steps":["trace[871765206] 'agreement among raft nodes before linearized reading' (duration: 122.9503ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:33:08.149443Z","caller":"traceutil/trace.go:171","msg":"trace[1918685369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13635; }","duration":"132.012625ms","start":"2026-08-26T05:33:08.017421Z","end":"2026-08-26T05:33:08.149434Z","steps":["trace[1918685369] 'agreement among raft nodes before linearized reading' (duration: 131.885282ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:33:08.253079Z","caller":"traceutil/trace.go:171","msg":"trace[2103839347] transaction","detail":"{read_only:false; response_revision:13636; number_of_response:1; }","duration":"100.244857ms","start":"2026-08-26T05:33:08.152784Z","end":"2026-08-26T05:33:08.253029Z","steps":["trace[2103839347] 'process raft request' (duration: 98.743759ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:33:23.301192Z","caller":"traceutil/trace.go:171","msg":"trace[2012227177] linearizableReadLoop","detail":"{readStateIndex:14201; appliedIndex:14200; }","duration":"131.848811ms","start":"2026-08-26T05:33:23.169315Z","end":"2026-08-26T05:33:23.301164Z","steps":["trace[2012227177] 'read index received' (duration: 131.645656ms)","trace[2012227177] 'applied index is now lower than readState.Index' (duration: 202.105µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:33:23.301246Z","caller":"traceutil/trace.go:171","msg":"trace[196899710] transaction","detail":"{read_only:false; response_revision:13729; number_of_response:1; }","duration":"141.462819ms","start":"2026-08-26T05:33:23.159751Z","end":"2026-08-26T05:33:23.301214Z","steps":["trace[196899710] 'process raft request' (duration: 141.285345ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:33:23.301393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.049556ms","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-26T05:33:23.301485Z","caller":"traceutil/trace.go:171","msg":"trace[1560616909] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13729; }","duration":"132.20558ms","start":"2026-08-26T05:33:23.169261Z","end":"2026-08-26T05:33:23.301467Z","steps":["trace[1560616909] 'agreement among raft nodes before linearized reading' (duration: 132.018285ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:33:23.571261Z","caller":"traceutil/trace.go:171","msg":"trace[318255528] linearizableReadLoop","detail":"{readStateIndex:14202; appliedIndex:14201; }","duration":"224.043888ms","start":"2026-08-26T05:33:23.347181Z","end":"2026-08-26T05:33:23.571224Z","steps":["trace[318255528] 'read index received' (duration: 203.360414ms)","trace[318255528] 'applied index is now lower than readState.Index' (duration: 20.682074ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:33:23.571608Z","caller":"traceutil/trace.go:171","msg":"trace[1970660532] transaction","detail":"{read_only:false; response_revision:13730; number_of_response:1; }","duration":"265.873126ms","start":"2026-08-26T05:33:23.30572Z","end":"2026-08-26T05:33:23.571593Z","steps":["trace[1970660532] 'process raft request' (duration: 244.778812ms)","trace[1970660532] 'compare' (duration: 20.206152ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:33:23.571963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.599424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T05:33:23.572053Z","caller":"traceutil/trace.go:171","msg":"trace[1792345904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13730; }","duration":"183.723578ms","start":"2026-08-26T05:33:23.38831Z","end":"2026-08-26T05:33:23.572034Z","steps":["trace[1792345904] 'agreement among raft nodes before linearized reading' (duration: 183.599145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:33:23.57206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.866229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.92\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T05:33:23.572129Z","caller":"traceutil/trace.go:171","msg":"trace[467722792] range","detail":"{range_begin:/registry/masterleases/162.253.55.92; range_end:; response_count:1; response_revision:13730; }","duration":"224.988312ms","start":"2026-08-26T05:33:23.347123Z","end":"2026-08-26T05:33:23.572111Z","steps":["trace[467722792] 'agreement among raft nodes before linearized reading' (duration: 224.780146ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:33:45.259073Z","caller":"traceutil/trace.go:171","msg":"trace[787959314] transaction","detail":"{read_only:false; response_revision:14038; number_of_response:1; }","duration":"267.678131ms","start":"2026-08-26T05:33:44.991371Z","end":"2026-08-26T05:33:45.259049Z","steps":["trace[787959314] 'process raft request' (duration: 267.542027ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:34:52.169563Z","caller":"traceutil/trace.go:171","msg":"trace[530244230] linearizableReadLoop","detail":"{readStateIndex:15253; appliedIndex:15252; }","duration":"123.283488ms","start":"2026-08-26T05:34:52.046253Z","end":"2026-08-26T05:34:52.169536Z","steps":["trace[530244230] 'read index received' (duration: 123.018701ms)","trace[530244230] 'applied index is now lower than readState.Index' (duration: 263.967µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:34:52.169671Z","caller":"traceutil/trace.go:171","msg":"trace[2002237281] transaction","detail":"{read_only:false; response_revision:14747; number_of_response:1; }","duration":"228.387996ms","start":"2026-08-26T05:34:51.941258Z","end":"2026-08-26T05:34:52.169646Z","steps":["trace[2002237281] 'process raft request' (duration: 228.106689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:34:52.169851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.583966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T05:34:52.169893Z","caller":"traceutil/trace.go:171","msg":"trace[1947551844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14747; }","duration":"123.685508ms","start":"2026-08-26T05:34:52.046198Z","end":"2026-08-26T05:34:52.169884Z","steps":["trace[1947551844] 'agreement among raft nodes before linearized reading' (duration: 123.520224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:34:52.169976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.546406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-08-26T05:34:52.170056Z","caller":"traceutil/trace.go:171","msg":"trace[1064960105] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:14747; }","duration":"115.658369ms","start":"2026-08-26T05:34:52.054379Z","end":"2026-08-26T05:34:52.170037Z","steps":["trace[1064960105] 'agreement among raft nodes before linearized reading' (duration: 115.461774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:34:52.170009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.602645ms","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-26T05:34:52.170211Z","caller":"traceutil/trace.go:171","msg":"trace[2081464527] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:14747; }","duration":"102.80982ms","start":"2026-08-26T05:34:52.06738Z","end":"2026-08-26T05:34:52.170189Z","steps":["trace[2081464527] 'agreement among raft nodes before linearized reading' (duration: 102.598145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:34:52.170332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.627281ms","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-26T05:34:52.170423Z","caller":"traceutil/trace.go:171","msg":"trace[1165835489] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14747; }","duration":"103.749183ms","start":"2026-08-26T05:34:52.066654Z","end":"2026-08-26T05:34:52.170403Z","steps":["trace[1165835489] 'agreement among raft nodes before linearized reading' (duration: 103.495977ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:36:30.109695Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12990} {"level":"info","ts":"2026-08-26T05:36:30.186252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12990,"took":"74.290474ms","hash":332995948,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-26T05:36:30.186307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":332995948,"revision":12990,"compact-revision":10796} {"level":"info","ts":"2026-08-26T05:40:31.207598Z","caller":"traceutil/trace.go:171","msg":"trace[355455674] transaction","detail":"{read_only:false; response_revision:17291; number_of_response:1; }","duration":"412.524678ms","start":"2026-08-26T05:40:30.795048Z","end":"2026-08-26T05:40:31.207573Z","steps":["trace[355455674] 'process raft request' (duration: 412.367044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:40:31.207802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:40:30.795021Z","time spent":"412.690032ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20874,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:40:31.407015Z","caller":"traceutil/trace.go:171","msg":"trace[413691726] transaction","detail":"{read_only:false; response_revision:17292; number_of_response:1; }","duration":"224.551029ms","start":"2026-08-26T05:40:31.182419Z","end":"2026-08-26T05:40:31.40697Z","steps":["trace[413691726] 'process raft request' (duration: 176.622663ms)","trace[413691726] 'compare' (duration: 47.767162ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:41:30.117083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15381} {"level":"info","ts":"2026-08-26T05:41:30.240586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15381,"took":"120.131474ms","hash":2800479640,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":33714176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T05:41:30.240738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2800479640,"revision":15381,"compact-revision":12990} {"level":"info","ts":"2026-08-26T05:44:30.661986Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39a4f14f6507253d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T05:44:30.667007Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-26T05:44:30.667117Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-26T05:46:07.138379Z","caller":"traceutil/trace.go:171","msg":"trace[1344682310] transaction","detail":"{read_only:false; response_revision:19912; number_of_response:1; }","duration":"138.887112ms","start":"2026-08-26T05:46:06.999468Z","end":"2026-08-26T05:46:07.138355Z","steps":["trace[1344682310] 'process raft request' (duration: 138.531192ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:07.145326Z","caller":"traceutil/trace.go:171","msg":"trace[930577558] transaction","detail":"{read_only:false; response_revision:19913; number_of_response:1; }","duration":"144.314132ms","start":"2026-08-26T05:46:07.000983Z","end":"2026-08-26T05:46:07.145297Z","steps":["trace[930577558] 'process raft request' (duration: 144.172098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:22.750155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.252312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-26T05:46:22.750245Z","caller":"traceutil/trace.go:171","msg":"trace[1677456056] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:19990; }","duration":"229.425887ms","start":"2026-08-26T05:46:22.520802Z","end":"2026-08-26T05:46:22.750228Z","steps":["trace[1677456056] 'count revisions from in-memory index tree' (duration: 229.16634ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:30.122902Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17957} {"level":"info","ts":"2026-08-26T05:46:30.256332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17957,"took":"129.22765ms","hash":1416138660,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":29356032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-26T05:46:30.256401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1416138660,"revision":17957,"compact-revision":15381} {"level":"info","ts":"2026-08-26T05:46:33.33227Z","caller":"traceutil/trace.go:171","msg":"trace[492554368] transaction","detail":"{read_only:false; response_revision:20046; number_of_response:1; }","duration":"107.937167ms","start":"2026-08-26T05:46:33.224309Z","end":"2026-08-26T05:46:33.332246Z","steps":["trace[492554368] 'process raft request' (duration: 107.781373ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:33.367151Z","caller":"traceutil/trace.go:171","msg":"trace[883633356] transaction","detail":"{read_only:false; response_revision:20047; number_of_response:1; }","duration":"127.128076ms","start":"2026-08-26T05:46:33.240003Z","end":"2026-08-26T05:46:33.367131Z","steps":["trace[883633356] 'process raft request' (duration: 127.010383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:33.841762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.07914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.92\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-26T05:46:33.841838Z","caller":"traceutil/trace.go:171","msg":"trace[1430635063] range","detail":"{range_begin:/registry/masterleases/162.253.55.92; range_end:; response_count:1; response_revision:20047; }","duration":"436.190802ms","start":"2026-08-26T05:46:33.405628Z","end":"2026-08-26T05:46:33.841819Z","steps":["trace[1430635063] 'range keys from in-memory index tree' (duration: 435.917685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:33.841891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.594399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-26T05:46:33.841919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:33.405613Z","time spent":"436.294245ms","remote":"127.0.0.1:34056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.92\" "} {"level":"info","ts":"2026-08-26T05:46:33.841941Z","caller":"traceutil/trace.go:171","msg":"trace[1852689230] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:20047; }","duration":"432.682411ms","start":"2026-08-26T05:46:33.409248Z","end":"2026-08-26T05:46:33.841931Z","steps":["trace[1852689230] 'count revisions from in-memory index tree' (duration: 432.540907ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:33.841964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:33.409237Z","time spent":"432.721833ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-08-26T05:46:33.84193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.774996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-26T05:46:33.842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.308253ms","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-26T05:46:33.842108Z","caller":"traceutil/trace.go:171","msg":"trace[47249226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20047; }","duration":"294.452516ms","start":"2026-08-26T05:46:33.547642Z","end":"2026-08-26T05:46:33.842094Z","steps":["trace[47249226] 'range keys from in-memory index tree' (duration: 294.115177ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:33.842112Z","caller":"traceutil/trace.go:171","msg":"trace[269925501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20047; }","duration":"454.978111ms","start":"2026-08-26T05:46:33.387111Z","end":"2026-08-26T05:46:33.842089Z","steps":["trace[269925501] 'range keys from in-memory index tree' (duration: 454.724884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:33.842166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:33.387097Z","time spent":"455.053863ms","remote":"127.0.0.1:34008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T05:46:33.861991Z","caller":"traceutil/trace.go:171","msg":"trace[1252344167] linearizableReadLoop","detail":"{readStateIndex:20732; appliedIndex:20731; }","duration":"156.813157ms","start":"2026-08-26T05:46:33.705139Z","end":"2026-08-26T05:46:33.861952Z","steps":["trace[1252344167] 'read index received' (duration: 156.634492ms)","trace[1252344167] 'applied index is now lower than readState.Index' (duration: 176.855µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:46:33.862116Z","caller":"traceutil/trace.go:171","msg":"trace[2012035999] transaction","detail":"{read_only:false; response_revision:20048; number_of_response:1; }","duration":"166.364895ms","start":"2026-08-26T05:46:33.695717Z","end":"2026-08-26T05:46:33.862082Z","steps":["trace[2012035999] 'process raft request' (duration: 166.090088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:33.862468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.321981ms","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-26T05:46:33.862521Z","caller":"traceutil/trace.go:171","msg":"trace[1060105603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20048; }","duration":"157.420193ms","start":"2026-08-26T05:46:33.705085Z","end":"2026-08-26T05:46:33.862506Z","steps":["trace[1060105603] 'agreement among raft nodes before linearized reading' (duration: 157.103245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:34.513678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.067888ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-26T05:46:34.514236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:33.843087Z","time spent":"671.139824ms","remote":"127.0.0.1:34056","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-26T05:46:34.514342Z","caller":"traceutil/trace.go:171","msg":"trace[8786409] transaction","detail":"{read_only:false; response_revision:20050; number_of_response:1; }","duration":"647.495649ms","start":"2026-08-26T05:46:33.866825Z","end":"2026-08-26T05:46:34.51432Z","steps":["trace[8786409] 'process raft request' (duration: 647.425757ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:34.514421Z","caller":"traceutil/trace.go:171","msg":"trace[1867890990] linearizableReadLoop","detail":"{readStateIndex:20734; appliedIndex:20732; }","duration":"652.223082ms","start":"2026-08-26T05:46:33.862166Z","end":"2026-08-26T05:46:34.514389Z","steps":["trace[1867890990] 'read index received' (duration: 222.376293ms)","trace[1867890990] 'applied index is now lower than readState.Index' (duration: 429.844209ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:46:34.514429Z","caller":"traceutil/trace.go:171","msg":"trace[1933044648] transaction","detail":"{read_only:false; response_revision:20049; number_of_response:1; }","duration":"668.760592ms","start":"2026-08-26T05:46:33.845627Z","end":"2026-08-26T05:46:34.514387Z","steps":["trace[1933044648] 'process raft request' (duration: 668.208188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:34.51447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:33.866812Z","time spent":"647.578111ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:46:34.514567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.233306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-26T05:46:34.514601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:33.845614Z","time spent":"668.886645ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:46:34.514629Z","caller":"traceutil/trace.go:171","msg":"trace[684402851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20050; }","duration":"671.311248ms","start":"2026-08-26T05:46:33.843299Z","end":"2026-08-26T05:46:34.51461Z","steps":["trace[684402851] 'agreement among raft nodes before linearized reading' (duration: 671.176275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:34.514661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:33.843288Z","time spent":"671.366039ms","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T05:46:34.514691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.289305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-08-26T05:46:34.514724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.412853ms","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-26T05:46:34.514705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.644272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-26T05:46:34.514725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.384523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-26T05:46:34.514755Z","caller":"traceutil/trace.go:171","msg":"trace[1618194007] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20050; }","duration":"534.393077ms","start":"2026-08-26T05:46:33.980342Z","end":"2026-08-26T05:46:34.514735Z","steps":["trace[1618194007] 'agreement among raft nodes before linearized reading' (duration: 534.207353ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:34.514765Z","caller":"traceutil/trace.go:171","msg":"trace[347893062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20050; }","duration":"132.508796ms","start":"2026-08-26T05:46:34.382244Z","end":"2026-08-26T05:46:34.514753Z","steps":["trace[347893062] 'agreement among raft nodes before linearized reading' (duration: 132.314061ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:34.514794Z","caller":"traceutil/trace.go:171","msg":"trace[1933791916] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:20050; }","duration":"397.790515ms","start":"2026-08-26T05:46:34.116977Z","end":"2026-08-26T05:46:34.514767Z","steps":["trace[1933791916] 'agreement among raft nodes before linearized reading' (duration: 397.59219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:34.51481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:33.980303Z","time spent":"534.49457ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-08-26T05:46:34.514811Z","caller":"traceutil/trace.go:171","msg":"trace[1291006485] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20050; }","duration":"132.531877ms","start":"2026-08-26T05:46:34.382259Z","end":"2026-08-26T05:46:34.514791Z","steps":["trace[1291006485] 'agreement among raft nodes before linearized reading' (duration: 132.329672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:34.514844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.116953Z","time spent":"397.880337ms","remote":"127.0.0.1:34518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":35,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-26T05:46:34.514938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.003709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-26T05:46:34.515001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.0941ms","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-26T05:46:34.515041Z","caller":"traceutil/trace.go:171","msg":"trace[1239457934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20050; }","duration":"256.157102ms","start":"2026-08-26T05:46:34.258872Z","end":"2026-08-26T05:46:34.515029Z","steps":["trace[1239457934] 'agreement among raft nodes before linearized reading' (duration: 255.846594ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:34.515064Z","caller":"traceutil/trace.go:171","msg":"trace[475204699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20050; }","duration":"461.145022ms","start":"2026-08-26T05:46:34.053862Z","end":"2026-08-26T05:46:34.515007Z","steps":["trace[475204699] 'agreement among raft nodes before linearized reading' (duration: 460.820174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:34.515123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.053844Z","time spent":"461.266376ms","remote":"127.0.0.1:34294","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-26T05:46:35.001783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.22673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-26T05:46:35.001889Z","caller":"traceutil/trace.go:171","msg":"trace[467324072] linearizableReadLoop","detail":"{readStateIndex:20736; appliedIndex:20735; }","duration":"482.919269ms","start":"2026-08-26T05:46:34.518953Z","end":"2026-08-26T05:46:35.001872Z","steps":["trace[467324072] 'read index received' (duration: 280.462594ms)","trace[467324072] 'applied index is now lower than readState.Index' (duration: 202.454575ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:46:35.001991Z","caller":"traceutil/trace.go:171","msg":"trace[747257437] transaction","detail":"{read_only:false; response_revision:20051; number_of_response:1; }","duration":"485.075815ms","start":"2026-08-26T05:46:34.51687Z","end":"2026-08-26T05:46:35.001946Z","steps":["trace[747257437] 'process raft request' (duration: 282.579609ms)","trace[747257437] 'compare' (duration: 201.938902ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T05:46:35.002058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.516852Z","time spent":"485.176008ms","remote":"127.0.0.1:34056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:46:35.002584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.581146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-26T05:46:35.002711Z","caller":"traceutil/trace.go:171","msg":"trace[2078312944] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20051; }","duration":"483.780741ms","start":"2026-08-26T05:46:34.518905Z","end":"2026-08-26T05:46:35.002686Z","steps":["trace[2078312944] 'agreement among raft nodes before linearized reading' (duration: 483.240787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.002762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.518884Z","time spent":"483.870564ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-26T05:46:35.06583Z","caller":"traceutil/trace.go:171","msg":"trace[1453504732] transaction","detail":"{read_only:false; response_revision:20052; number_of_response:1; }","duration":"546.580224ms","start":"2026-08-26T05:46:34.519209Z","end":"2026-08-26T05:46:35.06579Z","steps":["trace[1453504732] 'process raft request' (duration: 546.265406ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:35.065859Z","caller":"traceutil/trace.go:171","msg":"trace[1558581516] transaction","detail":"{read_only:false; response_revision:20053; number_of_response:1; }","duration":"546.424321ms","start":"2026-08-26T05:46:34.519389Z","end":"2026-08-26T05:46:35.065813Z","steps":["trace[1558581516] 'process raft request' (duration: 546.312998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.066036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.519195Z","time spent":"546.729319ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:46:35.066101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.519372Z","time spent":"546.596865ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:46:35.066306Z","caller":"traceutil/trace.go:171","msg":"trace[690653912] transaction","detail":"{read_only:false; response_revision:20054; number_of_response:1; }","duration":"546.80471ms","start":"2026-08-26T05:46:34.519447Z","end":"2026-08-26T05:46:35.066251Z","steps":["trace[690653912] 'process raft request' (duration: 546.313427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.066482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.519442Z","time spent":"546.949574ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:46:35.067016Z","caller":"traceutil/trace.go:171","msg":"trace[884358286] transaction","detail":"{read_only:false; response_revision:20056; number_of_response:1; }","duration":"547.479027ms","start":"2026-08-26T05:46:34.519515Z","end":"2026-08-26T05:46:35.066994Z","steps":["trace[884358286] 'process raft request' (duration: 547.387335ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:46:35.067108Z","caller":"traceutil/trace.go:171","msg":"trace[116390139] transaction","detail":"{read_only:false; response_revision:20055; number_of_response:1; }","duration":"547.578ms","start":"2026-08-26T05:46:34.519493Z","end":"2026-08-26T05:46:35.067071Z","steps":["trace[116390139] 'process raft request' (duration: 546.653526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.067214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.519496Z","time spent":"547.618092ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T05:46:35.067256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.519487Z","time spent":"547.693463ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:46:35.518895Z","caller":"traceutil/trace.go:171","msg":"trace[1991342046] linearizableReadLoop","detail":"{readStateIndex:20742; appliedIndex:20736; }","duration":"516.952444ms","start":"2026-08-26T05:46:35.001912Z","end":"2026-08-26T05:46:35.518865Z","steps":["trace[1991342046] 'read index received' (duration: 63.610114ms)","trace[1991342046] 'applied index is now lower than readState.Index' (duration: 453.34109ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:46:35.519039Z","caller":"traceutil/trace.go:171","msg":"trace[261019633] transaction","detail":"{read_only:false; response_revision:20057; number_of_response:1; }","duration":"694.135542ms","start":"2026-08-26T05:46:34.824887Z","end":"2026-08-26T05:46:35.519023Z","steps":["trace[261019633] 'process raft request' (duration: 693.435194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.519098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.255893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-26T05:46:35.519157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.824863Z","time spent":"694.211545ms","remote":"127.0.0.1:34294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T05:46:35.519151Z","caller":"traceutil/trace.go:171","msg":"trace[1904876848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20057; }","duration":"990.341305ms","start":"2026-08-26T05:46:34.528797Z","end":"2026-08-26T05:46:35.519139Z","steps":["trace[1904876848] 'agreement among raft nodes before linearized reading' (duration: 990.248233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.519358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:34.528784Z","time spent":"990.562721ms","remote":"127.0.0.1:34008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T05:46:35.603533Z","caller":"traceutil/trace.go:171","msg":"trace[1665041625] transaction","detail":"{read_only:false; response_revision:20059; number_of_response:1; }","duration":"231.277415ms","start":"2026-08-26T05:46:35.372218Z","end":"2026-08-26T05:46:35.603496Z","steps":["trace[1665041625] 'process raft request' (duration: 231.199653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.603613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.651111ms","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-26T05:46:35.603866Z","caller":"traceutil/trace.go:171","msg":"trace[1543507502] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20059; }","duration":"302.764394ms","start":"2026-08-26T05:46:35.300887Z","end":"2026-08-26T05:46:35.603651Z","steps":["trace[1543507502] 'agreement among raft nodes before linearized reading' (duration: 302.61225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.603922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:35.300866Z","time spent":"303.041701ms","remote":"127.0.0.1:57780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-26T05:46:35.603935Z","caller":"traceutil/trace.go:171","msg":"trace[201368885] transaction","detail":"{read_only:false; response_revision:20058; number_of_response:1; }","duration":"265.650569ms","start":"2026-08-26T05:46:35.338273Z","end":"2026-08-26T05:46:35.603923Z","steps":["trace[201368885] 'process raft request' (duration: 265.025153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.604194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.49909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-26T05:46:35.604289Z","caller":"traceutil/trace.go:171","msg":"trace[674869359] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:20059; }","duration":"599.694696ms","start":"2026-08-26T05:46:35.00457Z","end":"2026-08-26T05:46:35.604265Z","steps":["trace[674869359] 'agreement among raft nodes before linearized reading' (duration: 599.392088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.60434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:35.004551Z","time spent":"599.780048ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-26T05:46:35.604319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.950392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18260"} {"level":"info","ts":"2026-08-26T05:46:35.604568Z","caller":"traceutil/trace.go:171","msg":"trace[1497201983] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20059; }","duration":"600.24247ms","start":"2026-08-26T05:46:35.004298Z","end":"2026-08-26T05:46:35.60454Z","steps":["trace[1497201983] 'agreement among raft nodes before linearized reading' (duration: 599.640504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:46:35.604758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T05:46:35.004279Z","time spent":"600.464565ms","remote":"127.0.0.1:34188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18282,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-26T05:50:03.834854Z","caller":"traceutil/trace.go:171","msg":"trace[515333595] linearizableReadLoop","detail":"{readStateIndex:23013; appliedIndex:23012; }","duration":"120.0911ms","start":"2026-08-26T05:50:03.714741Z","end":"2026-08-26T05:50:03.834832Z","steps":["trace[515333595] 'read index received' (duration: 119.906695ms)","trace[515333595] 'applied index is now lower than readState.Index' (duration: 183.635µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T05:50:03.834956Z","caller":"traceutil/trace.go:171","msg":"trace[37218216] transaction","detail":"{read_only:false; response_revision:22259; number_of_response:1; }","duration":"124.740871ms","start":"2026-08-26T05:50:03.710178Z","end":"2026-08-26T05:50:03.834919Z","steps":["trace[37218216] 'process raft request' (duration: 124.533515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:50:03.835059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.307456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/cert-manager-test\" ","response":"range_response_count:1 size:385"} {"level":"info","ts":"2026-08-26T05:50:03.835084Z","caller":"traceutil/trace.go:171","msg":"trace[1855439027] range","detail":"{range_begin:/registry/namespaces/cert-manager-test; range_end:; response_count:1; response_revision:22259; }","duration":"120.383448ms","start":"2026-08-26T05:50:03.714693Z","end":"2026-08-26T05:50:03.835077Z","steps":["trace[1855439027] 'agreement among raft nodes before linearized reading' (duration: 120.241434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:50:03.986714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.854226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machinedrainrules.cluster.x-k8s.io\" ","response":"range_response_count:1 size:17860"} {"level":"info","ts":"2026-08-26T05:50:03.986824Z","caller":"traceutil/trace.go:171","msg":"trace[1486800600] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machinedrainrules.cluster.x-k8s.io; range_end:; response_count:1; response_revision:22259; }","duration":"126.984529ms","start":"2026-08-26T05:50:03.859812Z","end":"2026-08-26T05:50:03.986797Z","steps":["trace[1486800600] 'range keys from in-memory index tree' (duration: 126.747304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T05:50:03.986726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.002691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/cert-manager-test/\" range_end:\"/registry/ceph.rook.io/cephclusters/cert-manager-test0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-26T05:50:03.986884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.917587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machinesets.cluster.x-k8s.io\" ","response":"range_response_count:1 size:60938"} {"level":"info","ts":"2026-08-26T05:50:03.986922Z","caller":"traceutil/trace.go:171","msg":"trace[1392872389] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machinesets.cluster.x-k8s.io; range_end:; response_count:1; response_revision:22259; }","duration":"145.066731ms","start":"2026-08-26T05:50:03.841845Z","end":"2026-08-26T05:50:03.986911Z","steps":["trace[1392872389] 'range keys from in-memory index tree' (duration: 144.732892ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:50:03.986948Z","caller":"traceutil/trace.go:171","msg":"trace[669041041] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/cert-manager-test/; range_end:/registry/ceph.rook.io/cephclusters/cert-manager-test0; response_count:0; response_revision:22259; }","duration":"137.251647ms","start":"2026-08-26T05:50:03.849656Z","end":"2026-08-26T05:50:03.986908Z","steps":["trace[669041041] 'range keys from in-memory index tree' (duration: 136.921949ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T05:51:30.129525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20030} {"level":"info","ts":"2026-08-26T05:51:30.244868Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20030,"took":"109.800632ms","hash":618321709,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":42663936,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-26T05:51:30.244981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":618321709,"revision":20030,"compact-revision":17957} {"level":"info","ts":"2026-08-26T05:56:30.138516Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23273} {"level":"info","ts":"2026-08-26T05:56:30.284941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23273,"took":"143.404387ms","hash":2364546959,"current-db-size-bytes":56758272,"current-db-size":"57 MB","current-db-size-in-use-bytes":31133696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-26T05:56:30.285005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2364546959,"revision":23273,"compact-revision":20030}