{"level":"warn","ts":"2026-08-20T16:57:09.56617Z","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-20T16:57:09.566255Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186: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.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186: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-20T16:57:09.566314Z","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-20T16:57:09.566327Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-08-20T16:57:09.566343Z","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-20T16:57:09.566952Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-08-20T16:57:09.567022Z","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.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186: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-20T16:57:09.569373Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.148891ms"} {"level":"info","ts":"2026-08-20T16:57:09.57806Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-08-20T16:57:09.578247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T16:57:09.578302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-08-20T16:57:09.578331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T16:57:09.578355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-08-20T16:57:09.578428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-08-20T16:57:09.5831Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T16:57:09.589411Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T16:57:09.59249Z","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-20T16:57:09.595047Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T16:57:09.595252Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T16:57:09.595528Z","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-20T16:57:09.595632Z","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-20T16:57:09.595645Z","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-20T16:57:09.596347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-08-20T16:57:09.596553Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-08-20T16:57:09.598218Z","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-20T16:57:09.598498Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T16:57:09.598537Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T16:57:09.598698Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-08-20T16:57:09.598716Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-08-20T16:57:09.678943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T16:57:09.678985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T16:57:09.679014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-08-20T16:57:09.679033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-08-20T16:57:09.679045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-08-20T16:57:09.679066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-08-20T16:57:09.679084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-08-20T16:57:09.779094Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T16:57:09.7826Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T16:57:09.782852Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T16:57:09.782957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T16:57:09.783213Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T16:57:09.783375Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T16:57:09.784518Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T16:57:09.784704Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T16:57:09.784751Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T16:57:09.786319Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-08-20T16:57:09.786808Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T16:57:54.477441Z","caller":"traceutil/trace.go:171","msg":"trace[2094847009] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"104.896734ms","start":"2026-08-20T16:57:54.372513Z","end":"2026-08-20T16:57:54.47741Z","steps":["trace[2094847009] 'process raft request' (duration: 104.324838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T16:57:54.819696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.567678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T16:57:54.819936Z","caller":"traceutil/trace.go:171","msg":"trace[1548930095] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"263.716432ms","start":"2026-08-20T16:57:54.556204Z","end":"2026-08-20T16:57:54.81992Z","steps":["trace[1548930095] 'process raft request' (duration: 263.630719ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T16:57:54.820108Z","caller":"traceutil/trace.go:171","msg":"trace[371703409] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"280.926866ms","start":"2026-08-20T16:57:54.539152Z","end":"2026-08-20T16:57:54.820079Z","steps":["trace[371703409] 'read index received' (duration: 32.304457ms)","trace[371703409] 'applied index is now lower than readState.Index' (duration: 248.618609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T16:57:54.820475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.293686ms","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-20T16:57:54.820627Z","caller":"traceutil/trace.go:171","msg":"trace[872722175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"281.477741ms","start":"2026-08-20T16:57:54.539138Z","end":"2026-08-20T16:57:54.820616Z","steps":["trace[872722175] 'agreement among raft nodes before linearized reading' (duration: 281.07494ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T16:57:54.81988Z","caller":"traceutil/trace.go:171","msg":"trace[1327355260] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"282.248711ms","start":"2026-08-20T16:57:54.53758Z","end":"2026-08-20T16:57:54.819829Z","steps":["trace[1327355260] 'process raft request' (duration: 33.709686ms)","trace[1327355260] 'compare' (duration: 247.26742ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T16:57:55.004462Z","caller":"traceutil/trace.go:171","msg":"trace[463434249] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:666; }","duration":"173.249702ms","start":"2026-08-20T16:57:54.831186Z","end":"2026-08-20T16:57:55.004436Z","steps":["trace[463434249] 'read index received' (duration: 27.574253ms)","trace[463434249] 'applied index is now lower than readState.Index' (duration: 145.674339ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T16:57:55.004566Z","caller":"traceutil/trace.go:171","msg":"trace[636216850] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"178.325695ms","start":"2026-08-20T16:57:54.826182Z","end":"2026-08-20T16:57:55.004508Z","steps":["trace[636216850] 'process raft request' (duration: 93.179586ms)","trace[636216850] 'compare' (duration: 84.931623ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T16:57:55.004662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.444507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T16:57:55.004755Z","caller":"traceutil/trace.go:171","msg":"trace[1341432107] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:646; }","duration":"173.573802ms","start":"2026-08-20T16:57:54.831157Z","end":"2026-08-20T16:57:55.004731Z","steps":["trace[1341432107] 'agreement among raft nodes before linearized reading' (duration: 173.404767ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T16:59:31.65009Z","caller":"traceutil/trace.go:171","msg":"trace[601214276] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"118.964646ms","start":"2026-08-20T16:59:31.531094Z","end":"2026-08-20T16:59:31.650058Z","steps":["trace[601214276] 'read index received' (duration: 118.632305ms)","trace[601214276] 'applied index is now lower than readState.Index' (duration: 330.69µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T16:59:31.650148Z","caller":"traceutil/trace.go:171","msg":"trace[1350970772] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"148.176145ms","start":"2026-08-20T16:59:31.501923Z","end":"2026-08-20T16:59:31.6501Z","steps":["trace[1350970772] 'process raft request' (duration: 147.947117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T16:59:31.650402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.917004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T16:59:31.650453Z","caller":"traceutil/trace.go:171","msg":"trace[139004355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1911; }","duration":"119.067448ms","start":"2026-08-20T16:59:31.531372Z","end":"2026-08-20T16:59:31.65044Z","steps":["trace[139004355] 'agreement among raft nodes before linearized reading' (duration: 118.882413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T16:59:31.6505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.375727ms","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-20T16:59:31.650661Z","caller":"traceutil/trace.go:171","msg":"trace[1746001603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1911; }","duration":"119.604873ms","start":"2026-08-20T16:59:31.53103Z","end":"2026-08-20T16:59:31.650634Z","steps":["trace[1746001603] 'agreement among raft nodes before linearized reading' (duration: 119.181702ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T16:59:44.324707Z","caller":"traceutil/trace.go:171","msg":"trace[666316427] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"141.751474ms","start":"2026-08-20T16:59:44.182929Z","end":"2026-08-20T16:59:44.32468Z","steps":["trace[666316427] 'process raft request' (duration: 141.709653ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T16:59:44.324774Z","caller":"traceutil/trace.go:171","msg":"trace[1496428026] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"144.316277ms","start":"2026-08-20T16:59:44.180423Z","end":"2026-08-20T16:59:44.324739Z","steps":["trace[1496428026] 'process raft request' (duration: 143.952257ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T16:59:44.324844Z","caller":"traceutil/trace.go:171","msg":"trace[74221297] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"144.334496ms","start":"2026-08-20T16:59:44.180463Z","end":"2026-08-20T16:59:44.324797Z","steps":["trace[74221297] 'process raft request' (duration: 144.082109ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T16:59:47.423269Z","caller":"traceutil/trace.go:171","msg":"trace[1602559821] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"153.659638ms","start":"2026-08-20T16:59:47.269557Z","end":"2026-08-20T16:59:47.423216Z","steps":["trace[1602559821] 'process raft request' (duration: 153.350199ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:00:20.492244Z","caller":"traceutil/trace.go:171","msg":"trace[2049583359] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"129.923513ms","start":"2026-08-20T17:00:20.362286Z","end":"2026-08-20T17:00:20.492209Z","steps":["trace[2049583359] 'process raft request' (duration: 129.674335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:00:26.823428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.633054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T17:00:26.823666Z","caller":"traceutil/trace.go:171","msg":"trace[1846738864] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"300.404819ms","start":"2026-08-20T17:00:26.523243Z","end":"2026-08-20T17:00:26.823648Z","steps":["trace[1846738864] 'process raft request' (duration: 300.286446ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:00:26.823691Z","caller":"traceutil/trace.go:171","msg":"trace[1659159723] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"305.356436ms","start":"2026-08-20T17:00:26.518294Z","end":"2026-08-20T17:00:26.82365Z","steps":["trace[1659159723] 'process raft request' (duration: 153.409003ms)","trace[1659159723] 'compare' (duration: 151.441238ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:00:26.823739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T17:00:26.523218Z","time spent":"300.484271ms","remote":"127.0.0.1:38610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T17:00:26.823801Z","caller":"traceutil/trace.go:171","msg":"trace[1926056411] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"300.516152ms","start":"2026-08-20T17:00:26.523266Z","end":"2026-08-20T17:00:26.823782Z","steps":["trace[1926056411] 'process raft request' (duration: 300.316286ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:00:26.823806Z","caller":"traceutil/trace.go:171","msg":"trace[919994090] linearizableReadLoop","detail":"{readStateIndex:2344; appliedIndex:2341; }","duration":"110.369455ms","start":"2026-08-20T17:00:26.71339Z","end":"2026-08-20T17:00:26.823759Z","steps":["trace[919994090] 'read index received' (duration: 49.322µs)","trace[919994090] 'applied index is now lower than readState.Index' (duration: 110.318633ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:00:26.823896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T17:00:26.523257Z","time spent":"300.591784ms","remote":"127.0.0.1:38610","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-20T17:00:26.823894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T17:00:26.51827Z","time spent":"305.519351ms","remote":"127.0.0.1:38610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T17:00:26.824072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.674243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T17:00:26.824126Z","caller":"traceutil/trace.go:171","msg":"trace[1113275787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2262; }","duration":"110.761496ms","start":"2026-08-20T17:00:26.713348Z","end":"2026-08-20T17:00:26.82411Z","steps":["trace[1113275787] 'agreement among raft nodes before linearized reading' (duration: 110.524289ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:01:02.910396Z","caller":"traceutil/trace.go:171","msg":"trace[2146560807] linearizableReadLoop","detail":"{readStateIndex:2728; appliedIndex:2727; }","duration":"119.420415ms","start":"2026-08-20T17:01:02.790934Z","end":"2026-08-20T17:01:02.910354Z","steps":["trace[2146560807] 'read index received' (duration: 118.964603ms)","trace[2146560807] 'applied index is now lower than readState.Index' (duration: 454.322µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:01:02.91065Z","caller":"traceutil/trace.go:171","msg":"trace[841973294] transaction","detail":"{read_only:false; response_revision:2631; number_of_response:1; }","duration":"188.754397ms","start":"2026-08-20T17:01:02.721876Z","end":"2026-08-20T17:01:02.91063Z","steps":["trace[841973294] 'process raft request' (duration: 188.081589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:01:02.911058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.073344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T17:01:02.91118Z","caller":"traceutil/trace.go:171","msg":"trace[1986588149] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2631; }","duration":"120.28149ms","start":"2026-08-20T17:01:02.790871Z","end":"2026-08-20T17:01:02.911153Z","steps":["trace[1986588149] 'agreement among raft nodes before linearized reading' (duration: 120.062774ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:01:36.490889Z","caller":"traceutil/trace.go:171","msg":"trace[1614813058] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"120.63579ms","start":"2026-08-20T17:01:36.370217Z","end":"2026-08-20T17:01:36.490853Z","steps":["trace[1614813058] 'process raft request' (duration: 120.445235ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:01:36.521774Z","caller":"traceutil/trace.go:171","msg":"trace[615122331] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"119.980171ms","start":"2026-08-20T17:01:36.401746Z","end":"2026-08-20T17:01:36.521726Z","steps":["trace[615122331] 'process raft request' (duration: 119.408185ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:05:52.154535Z","caller":"traceutil/trace.go:171","msg":"trace[1538340975] linearizableReadLoop","detail":"{readStateIndex:5287; appliedIndex:5286; }","duration":"150.151002ms","start":"2026-08-20T17:05:52.004363Z","end":"2026-08-20T17:05:52.154514Z","steps":["trace[1538340975] 'read index received' (duration: 108.933715ms)","trace[1538340975] 'applied index is now lower than readState.Index' (duration: 41.216257ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:05:52.154577Z","caller":"traceutil/trace.go:171","msg":"trace[870387483] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"185.03126ms","start":"2026-08-20T17:05:51.969487Z","end":"2026-08-20T17:05:52.154518Z","steps":["trace[870387483] 'process raft request' (duration: 143.876265ms)","trace[870387483] 'compare' (duration: 40.98783ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:05:52.154996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.601214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-20T17:05:52.155109Z","caller":"traceutil/trace.go:171","msg":"trace[112317625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5097; }","duration":"150.778888ms","start":"2026-08-20T17:05:52.004306Z","end":"2026-08-20T17:05:52.155085Z","steps":["trace[112317625] 'agreement among raft nodes before linearized reading' (duration: 150.292465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:05:52.180102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.31913ms","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-20T17:05:52.180211Z","caller":"traceutil/trace.go:171","msg":"trace[2102041355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5099; }","duration":"163.444223ms","start":"2026-08-20T17:05:52.016744Z","end":"2026-08-20T17:05:52.180188Z","steps":["trace[2102041355] 'agreement among raft nodes before linearized reading' (duration: 163.156275ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:06.39444Z","caller":"traceutil/trace.go:171","msg":"trace[1313877220] transaction","detail":"{read_only:false; response_revision:5371; number_of_response:1; }","duration":"105.170751ms","start":"2026-08-20T17:06:06.289221Z","end":"2026-08-20T17:06:06.394392Z","steps":["trace[1313877220] 'process raft request' (duration: 49.55329ms)","trace[1313877220] 'compare' (duration: 55.288982ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:06:06.39471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.240292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T17:06:06.394779Z","caller":"traceutil/trace.go:171","msg":"trace[1117066345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5372; }","duration":"136.383146ms","start":"2026-08-20T17:06:06.25838Z","end":"2026-08-20T17:06:06.394763Z","steps":["trace[1117066345] 'agreement among raft nodes before linearized reading' (duration: 136.110709ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:06.640542Z","caller":"traceutil/trace.go:171","msg":"trace[1636242245] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"154.019768ms","start":"2026-08-20T17:06:06.486483Z","end":"2026-08-20T17:06:06.640502Z","steps":["trace[1636242245] 'process raft request' (duration: 153.71142ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:06.775491Z","caller":"traceutil/trace.go:171","msg":"trace[1238242918] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"287.488952ms","start":"2026-08-20T17:06:06.487946Z","end":"2026-08-20T17:06:06.775435Z","steps":["trace[1238242918] 'process raft request' (duration: 228.22455ms)","trace[1238242918] 'compare' (duration: 58.80475ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:06:06.775748Z","caller":"traceutil/trace.go:171","msg":"trace[404730189] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"282.645536ms","start":"2026-08-20T17:06:06.493075Z","end":"2026-08-20T17:06:06.775721Z","steps":["trace[404730189] 'process raft request' (duration: 282.297417ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:06.775721Z","caller":"traceutil/trace.go:171","msg":"trace[426106363] linearizableReadLoop","detail":"{readStateIndex:5574; appliedIndex:5572; }","duration":"287.275616ms","start":"2026-08-20T17:06:06.488407Z","end":"2026-08-20T17:06:06.775682Z","steps":["trace[426106363] 'read index received' (duration: 151.867399ms)","trace[426106363] 'applied index is now lower than readState.Index' (duration: 135.404997ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:06:06.776066Z","caller":"traceutil/trace.go:171","msg":"trace[1127695898] transaction","detail":"{read_only:false; response_revision:5377; number_of_response:1; }","duration":"133.0114ms","start":"2026-08-20T17:06:06.642972Z","end":"2026-08-20T17:06:06.775983Z","steps":["trace[1127695898] 'process raft request' (duration: 132.528226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:06:06.777121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.667247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:71039"} {"level":"info","ts":"2026-08-20T17:06:06.777247Z","caller":"traceutil/trace.go:171","msg":"trace[20098641] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5377; }","duration":"288.85203ms","start":"2026-08-20T17:06:06.488368Z","end":"2026-08-20T17:06:06.77722Z","steps":["trace[20098641] 'agreement among raft nodes before linearized reading' (duration: 287.457541ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:06.950215Z","caller":"traceutil/trace.go:171","msg":"trace[62796011] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"168.050983ms","start":"2026-08-20T17:06:06.782133Z","end":"2026-08-20T17:06:06.950184Z","steps":["trace[62796011] 'process raft request' (duration: 99.205963ms)","trace[62796011] 'compare' (duration: 68.577542ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:06:06.950241Z","caller":"traceutil/trace.go:171","msg":"trace[94909810] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"124.335157ms","start":"2026-08-20T17:06:06.825893Z","end":"2026-08-20T17:06:06.950228Z","steps":["trace[94909810] 'process raft request' (duration: 124.302686ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:06.950407Z","caller":"traceutil/trace.go:171","msg":"trace[1447918720] linearizableReadLoop","detail":"{readStateIndex:5580; appliedIndex:5576; }","duration":"159.142313ms","start":"2026-08-20T17:06:06.791257Z","end":"2026-08-20T17:06:06.950399Z","steps":["trace[1447918720] 'read index received' (duration: 28.743956ms)","trace[1447918720] 'applied index is now lower than readState.Index' (duration: 130.396987ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:06:06.950396Z","caller":"traceutil/trace.go:171","msg":"trace[1102406632] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"167.455275ms","start":"2026-08-20T17:06:06.782886Z","end":"2026-08-20T17:06:06.950341Z","steps":["trace[1102406632] 'process raft request' (duration: 167.208698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:06:06.950543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.263906ms","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-20T17:06:06.950567Z","caller":"traceutil/trace.go:171","msg":"trace[561178121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5382; }","duration":"159.363179ms","start":"2026-08-20T17:06:06.791196Z","end":"2026-08-20T17:06:06.950559Z","steps":["trace[561178121] 'agreement among raft nodes before linearized reading' (duration: 159.229436ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:06.950529Z","caller":"traceutil/trace.go:171","msg":"trace[771056435] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"167.530908ms","start":"2026-08-20T17:06:06.782935Z","end":"2026-08-20T17:06:06.950466Z","steps":["trace[771056435] 'process raft request' (duration: 167.224349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:06:16.403206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.432495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2101"} {"level":"info","ts":"2026-08-20T17:06:16.403252Z","caller":"traceutil/trace.go:171","msg":"trace[1681616928] transaction","detail":"{read_only:false; response_revision:5582; number_of_response:1; }","duration":"112.676631ms","start":"2026-08-20T17:06:16.290534Z","end":"2026-08-20T17:06:16.403211Z","steps":["trace[1681616928] 'process raft request' (duration: 65.447776ms)","trace[1681616928] 'compare' (duration: 46.952557ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:06:16.403318Z","caller":"traceutil/trace.go:171","msg":"trace[730525997] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5581; }","duration":"142.63497ms","start":"2026-08-20T17:06:16.260664Z","end":"2026-08-20T17:06:16.403299Z","steps":["trace[730525997] 'range keys from in-memory index tree' (duration: 142.198398ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:16.690543Z","caller":"traceutil/trace.go:171","msg":"trace[708127375] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"179.114823ms","start":"2026-08-20T17:06:16.511395Z","end":"2026-08-20T17:06:16.69051Z","steps":["trace[708127375] 'process raft request' (duration: 104.13046ms)","trace[708127375] 'compare' (duration: 74.611913ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:06:16.690534Z","caller":"traceutil/trace.go:171","msg":"trace[1111890267] linearizableReadLoop","detail":"{readStateIndex:5789; appliedIndex:5788; }","duration":"166.758716ms","start":"2026-08-20T17:06:16.523715Z","end":"2026-08-20T17:06:16.690474Z","steps":["trace[1111890267] 'read index received' (duration: 91.686901ms)","trace[1111890267] 'applied index is now lower than readState.Index' (duration: 75.069295ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:06:16.690854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.122577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-20T17:06:16.690938Z","caller":"traceutil/trace.go:171","msg":"trace[209433631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5585; }","duration":"167.311001ms","start":"2026-08-20T17:06:16.523608Z","end":"2026-08-20T17:06:16.690919Z","steps":["trace[209433631] 'agreement among raft nodes before linearized reading' (duration: 167.057344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:06:16.691149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.751607ms","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-20T17:06:16.69121Z","caller":"traceutil/trace.go:171","msg":"trace[516153170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5585; }","duration":"160.861591ms","start":"2026-08-20T17:06:16.530335Z","end":"2026-08-20T17:06:16.691196Z","steps":["trace[516153170] 'agreement among raft nodes before linearized reading' (duration: 160.588794ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:16.907155Z","caller":"traceutil/trace.go:171","msg":"trace[87437623] linearizableReadLoop","detail":"{readStateIndex:5792; appliedIndex:5789; }","duration":"201.840601ms","start":"2026-08-20T17:06:16.705267Z","end":"2026-08-20T17:06:16.907108Z","steps":["trace[87437623] 'read index received' (duration: 46.967377ms)","trace[87437623] 'applied index is now lower than readState.Index' (duration: 154.870794ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:06:16.907412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.107339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-93277bed-b041-4a5e-994e-0f045ca19502\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-08-20T17:06:16.907465Z","caller":"traceutil/trace.go:171","msg":"trace[1100492686] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-93277bed-b041-4a5e-994e-0f045ca19502; range_end:; response_count:1; response_revision:5588; }","duration":"202.229393ms","start":"2026-08-20T17:06:16.705219Z","end":"2026-08-20T17:06:16.907448Z","steps":["trace[1100492686] 'agreement among raft nodes before linearized reading' (duration: 202.098979ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:16.907426Z","caller":"traceutil/trace.go:171","msg":"trace[1236838132] transaction","detail":"{read_only:false; response_revision:5587; number_of_response:1; }","duration":"210.949716ms","start":"2026-08-20T17:06:16.696298Z","end":"2026-08-20T17:06:16.907248Z","steps":["trace[1236838132] 'process raft request' (duration: 210.457592ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:16.907672Z","caller":"traceutil/trace.go:171","msg":"trace[1922280255] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"208.071386ms","start":"2026-08-20T17:06:16.699243Z","end":"2026-08-20T17:06:16.907315Z","steps":["trace[1922280255] 'process raft request' (duration: 207.761926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:06:17.225415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.187922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-20T17:06:17.225502Z","caller":"traceutil/trace.go:171","msg":"trace[140724398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5588; }","duration":"196.312575ms","start":"2026-08-20T17:06:17.029169Z","end":"2026-08-20T17:06:17.225482Z","steps":["trace[140724398] 'range keys from in-memory index tree' (duration: 195.987856ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:18.840595Z","caller":"traceutil/trace.go:171","msg":"trace[2130873944] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"137.813735ms","start":"2026-08-20T17:06:18.702735Z","end":"2026-08-20T17:06:18.840549Z","steps":["trace[2130873944] 'process raft request' (duration: 130.253713ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:06:18.840723Z","caller":"traceutil/trace.go:171","msg":"trace[709459267] linearizableReadLoop","detail":"{readStateIndex:5814; appliedIndex:5813; }","duration":"104.845411ms","start":"2026-08-20T17:06:18.735796Z","end":"2026-08-20T17:06:18.840642Z","steps":["trace[709459267] 'read index received' (duration: 97.161416ms)","trace[709459267] 'applied index is now lower than readState.Index' (duration: 7.676275ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:06:18.856077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.261413ms","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-20T17:06:18.856205Z","caller":"traceutil/trace.go:171","msg":"trace[949280639] 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:5610; }","duration":"120.396546ms","start":"2026-08-20T17:06:18.735776Z","end":"2026-08-20T17:06:18.856173Z","steps":["trace[949280639] 'agreement among raft nodes before linearized reading' (duration: 104.982505ms)","trace[949280639] 'range keys from in-memory index tree' (duration: 15.176545ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:07:10.493396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-08-20T17:07:10.576273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"80.766825ms","hash":3482530879,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T17:07:10.57636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3482530879,"revision":3020,"compact-revision":-1} {"level":"info","ts":"2026-08-20T17:12:10.504121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6068} {"level":"info","ts":"2026-08-20T17:12:10.627339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6068,"took":"120.954375ms","hash":4000787332,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":21893120,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-20T17:12:10.627522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4000787332,"revision":6068,"compact-revision":3020} {"level":"info","ts":"2026-08-20T17:12:53.357698Z","caller":"traceutil/trace.go:171","msg":"trace[1900617306] linearizableReadLoop","detail":"{readStateIndex:8839; appliedIndex:8838; }","duration":"128.766296ms","start":"2026-08-20T17:12:53.228914Z","end":"2026-08-20T17:12:53.357681Z","steps":["trace[1900617306] 'read index received' (duration: 128.592382ms)","trace[1900617306] 'applied index is now lower than readState.Index' (duration: 172.604µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:12:53.357771Z","caller":"traceutil/trace.go:171","msg":"trace[162193304] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"233.462277ms","start":"2026-08-20T17:12:53.124265Z","end":"2026-08-20T17:12:53.357727Z","steps":["trace[162193304] 'process raft request' (duration: 233.258361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:12:53.357921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.992302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T17:12:53.357947Z","caller":"traceutil/trace.go:171","msg":"trace[1922145203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8538; }","duration":"129.080906ms","start":"2026-08-20T17:12:53.228859Z","end":"2026-08-20T17:12:53.35794Z","steps":["trace[1922145203] 'agreement among raft nodes before linearized reading' (duration: 128.972233ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:13.763576Z","caller":"traceutil/trace.go:171","msg":"trace[260340289] linearizableReadLoop","detail":"{readStateIndex:8957; appliedIndex:8956; }","duration":"193.478144ms","start":"2026-08-20T17:13:13.569998Z","end":"2026-08-20T17:13:13.763476Z","steps":["trace[260340289] 'read index received' (duration: 193.284288ms)","trace[260340289] 'applied index is now lower than readState.Index' (duration: 192.246µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:13:13.763931Z","caller":"traceutil/trace.go:171","msg":"trace[1891681484] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"195.80422ms","start":"2026-08-20T17:13:13.568028Z","end":"2026-08-20T17:13:13.763832Z","steps":["trace[1891681484] 'process raft request' (duration: 195.268705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:13.764085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.05866ms","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-20T17:13:13.764191Z","caller":"traceutil/trace.go:171","msg":"trace[1171977988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8652; }","duration":"194.200354ms","start":"2026-08-20T17:13:13.569962Z","end":"2026-08-20T17:13:13.764162Z","steps":["trace[1171977988] 'agreement among raft nodes before linearized reading' (duration: 193.826873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:13.764419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.152618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T17:13:13.764546Z","caller":"traceutil/trace.go:171","msg":"trace[472548876] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:8652; }","duration":"134.356153ms","start":"2026-08-20T17:13:13.630162Z","end":"2026-08-20T17:13:13.764519Z","steps":["trace[472548876] 'agreement among raft nodes before linearized reading' (duration: 133.721206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:14.021328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.269557ms","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-20T17:13:14.021424Z","caller":"traceutil/trace.go:171","msg":"trace[386503818] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8654; }","duration":"131.441761ms","start":"2026-08-20T17:13:13.88996Z","end":"2026-08-20T17:13:14.021402Z","steps":["trace[386503818] 'range keys from in-memory index tree' (duration: 130.941208ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:22.649367Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002355.wal"} {"level":"info","ts":"2026-08-20T17:13:44.253413Z","caller":"traceutil/trace.go:171","msg":"trace[1700630991] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"263.590763ms","start":"2026-08-20T17:13:43.989783Z","end":"2026-08-20T17:13:44.253374Z","steps":["trace[1700630991] 'process raft request' (duration: 263.398388ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:44.38393Z","caller":"traceutil/trace.go:171","msg":"trace[878435225] linearizableReadLoop","detail":"{readStateIndex:9225; appliedIndex:9223; }","duration":"363.742355ms","start":"2026-08-20T17:13:44.020165Z","end":"2026-08-20T17:13:44.383908Z","steps":["trace[878435225] 'read index received' (duration: 233.048005ms)","trace[878435225] 'applied index is now lower than readState.Index' (duration: 130.69317ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:13:44.384052Z","caller":"traceutil/trace.go:171","msg":"trace[983899702] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8906; }","duration":"392.875215ms","start":"2026-08-20T17:13:43.991105Z","end":"2026-08-20T17:13:44.38398Z","steps":["trace[983899702] 'process raft request' (duration: 379.813538ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:44.384305Z","caller":"traceutil/trace.go:171","msg":"trace[835178099] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"240.256487ms","start":"2026-08-20T17:13:44.144035Z","end":"2026-08-20T17:13:44.384291Z","steps":["trace[835178099] 'process raft request' (duration: 239.766293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:44.384311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T17:13:43.991066Z","time spent":"393.07822ms","remote":"127.0.0.1:42934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":40,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-08-20T17:13:44.384664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.481647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-20T17:13:44.384728Z","caller":"traceutil/trace.go:171","msg":"trace[1567304355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8907; }","duration":"364.61923ms","start":"2026-08-20T17:13:44.020094Z","end":"2026-08-20T17:13:44.384713Z","steps":["trace[1567304355] 'agreement among raft nodes before linearized reading' (duration: 364.395714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:44.384764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T17:13:44.02007Z","time spent":"364.685702ms","remote":"127.0.0.1:38610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-20T17:13:44.396882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.60292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lmwzc\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-20T17:13:44.396945Z","caller":"traceutil/trace.go:171","msg":"trace[177312604] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lmwzc; range_end:; response_count:1; response_revision:8910; }","duration":"369.692923ms","start":"2026-08-20T17:13:44.027237Z","end":"2026-08-20T17:13:44.396929Z","steps":["trace[177312604] 'agreement among raft nodes before linearized reading' (duration: 369.495187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:44.396989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T17:13:44.027226Z","time spent":"369.750045ms","remote":"127.0.0.1:38518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-lmwzc\" "} {"level":"warn","ts":"2026-08-20T17:13:44.396977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.634487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T17:13:44.397047Z","caller":"traceutil/trace.go:171","msg":"trace[1581615058] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8910; }","duration":"157.74163ms","start":"2026-08-20T17:13:44.239286Z","end":"2026-08-20T17:13:44.397028Z","steps":["trace[1581615058] 'agreement among raft nodes before linearized reading' (duration: 157.582555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:44.397136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.967821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2fnql\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-08-20T17:13:44.397162Z","caller":"traceutil/trace.go:171","msg":"trace[1811131698] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2fnql; range_end:; response_count:1; response_revision:8910; }","duration":"140.042103ms","start":"2026-08-20T17:13:44.257113Z","end":"2026-08-20T17:13:44.397155Z","steps":["trace[1811131698] 'agreement among raft nodes before linearized reading' (duration: 139.961701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:44.39717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.518977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T17:13:44.397245Z","caller":"traceutil/trace.go:171","msg":"trace[756920091] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8910; }","duration":"333.651871ms","start":"2026-08-20T17:13:44.063575Z","end":"2026-08-20T17:13:44.397227Z","steps":["trace[756920091] 'agreement among raft nodes before linearized reading' (duration: 333.543058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:44.397076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.002861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-20T17:13:44.397303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T17:13:44.063545Z","time spent":"333.741763ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-08-20T17:13:44.397151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.557253ms","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-20T17:13:44.397353Z","caller":"traceutil/trace.go:171","msg":"trace[12817758] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:8910; }","duration":"297.347891ms","start":"2026-08-20T17:13:44.09998Z","end":"2026-08-20T17:13:44.397328Z","steps":["trace[12817758] 'agreement among raft nodes before linearized reading' (duration: 296.96265ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:44.397387Z","caller":"traceutil/trace.go:171","msg":"trace[443544482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8910; }","duration":"106.865181ms","start":"2026-08-20T17:13:44.290507Z","end":"2026-08-20T17:13:44.397372Z","steps":["trace[443544482] 'agreement among raft nodes before linearized reading' (duration: 106.403658ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:44.397594Z","caller":"traceutil/trace.go:171","msg":"trace[566334904] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"134.497458ms","start":"2026-08-20T17:13:44.263039Z","end":"2026-08-20T17:13:44.397536Z","steps":["trace[566334904] 'process raft request' (duration: 133.531831ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:44.903471Z","caller":"traceutil/trace.go:171","msg":"trace[208577536] transaction","detail":"{read_only:false; response_revision:8915; number_of_response:1; }","duration":"220.411641ms","start":"2026-08-20T17:13:44.682996Z","end":"2026-08-20T17:13:44.903408Z","steps":["trace[208577536] 'process raft request' (duration: 212.852978ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:44.927579Z","caller":"traceutil/trace.go:171","msg":"trace[119717788] linearizableReadLoop","detail":"{readStateIndex:9235; appliedIndex:9233; }","duration":"222.50581ms","start":"2026-08-20T17:13:44.705043Z","end":"2026-08-20T17:13:44.927548Z","steps":["trace[119717788] 'read index received' (duration: 190.890982ms)","trace[119717788] 'applied index is now lower than readState.Index' (duration: 31.613558ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:13:44.927787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.667844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T17:13:44.927835Z","caller":"traceutil/trace.go:171","msg":"trace[772673575] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8916; }","duration":"222.840949ms","start":"2026-08-20T17:13:44.704982Z","end":"2026-08-20T17:13:44.927823Z","steps":["trace[772673575] 'agreement among raft nodes before linearized reading' (duration: 222.683034ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:44.927924Z","caller":"traceutil/trace.go:171","msg":"trace[850097331] transaction","detail":"{read_only:false; response_revision:8916; number_of_response:1; }","duration":"231.653606ms","start":"2026-08-20T17:13:44.696252Z","end":"2026-08-20T17:13:44.927906Z","steps":["trace[850097331] 'process raft request' (duration: 231.163943ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:13:45.330361Z","caller":"traceutil/trace.go:171","msg":"trace[560081087] linearizableReadLoop","detail":"{readStateIndex:9236; appliedIndex:9235; }","duration":"161.712952ms","start":"2026-08-20T17:13:45.168602Z","end":"2026-08-20T17:13:45.330315Z","steps":["trace[560081087] 'read index received' (duration: 161.465125ms)","trace[560081087] 'applied index is now lower than readState.Index' (duration: 246.607µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:13:45.330436Z","caller":"traceutil/trace.go:171","msg":"trace[1157385106] transaction","detail":"{read_only:false; response_revision:8917; number_of_response:1; }","duration":"219.549156ms","start":"2026-08-20T17:13:45.110843Z","end":"2026-08-20T17:13:45.330392Z","steps":["trace[1157385106] 'process raft request' (duration: 219.263828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:45.330663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.046911ms","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-20T17:13:45.330723Z","caller":"traceutil/trace.go:171","msg":"trace[486305343] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8917; }","duration":"162.240156ms","start":"2026-08-20T17:13:45.168466Z","end":"2026-08-20T17:13:45.330707Z","steps":["trace[486305343] 'agreement among raft nodes before linearized reading' (duration: 162.002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:13:45.935485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.488845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-20T17:13:45.935585Z","caller":"traceutil/trace.go:171","msg":"trace[1446701055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8920; }","duration":"182.65517ms","start":"2026-08-20T17:13:45.75291Z","end":"2026-08-20T17:13:45.935565Z","steps":["trace[1446701055] 'range keys from in-memory index tree' (duration: 182.30577ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:15:00.634632Z","caller":"traceutil/trace.go:171","msg":"trace[249811831] transaction","detail":"{read_only:false; response_revision:9659; number_of_response:1; }","duration":"177.678207ms","start":"2026-08-20T17:15:00.456922Z","end":"2026-08-20T17:15:00.6346Z","steps":["trace[249811831] 'process raft request' (duration: 165.123794ms)","trace[249811831] 'compare' (duration: 12.304797ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:15:00.63478Z","caller":"traceutil/trace.go:171","msg":"trace[1513973784] transaction","detail":"{read_only:false; response_revision:9660; number_of_response:1; }","duration":"177.821341ms","start":"2026-08-20T17:15:00.456918Z","end":"2026-08-20T17:15:00.63474Z","steps":["trace[1513973784] 'process raft request' (duration: 177.618756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:15:00.907495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.884004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T17:15:00.907718Z","caller":"traceutil/trace.go:171","msg":"trace[484026135] linearizableReadLoop","detail":"{readStateIndex:9998; appliedIndex:9997; }","duration":"184.987733ms","start":"2026-08-20T17:15:00.722702Z","end":"2026-08-20T17:15:00.90769Z","steps":["trace[484026135] 'read index received' (duration: 56.702018ms)","trace[484026135] 'applied index is now lower than readState.Index' (duration: 128.282675ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:15:00.907766Z","caller":"traceutil/trace.go:171","msg":"trace[959652001] transaction","detail":"{read_only:false; response_revision:9663; number_of_response:1; }","duration":"182.169364ms","start":"2026-08-20T17:15:00.725577Z","end":"2026-08-20T17:15:00.907747Z","steps":["trace[959652001] 'process raft request' (duration: 182.111912ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:15:00.907773Z","caller":"traceutil/trace.go:171","msg":"trace[543750537] transaction","detail":"{read_only:false; response_revision:9661; number_of_response:1; }","duration":"209.500935ms","start":"2026-08-20T17:15:00.698201Z","end":"2026-08-20T17:15:00.907702Z","steps":["trace[543750537] 'process raft request' (duration: 81.288721ms)","trace[543750537] 'compare' (duration: 127.688819ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:15:00.907849Z","caller":"traceutil/trace.go:171","msg":"trace[498286611] transaction","detail":"{read_only:false; response_revision:9662; number_of_response:1; }","duration":"182.39739ms","start":"2026-08-20T17:15:00.725438Z","end":"2026-08-20T17:15:00.907835Z","steps":["trace[498286611] 'process raft request' (duration: 182.190465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:15:00.908076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.304262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T17:15:00.908167Z","caller":"traceutil/trace.go:171","msg":"trace[745573709] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:9663; }","duration":"185.523278ms","start":"2026-08-20T17:15:00.722619Z","end":"2026-08-20T17:15:00.908142Z","steps":["trace[745573709] 'agreement among raft nodes before linearized reading' (duration: 185.206059ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:15:00.962413Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"warn","ts":"2026-08-20T17:15:00.98775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.99695ms","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-20T17:15:00.987833Z","caller":"traceutil/trace.go:171","msg":"trace[1553630147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9664; }","duration":"132.139594ms","start":"2026-08-20T17:15:00.855671Z","end":"2026-08-20T17:15:00.987811Z","steps":["trace[1553630147] 'agreement among raft nodes before linearized reading' (duration: 106.848461ms)","trace[1553630147] 'range keys from in-memory index tree' (duration: 25.052126ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:15:01.090529Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-20T17:15:01.09069Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-08-20T17:15:01.294536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.283147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T17:15:01.294799Z","caller":"traceutil/trace.go:171","msg":"trace[648126961] transaction","detail":"{read_only:false; response_revision:9667; number_of_response:1; }","duration":"156.303106ms","start":"2026-08-20T17:15:01.138449Z","end":"2026-08-20T17:15:01.294752Z","steps":["trace[648126961] 'process raft request' (duration: 50.693469ms)","trace[648126961] 'compare' (duration: 105.136053ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:17:10.510846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8138} {"level":"info","ts":"2026-08-20T17:17:10.578437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8138,"took":"64.134316ms","hash":838735917,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":28192768,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-20T17:17:10.57851Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":838735917,"revision":8138,"compact-revision":6068} {"level":"info","ts":"2026-08-20T17:20:39.322708Z","caller":"traceutil/trace.go:171","msg":"trace[1682318672] linearizableReadLoop","detail":"{readStateIndex:12512; appliedIndex:12511; }","duration":"137.746758ms","start":"2026-08-20T17:20:39.184934Z","end":"2026-08-20T17:20:39.322681Z","steps":["trace[1682318672] 'read index received' (duration: 137.560523ms)","trace[1682318672] 'applied index is now lower than readState.Index' (duration: 185.025µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:20:39.322848Z","caller":"traceutil/trace.go:171","msg":"trace[1790541483] transaction","detail":"{read_only:false; response_revision:12096; number_of_response:1; }","duration":"206.197887ms","start":"2026-08-20T17:20:39.116605Z","end":"2026-08-20T17:20:39.322803Z","steps":["trace[1790541483] 'process raft request' (duration: 205.923628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:20:39.322995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.993746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T17:20:39.323119Z","caller":"traceutil/trace.go:171","msg":"trace[1333787816] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:12096; }","duration":"138.181422ms","start":"2026-08-20T17:20:39.18491Z","end":"2026-08-20T17:20:39.323092Z","steps":["trace[1333787816] 'agreement among raft nodes before linearized reading' (duration: 137.923694ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:22:10.517384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10547} {"level":"info","ts":"2026-08-20T17:22:10.602799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10547,"took":"82.760469ms","hash":4119876344,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":26734592,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-20T17:22:10.602889Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4119876344,"revision":10547,"compact-revision":8138} {"level":"info","ts":"2026-08-20T17:27:10.523962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12677} {"level":"info","ts":"2026-08-20T17:27:10.60245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12677,"took":"75.565313ms","hash":2434251927,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":27680768,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-20T17:27:10.602532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2434251927,"revision":12677,"compact-revision":10547} {"level":"info","ts":"2026-08-20T17:28:47.711757Z","caller":"traceutil/trace.go:171","msg":"trace[1045484424] transaction","detail":"{read_only:false; response_revision:15211; number_of_response:1; }","duration":"252.710152ms","start":"2026-08-20T17:28:47.459011Z","end":"2026-08-20T17:28:47.711721Z","steps":["trace[1045484424] 'process raft request' (duration: 252.465005ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:28:47.713213Z","caller":"traceutil/trace.go:171","msg":"trace[1532118563] linearizableReadLoop","detail":"{readStateIndex:15740; appliedIndex:15738; }","duration":"221.511801ms","start":"2026-08-20T17:28:47.491686Z","end":"2026-08-20T17:28:47.713198Z","steps":["trace[1532118563] 'read index received' (duration: 219.827464ms)","trace[1532118563] 'applied index is now lower than readState.Index' (duration: 1.683467ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:28:47.713295Z","caller":"traceutil/trace.go:171","msg":"trace[965163894] transaction","detail":"{read_only:false; response_revision:15212; number_of_response:1; }","duration":"222.847458ms","start":"2026-08-20T17:28:47.49042Z","end":"2026-08-20T17:28:47.713267Z","steps":["trace[965163894] 'process raft request' (duration: 222.701374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:28:47.713511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.796019ms","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-20T17:28:47.713563Z","caller":"traceutil/trace.go:171","msg":"trace[999568013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15212; }","duration":"221.904312ms","start":"2026-08-20T17:28:47.491648Z","end":"2026-08-20T17:28:47.713552Z","steps":["trace[999568013] 'agreement among raft nodes before linearized reading' (duration: 221.679206ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:30:37.582285Z","caller":"traceutil/trace.go:171","msg":"trace[115312619] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:16338; }","duration":"100.003921ms","start":"2026-08-20T17:30:37.482232Z","end":"2026-08-20T17:30:37.582236Z","steps":["trace[115312619] 'count revisions from in-memory index tree' (duration: 99.917019ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:30:38.430327Z","caller":"traceutil/trace.go:171","msg":"trace[815399352] linearizableReadLoop","detail":"{readStateIndex:16905; appliedIndex:16904; }","duration":"111.144165ms","start":"2026-08-20T17:30:38.319156Z","end":"2026-08-20T17:30:38.4303Z","steps":["trace[815399352] 'read index received' (duration: 110.908809ms)","trace[815399352] 'applied index is now lower than readState.Index' (duration: 233.836µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:30:38.430482Z","caller":"traceutil/trace.go:171","msg":"trace[927418453] transaction","detail":"{read_only:false; response_revision:16341; number_of_response:1; }","duration":"111.953218ms","start":"2026-08-20T17:30:38.318483Z","end":"2026-08-20T17:30:38.430437Z","steps":["trace[927418453] 'process raft request' (duration: 111.648769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:30:38.430752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.557178ms","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-20T17:30:38.430867Z","caller":"traceutil/trace.go:171","msg":"trace[374883639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16341; }","duration":"111.703691ms","start":"2026-08-20T17:30:38.319137Z","end":"2026-08-20T17:30:38.43084Z","steps":["trace[374883639] 'agreement among raft nodes before linearized reading' (duration: 111.309119ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:30:38.552518Z","caller":"traceutil/trace.go:171","msg":"trace[452024234] linearizableReadLoop","detail":"{readStateIndex:16906; appliedIndex:16905; }","duration":"112.908015ms","start":"2026-08-20T17:30:38.43958Z","end":"2026-08-20T17:30:38.552488Z","steps":["trace[452024234] 'read index received' (duration: 108.030517ms)","trace[452024234] 'applied index is now lower than readState.Index' (duration: 4.876148ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:30:38.553268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.665856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737371"} {"level":"info","ts":"2026-08-20T17:30:38.553329Z","caller":"traceutil/trace.go:171","msg":"trace[596416126] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16342; }","duration":"113.767469ms","start":"2026-08-20T17:30:38.439551Z","end":"2026-08-20T17:30:38.553319Z","steps":["trace[596416126] 'agreement among raft nodes before linearized reading' (duration: 113.036329ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:30:38.552585Z","caller":"traceutil/trace.go:171","msg":"trace[1092560018] transaction","detail":"{read_only:false; response_revision:16342; number_of_response:1; }","duration":"115.931871ms","start":"2026-08-20T17:30:38.436611Z","end":"2026-08-20T17:30:38.552542Z","steps":["trace[1092560018] 'process raft request' (duration: 111.007601ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:32:10.532384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14703} {"level":"info","ts":"2026-08-20T17:32:10.625834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14703,"took":"90.417049ms","hash":1838735690,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30150656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-20T17:32:10.625934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1838735690,"revision":14703,"compact-revision":12677} {"level":"info","ts":"2026-08-20T17:37:07.848243Z","caller":"traceutil/trace.go:171","msg":"trace[1865256249] transaction","detail":"{read_only:false; response_revision:19142; number_of_response:1; }","duration":"105.879004ms","start":"2026-08-20T17:37:07.742327Z","end":"2026-08-20T17:37:07.848206Z","steps":["trace[1865256249] 'process raft request' (duration: 105.664498ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:37:08.479369Z","caller":"traceutil/trace.go:171","msg":"trace[187072053] linearizableReadLoop","detail":"{readStateIndex:19803; appliedIndex:19802; }","duration":"168.888453ms","start":"2026-08-20T17:37:08.310446Z","end":"2026-08-20T17:37:08.479334Z","steps":["trace[187072053] 'read index received' (duration: 168.835792ms)","trace[187072053] 'applied index is now lower than readState.Index' (duration: 50.961µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:37:08.479469Z","caller":"traceutil/trace.go:171","msg":"trace[862968505] transaction","detail":"{read_only:false; response_revision:19145; number_of_response:1; }","duration":"176.18657ms","start":"2026-08-20T17:37:08.303224Z","end":"2026-08-20T17:37:08.47941Z","steps":["trace[862968505] 'process raft request' (duration: 175.922643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:37:08.480597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.118028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946840"} {"level":"info","ts":"2026-08-20T17:37:08.480652Z","caller":"traceutil/trace.go:171","msg":"trace[1486503665] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:19145; }","duration":"170.252592ms","start":"2026-08-20T17:37:08.310388Z","end":"2026-08-20T17:37:08.48064Z","steps":["trace[1486503665] 'agreement among raft nodes before linearized reading' (duration: 169.1128ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:37:08.566676Z","caller":"traceutil/trace.go:171","msg":"trace[2070302739] transaction","detail":"{read_only:false; response_revision:19146; number_of_response:1; }","duration":"256.178791ms","start":"2026-08-20T17:37:08.310475Z","end":"2026-08-20T17:37:08.566653Z","steps":["trace[2070302739] 'process raft request' (duration: 220.973982ms)","trace[2070302739] 'compare' (duration: 34.922631ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:37:08.56685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.697813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T17:37:08.566994Z","caller":"traceutil/trace.go:171","msg":"trace[1606412173] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:19148; }","duration":"239.902588ms","start":"2026-08-20T17:37:08.327057Z","end":"2026-08-20T17:37:08.566959Z","steps":["trace[1606412173] 'agreement among raft nodes before linearized reading' (duration: 239.647791ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:37:08.567074Z","caller":"traceutil/trace.go:171","msg":"trace[1290521552] transaction","detail":"{read_only:false; response_revision:19147; number_of_response:1; }","duration":"256.468599ms","start":"2026-08-20T17:37:08.310569Z","end":"2026-08-20T17:37:08.567038Z","steps":["trace[1290521552] 'process raft request' (duration: 255.952654ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:37:08.567315Z","caller":"traceutil/trace.go:171","msg":"trace[831647163] transaction","detail":"{read_only:false; response_revision:19148; number_of_response:1; }","duration":"256.432418ms","start":"2026-08-20T17:37:08.310862Z","end":"2026-08-20T17:37:08.567295Z","steps":["trace[831647163] 'process raft request' (duration: 255.695777ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:37:10.53953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16942} {"level":"info","ts":"2026-08-20T17:37:10.660179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16942,"took":"116.517196ms","hash":2749482759,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T17:37:10.660288Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2749482759,"revision":16942,"compact-revision":14703} {"level":"info","ts":"2026-08-20T17:37:14.712775Z","caller":"traceutil/trace.go:171","msg":"trace[65410981] linearizableReadLoop","detail":"{readStateIndex:19909; appliedIndex:19907; }","duration":"100.966505ms","start":"2026-08-20T17:37:14.611774Z","end":"2026-08-20T17:37:14.71274Z","steps":["trace[65410981] 'read index received' (duration: 88.401518ms)","trace[65410981] 'applied index is now lower than readState.Index' (duration: 12.560107ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:37:14.71281Z","caller":"traceutil/trace.go:171","msg":"trace[613949125] transaction","detail":"{read_only:false; response_revision:19247; number_of_response:1; }","duration":"117.972387ms","start":"2026-08-20T17:37:14.594789Z","end":"2026-08-20T17:37:14.712761Z","steps":["trace[613949125] 'process raft request' (duration: 105.465222ms)","trace[613949125] 'compare' (duration: 12.173946ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:37:14.712821Z","caller":"traceutil/trace.go:171","msg":"trace[1534109351] transaction","detail":"{read_only:false; response_revision:19248; number_of_response:1; }","duration":"117.131184ms","start":"2026-08-20T17:37:14.59565Z","end":"2026-08-20T17:37:14.712781Z","steps":["trace[1534109351] 'process raft request' (duration: 117.010131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:37:14.713049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.238612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2242e64f-9fc5-4df3-bbc9-21636935e6ba\" ","response":"range_response_count:1 size:3640"} {"level":"info","ts":"2026-08-20T17:37:14.713098Z","caller":"traceutil/trace.go:171","msg":"trace[2139494723] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2242e64f-9fc5-4df3-bbc9-21636935e6ba; range_end:; response_count:1; response_revision:19248; }","duration":"101.346725ms","start":"2026-08-20T17:37:14.611739Z","end":"2026-08-20T17:37:14.713085Z","steps":["trace[2139494723] 'agreement among raft nodes before linearized reading' (duration: 101.114229ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:37:16.662814Z","caller":"traceutil/trace.go:171","msg":"trace[903092181] transaction","detail":"{read_only:false; response_revision:19273; number_of_response:1; }","duration":"134.571019ms","start":"2026-08-20T17:37:16.528214Z","end":"2026-08-20T17:37:16.662785Z","steps":["trace[903092181] 'process raft request' (duration: 131.667366ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:37:16.663027Z","caller":"traceutil/trace.go:171","msg":"trace[63049425] linearizableReadLoop","detail":"{readStateIndex:19936; appliedIndex:19933; }","duration":"121.425265ms","start":"2026-08-20T17:37:16.541588Z","end":"2026-08-20T17:37:16.663014Z","steps":["trace[63049425] 'read index received' (duration: 36.29873ms)","trace[63049425] 'applied index is now lower than readState.Index' (duration: 85.124985ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T17:37:16.663306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.701413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T17:37:16.663346Z","caller":"traceutil/trace.go:171","msg":"trace[2045635465] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:19274; }","duration":"121.812196ms","start":"2026-08-20T17:37:16.541523Z","end":"2026-08-20T17:37:16.663336Z","steps":["trace[2045635465] 'agreement among raft nodes before linearized reading' (duration: 121.692833ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:37:18.574933Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T17:37:18.596569Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-20T17:37:18.596753Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-20T17:42:10.546603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19163} {"level":"info","ts":"2026-08-20T17:42:10.647117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19163,"took":"95.654164ms","hash":278222004,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":33890304,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-20T17:42:10.647237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":278222004,"revision":19163,"compact-revision":16942} {"level":"warn","ts":"2026-08-20T17:43:45.770872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.287501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T17:43:45.771006Z","caller":"traceutil/trace.go:171","msg":"trace[1293159300] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:22118; }","duration":"118.500676ms","start":"2026-08-20T17:43:45.652477Z","end":"2026-08-20T17:43:45.770978Z","steps":["trace[1293159300] 'count revisions from in-memory index tree' (duration: 118.157357ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:43:46.414197Z","caller":"traceutil/trace.go:171","msg":"trace[1810512770] linearizableReadLoop","detail":"{readStateIndex:22876; appliedIndex:22875; }","duration":"267.756192ms","start":"2026-08-20T17:43:46.146407Z","end":"2026-08-20T17:43:46.414163Z","steps":["trace[1810512770] 'read index received' (duration: 267.618998ms)","trace[1810512770] 'applied index is now lower than readState.Index' (duration: 135.684µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:43:46.414248Z","caller":"traceutil/trace.go:171","msg":"trace[1238716458] transaction","detail":"{read_only:false; response_revision:22119; number_of_response:1; }","duration":"301.892106ms","start":"2026-08-20T17:43:46.112314Z","end":"2026-08-20T17:43:46.414206Z","steps":["trace[1238716458] 'process raft request' (duration: 301.66193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:43:46.414449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T17:43:46.112291Z","time spent":"302.05099ms","remote":"127.0.0.1:38610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T17:43:46.414472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.033219ms","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-20T17:43:46.414511Z","caller":"traceutil/trace.go:171","msg":"trace[1743616791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22119; }","duration":"268.151993ms","start":"2026-08-20T17:43:46.146347Z","end":"2026-08-20T17:43:46.414499Z","steps":["trace[1743616791] 'agreement among raft nodes before linearized reading' (duration: 267.957288ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:43:46.523638Z","caller":"traceutil/trace.go:171","msg":"trace[605270513] transaction","detail":"{read_only:false; response_revision:22123; number_of_response:1; }","duration":"104.612304ms","start":"2026-08-20T17:43:46.419001Z","end":"2026-08-20T17:43:46.523614Z","steps":["trace[605270513] 'process raft request' (duration: 104.534982ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:43:46.523656Z","caller":"traceutil/trace.go:171","msg":"trace[698832553] transaction","detail":"{read_only:false; response_revision:22122; number_of_response:1; }","duration":"166.899073ms","start":"2026-08-20T17:43:46.356714Z","end":"2026-08-20T17:43:46.523613Z","steps":["trace[698832553] 'process raft request' (duration: 166.79164ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:43:46.52369Z","caller":"traceutil/trace.go:171","msg":"trace[289562995] transaction","detail":"{read_only:false; response_revision:22120; number_of_response:1; }","duration":"298.039107ms","start":"2026-08-20T17:43:46.225558Z","end":"2026-08-20T17:43:46.523597Z","steps":["trace[289562995] 'process raft request' (duration: 287.929071ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:43:46.523721Z","caller":"traceutil/trace.go:171","msg":"trace[1670742039] linearizableReadLoop","detail":"{readStateIndex:22879; appliedIndex:22876; }","duration":"109.389329ms","start":"2026-08-20T17:43:46.414274Z","end":"2026-08-20T17:43:46.523663Z","steps":["trace[1670742039] 'read index received' (duration: 99.315314ms)","trace[1670742039] 'applied index is now lower than readState.Index' (duration: 10.070245ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:43:46.52393Z","caller":"traceutil/trace.go:171","msg":"trace[1744277212] transaction","detail":"{read_only:false; response_revision:22121; number_of_response:1; }","duration":"231.621031ms","start":"2026-08-20T17:43:46.292283Z","end":"2026-08-20T17:43:46.523904Z","steps":["trace[1744277212] 'process raft request' (duration: 231.188559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:43:46.524065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.659704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T17:43:46.524125Z","caller":"traceutil/trace.go:171","msg":"trace[652278913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22124; }","duration":"105.788507ms","start":"2026-08-20T17:43:46.418321Z","end":"2026-08-20T17:43:46.524109Z","steps":["trace[652278913] 'agreement among raft nodes before linearized reading' (duration: 105.659904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:43:46.524164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.854208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-20T17:43:46.524223Z","caller":"traceutil/trace.go:171","msg":"trace[1044068831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22124; }","duration":"197.969711ms","start":"2026-08-20T17:43:46.326236Z","end":"2026-08-20T17:43:46.524206Z","steps":["trace[1044068831] 'agreement among raft nodes before linearized reading' (duration: 197.658152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:43:46.524534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.134729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T17:43:46.524684Z","caller":"traceutil/trace.go:171","msg":"trace[1908083480] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:22124; }","duration":"298.368755ms","start":"2026-08-20T17:43:46.226282Z","end":"2026-08-20T17:43:46.52465Z","steps":["trace[1908083480] 'agreement among raft nodes before linearized reading' (duration: 297.81264ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:43:46.699667Z","caller":"traceutil/trace.go:171","msg":"trace[1274367444] transaction","detail":"{read_only:false; response_revision:22125; number_of_response:1; }","duration":"170.124975ms","start":"2026-08-20T17:43:46.529513Z","end":"2026-08-20T17:43:46.699638Z","steps":["trace[1274367444] 'process raft request' (duration: 158.030382ms)","trace[1274367444] 'compare' (duration: 11.826875ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T17:47:10.554901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21575} {"level":"info","ts":"2026-08-20T17:47:10.662813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21575,"took":"104.898073ms","hash":802988510,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30633984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-20T17:47:10.662895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":802988510,"revision":21575,"compact-revision":19163} {"level":"warn","ts":"2026-08-20T17:48:00.050024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.767325ms","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-20T17:48:00.050865Z","caller":"traceutil/trace.go:171","msg":"trace[798477238] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:24674; }","duration":"158.65207ms","start":"2026-08-20T17:47:59.892177Z","end":"2026-08-20T17:48:00.050829Z","steps":["trace[798477238] 'count revisions from in-memory index tree' (duration: 157.681573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T17:48:00.050217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.969102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-20T17:48:00.051181Z","caller":"traceutil/trace.go:171","msg":"trace[464908069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24674; }","duration":"180.926749ms","start":"2026-08-20T17:47:59.870166Z","end":"2026-08-20T17:48:00.051092Z","steps":["trace[464908069] 'range keys from in-memory index tree' (duration: 179.774177ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T17:49:49.949195Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006819.wal"} {"level":"info","ts":"2026-08-20T17:52:10.561628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23780} {"level":"info","ts":"2026-08-20T17:52:10.682326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23780,"took":"115.972047ms","hash":1622735126,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":42143744,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-20T17:52:10.682457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1622735126,"revision":23780,"compact-revision":21575} {"level":"info","ts":"2026-08-20T17:57:05.581537Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T17:57:05.586224Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-20T17:57:05.58632Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-20T17:57:10.567541Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26851} {"level":"info","ts":"2026-08-20T17:57:10.703566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26851,"took":"130.977802ms","hash":706660032,"current-db-size-bytes":57659392,"current-db-size":"58 MB","current-db-size-in-use-bytes":29691904,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-20T17:57:10.703649Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":706660032,"revision":26851,"compact-revision":23780}