{"level":"warn","ts":"2026-04-20T08:11:23.983503Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:11:23.983656Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.203:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.203:2380","--initial-cluster=instance=https://199.19.213.203:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.203:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.203: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-04-20T08:11:23.98377Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:11:23.983793Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.203:2380"]} {"level":"info","ts":"2026-04-20T08:11:23.983828Z","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-04-20T08:11:23.985223Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"]} {"level":"info","ts":"2026-04-20T08:11:23.985363Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.203:2380"],"listen-peer-urls":["https://199.19.213.203:2380"],"advertise-client-urls":["https://199.19.213.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.203: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-04-20T08:11:23.991834Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.477135ms"} {"level":"info","ts":"2026-04-20T08:11:24.001018Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b5a1dacc8ba86a4f","cluster-id":"a3ec0b489c26f222"} {"level":"info","ts":"2026-04-20T08:11:24.001098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:11:24.001119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became follower at term 0"} {"level":"info","ts":"2026-04-20T08:11:24.001137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b5a1dacc8ba86a4f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:11:24.00115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became follower at term 1"} {"level":"info","ts":"2026-04-20T08:11:24.001187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=(13087982564166625871)"} {"level":"warn","ts":"2026-04-20T08:11:24.011789Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:11:24.013737Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:11:24.015242Z","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-04-20T08:11:24.018195Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b5a1dacc8ba86a4f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:11:24.018555Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b5a1dacc8ba86a4f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:11:24.018629Z","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-04-20T08:11:24.020438Z","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-04-20T08:11:24.020478Z","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-04-20T08:11:24.022174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=(13087982564166625871)"} {"level":"info","ts":"2026-04-20T08:11:24.022456Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a3ec0b489c26f222","local-member-id":"b5a1dacc8ba86a4f","added-peer-id":"b5a1dacc8ba86a4f","added-peer-peer-urls":["https://199.19.213.203:2380"]} {"level":"info","ts":"2026-04-20T08:11:24.025691Z","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-04-20T08:11:24.025976Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.203:2380"} {"level":"info","ts":"2026-04-20T08:11:24.026022Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.203:2380"} {"level":"info","ts":"2026-04-20T08:11:24.026095Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b5a1dacc8ba86a4f","initial-advertise-peer-urls":["https://199.19.213.203:2380"],"listen-peer-urls":["https://199.19.213.203:2380"],"advertise-client-urls":["https://199.19.213.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:11:24.026163Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:11:24.302163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:11:24.30228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:11:24.302351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f received MsgPreVoteResp from b5a1dacc8ba86a4f at term 1"} {"level":"info","ts":"2026-04-20T08:11:24.302387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:11:24.30241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f received MsgVoteResp from b5a1dacc8ba86a4f at term 2"} {"level":"info","ts":"2026-04-20T08:11:24.302435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became leader at term 2"} {"level":"info","ts":"2026-04-20T08:11:24.302467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b5a1dacc8ba86a4f elected leader b5a1dacc8ba86a4f at term 2"} {"level":"info","ts":"2026-04-20T08:11:24.303533Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b5a1dacc8ba86a4f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.203:2379]}","request-path":"/0/members/b5a1dacc8ba86a4f/attributes","cluster-id":"a3ec0b489c26f222","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:11:24.30354Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:24.303539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:11:24.303605Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:11:24.303833Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:11:24.303885Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:11:24.304515Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a3ec0b489c26f222","local-member-id":"b5a1dacc8ba86a4f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:24.30464Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:24.304698Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:11:24.306197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.203:2379"} {"level":"info","ts":"2026-04-20T08:11:24.306217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T08:11:42.940692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.068939ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T08:11:42.940836Z","caller":"traceutil/trace.go:171","msg":"trace[1089999491] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:472; }","duration":"106.392536ms","start":"2026-04-20T08:11:42.834424Z","end":"2026-04-20T08:11:42.940816Z","steps":["trace[1089999491] 'read index received' (duration: 210.585µs)","trace[1089999491] 'applied index is now lower than readState.Index' (duration: 106.179881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:11:42.940988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.54407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:11:42.941024Z","caller":"traceutil/trace.go:171","msg":"trace[1232626874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"106.622963ms","start":"2026-04-20T08:11:42.834391Z","end":"2026-04-20T08:11:42.941014Z","steps":["trace[1232626874] 'agreement among raft nodes before linearized reading' (duration: 106.50968ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:48.545445Z","caller":"traceutil/trace.go:171","msg":"trace[20884591] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"205.964666ms","start":"2026-04-20T08:11:48.339458Z","end":"2026-04-20T08:11:48.545423Z","steps":["trace[20884591] 'process raft request' (duration: 205.855413ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.015805Z","caller":"traceutil/trace.go:171","msg":"trace[1139179718] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"158.04203ms","start":"2026-04-20T08:11:53.857744Z","end":"2026-04-20T08:11:54.015786Z","steps":["trace[1139179718] 'process raft request' (duration: 157.934917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022256Z","caller":"traceutil/trace.go:171","msg":"trace[1924312649] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"158.50953ms","start":"2026-04-20T08:11:53.863713Z","end":"2026-04-20T08:11:54.022223Z","steps":["trace[1924312649] 'process raft request' (duration: 158.334976ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022438Z","caller":"traceutil/trace.go:171","msg":"trace[1541322689] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"150.282072ms","start":"2026-04-20T08:11:53.872142Z","end":"2026-04-20T08:11:54.022424Z","steps":["trace[1541322689] 'process raft request' (duration: 150.083417ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022336Z","caller":"traceutil/trace.go:171","msg":"trace[1360552764] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"158.267344ms","start":"2026-04-20T08:11:53.864034Z","end":"2026-04-20T08:11:54.022302Z","steps":["trace[1360552764] 'process raft request' (duration: 158.12088ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022494Z","caller":"traceutil/trace.go:171","msg":"trace[1588564295] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"148.371398ms","start":"2026-04-20T08:11:53.87411Z","end":"2026-04-20T08:11:54.022482Z","steps":["trace[1588564295] 'process raft request' (duration: 148.218604ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022586Z","caller":"traceutil/trace.go:171","msg":"trace[192909831] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"148.351828ms","start":"2026-04-20T08:11:53.874223Z","end":"2026-04-20T08:11:54.022575Z","steps":["trace[192909831] 'process raft request' (duration: 148.215145ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022571Z","caller":"traceutil/trace.go:171","msg":"trace[559396382] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"149.418692ms","start":"2026-04-20T08:11:53.87307Z","end":"2026-04-20T08:11:54.022489Z","steps":["trace[559396382] 'process raft request' (duration: 149.209837ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022713Z","caller":"traceutil/trace.go:171","msg":"trace[1437288265] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"147.427776ms","start":"2026-04-20T08:11:53.875277Z","end":"2026-04-20T08:11:54.022704Z","steps":["trace[1437288265] 'process raft request' (duration: 147.210921ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022847Z","caller":"traceutil/trace.go:171","msg":"trace[1243310508] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"102.508237ms","start":"2026-04-20T08:11:53.920316Z","end":"2026-04-20T08:11:54.022824Z","steps":["trace[1243310508] 'process raft request' (duration: 102.453705ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.022904Z","caller":"traceutil/trace.go:171","msg":"trace[1848903607] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"133.335453ms","start":"2026-04-20T08:11:53.889488Z","end":"2026-04-20T08:11:54.022823Z","steps":["trace[1848903607] 'process raft request' (duration: 133.23519ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.023064Z","caller":"traceutil/trace.go:171","msg":"trace[480587984] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"134.136511ms","start":"2026-04-20T08:11:53.888686Z","end":"2026-04-20T08:11:54.022822Z","steps":["trace[480587984] 'process raft request' (duration: 133.897855ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.351024Z","caller":"traceutil/trace.go:171","msg":"trace[556004113] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"130.144201ms","start":"2026-04-20T08:11:54.220851Z","end":"2026-04-20T08:11:54.350995Z","steps":["trace[556004113] 'process raft request' (duration: 128.330739ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.353756Z","caller":"traceutil/trace.go:171","msg":"trace[539511423] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"127.756577ms","start":"2026-04-20T08:11:54.225978Z","end":"2026-04-20T08:11:54.353735Z","steps":["trace[539511423] 'process raft request' (duration: 127.691754ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.353843Z","caller":"traceutil/trace.go:171","msg":"trace[998275037] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"132.282591ms","start":"2026-04-20T08:11:54.22153Z","end":"2026-04-20T08:11:54.353813Z","steps":["trace[998275037] 'process raft request' (duration: 131.987093ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:11:54.571184Z","caller":"traceutil/trace.go:171","msg":"trace[1507579706] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"135.250857ms","start":"2026-04-20T08:11:54.435892Z","end":"2026-04-20T08:11:54.571143Z","steps":["trace[1507579706] 'process raft request' (duration: 99.424666ms)","trace[1507579706] 'compare' (duration: 35.669027ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:11:54.572417Z","caller":"traceutil/trace.go:171","msg":"trace[539256765] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"118.655948ms","start":"2026-04-20T08:11:54.453741Z","end":"2026-04-20T08:11:54.572397Z","steps":["trace[539256765] 'process raft request' (duration: 117.90441ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:00.70739Z","caller":"traceutil/trace.go:171","msg":"trace[782076762] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"108.02321ms","start":"2026-04-20T08:12:00.599338Z","end":"2026-04-20T08:12:00.707361Z","steps":["trace[782076762] 'read index received' (duration: 107.98995ms)","trace[782076762] 'applied index is now lower than readState.Index' (duration: 32.21µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:12:00.707616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.270836ms","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-04-20T08:12:00.707668Z","caller":"traceutil/trace.go:171","msg":"trace[1556938009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"108.333037ms","start":"2026-04-20T08:12:00.599319Z","end":"2026-04-20T08:12:00.707652Z","steps":["trace[1556938009] 'agreement among raft nodes before linearized reading' (duration: 108.161913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:00.707768Z","caller":"traceutil/trace.go:171","msg":"trace[579691655] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"318.224702ms","start":"2026-04-20T08:12:00.38952Z","end":"2026-04-20T08:12:00.707744Z","steps":["trace[579691655] 'process raft request' (duration: 317.67915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:00.708591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:12:00.389487Z","time spent":"318.418987ms","remote":"127.0.0.1:40472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:12:00.926128Z","caller":"traceutil/trace.go:171","msg":"trace[357379258] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"293.666962ms","start":"2026-04-20T08:12:00.632433Z","end":"2026-04-20T08:12:00.9261Z","steps":["trace[357379258] 'process raft request' (duration: 290.680643ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:12:00.928968Z","caller":"traceutil/trace.go:171","msg":"trace[1407227135] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"216.750326ms","start":"2026-04-20T08:12:00.712162Z","end":"2026-04-20T08:12:00.928912Z","steps":["trace[1407227135] 'process raft request' (duration: 216.612362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:01.530887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.784587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:12:01.531061Z","caller":"traceutil/trace.go:171","msg":"trace[78861630] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"548.999142ms","start":"2026-04-20T08:12:00.982022Z","end":"2026-04-20T08:12:01.531021Z","steps":["trace[78861630] 'process raft request' (duration: 369.013851ms)","trace[78861630] 'compare' (duration: 179.670043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:12:01.531194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:12:00.982002Z","time spent":"549.131585ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:12:17.372235Z","caller":"traceutil/trace.go:171","msg":"trace[1970424154] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"358.871316ms","start":"2026-04-20T08:12:17.013336Z","end":"2026-04-20T08:12:17.372207Z","steps":["trace[1970424154] 'read index received' (duration: 358.688462ms)","trace[1970424154] 'applied index is now lower than readState.Index' (duration: 182.154µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:12:17.372453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.098091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-04-20T08:12:17.372495Z","caller":"traceutil/trace.go:171","msg":"trace[1080258511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"359.197644ms","start":"2026-04-20T08:12:17.013285Z","end":"2026-04-20T08:12:17.372482Z","steps":["trace[1080258511] 'agreement among raft nodes before linearized reading' (duration: 359.03549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:17.372529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:12:17.013264Z","time spent":"359.257614ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T08:12:17.372615Z","caller":"traceutil/trace.go:171","msg":"trace[622014104] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"387.170604ms","start":"2026-04-20T08:12:16.985407Z","end":"2026-04-20T08:12:17.372578Z","steps":["trace[622014104] 'process raft request' (duration: 386.676001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:17.372779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:12:16.985386Z","time spent":"387.304057ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:12:22.319639Z","caller":"traceutil/trace.go:171","msg":"trace[1812498114] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"170.880219ms","start":"2026-04-20T08:12:22.148729Z","end":"2026-04-20T08:12:22.319609Z","steps":["trace[1812498114] 'read index received' (duration: 129.393246ms)","trace[1812498114] 'applied index is now lower than readState.Index' (duration: 41.485483ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:12:22.31984Z","caller":"traceutil/trace.go:171","msg":"trace[653939247] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"189.593593ms","start":"2026-04-20T08:12:22.130158Z","end":"2026-04-20T08:12:22.319751Z","steps":["trace[653939247] 'process raft request' (duration: 148.051839ms)","trace[653939247] 'compare' (duration: 41.240207ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:12:22.32006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.5876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T08:12:22.32012Z","caller":"traceutil/trace.go:171","msg":"trace[665762098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"138.682252ms","start":"2026-04-20T08:12:22.181422Z","end":"2026-04-20T08:12:22.320104Z","steps":["trace[665762098] 'agreement among raft nodes before linearized reading' (duration: 138.446757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:22.320122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.468647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T08:12:22.320214Z","caller":"traceutil/trace.go:171","msg":"trace[837120468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:843; }","duration":"138.57926ms","start":"2026-04-20T08:12:22.181611Z","end":"2026-04-20T08:12:22.32019Z","steps":["trace[837120468] 'agreement among raft nodes before linearized reading' (duration: 138.280203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:22.320475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.72271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-04-20T08:12:22.320579Z","caller":"traceutil/trace.go:171","msg":"trace[1941393832] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:843; }","duration":"171.871763ms","start":"2026-04-20T08:12:22.148682Z","end":"2026-04-20T08:12:22.320554Z","steps":["trace[1941393832] 'agreement among raft nodes before linearized reading' (duration: 171.177267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:46.002135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.598523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:12:46.00224Z","caller":"traceutil/trace.go:171","msg":"trace[1516836148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1184; }","duration":"167.758967ms","start":"2026-04-20T08:12:45.83446Z","end":"2026-04-20T08:12:46.002219Z","steps":["trace[1516836148] 'range keys from in-memory index tree' (duration: 167.46696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:46.00216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.577615ms","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-04-20T08:12:46.002474Z","caller":"traceutil/trace.go:171","msg":"trace[2141777493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"391.938763ms","start":"2026-04-20T08:12:45.610513Z","end":"2026-04-20T08:12:46.002452Z","steps":["trace[2141777493] 'range keys from in-memory index tree' (duration: 391.442292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:12:46.002533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:12:45.610494Z","time spent":"392.023395ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T08:12:46.002573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.037769ms","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-04-20T08:12:46.002647Z","caller":"traceutil/trace.go:171","msg":"trace[707827075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1184; }","duration":"195.157553ms","start":"2026-04-20T08:12:45.80747Z","end":"2026-04-20T08:12:46.002628Z","steps":["trace[707827075] 'range keys from in-memory index tree' (duration: 194.822215ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:01.204765Z","caller":"traceutil/trace.go:171","msg":"trace[1835832044] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"137.281503ms","start":"2026-04-20T08:13:01.06744Z","end":"2026-04-20T08:13:01.204722Z","steps":["trace[1835832044] 'process raft request' (duration: 137.015177ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:32.227063Z","caller":"traceutil/trace.go:171","msg":"trace[2078171997] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"237.315138ms","start":"2026-04-20T08:13:31.989721Z","end":"2026-04-20T08:13:32.227036Z","steps":["trace[2078171997] 'read index received' (duration: 237.141584ms)","trace[2078171997] 'applied index is now lower than readState.Index' (duration: 172.184µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:32.227152Z","caller":"traceutil/trace.go:171","msg":"trace[1670124815] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"264.639186ms","start":"2026-04-20T08:13:31.962451Z","end":"2026-04-20T08:13:32.22709Z","steps":["trace[1670124815] 'process raft request' (duration: 264.413931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:32.227374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.571403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-30715905-529b-4dfe-9dad-17273ca545f2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T08:13:32.227422Z","caller":"traceutil/trace.go:171","msg":"trace[1623719609] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-30715905-529b-4dfe-9dad-17273ca545f2; range_end:; response_count:1; response_revision:1861; }","duration":"237.724696ms","start":"2026-04-20T08:13:31.989685Z","end":"2026-04-20T08:13:32.227409Z","steps":["trace[1623719609] 'agreement among raft nodes before linearized reading' (duration: 237.500131ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:32.451246Z","caller":"traceutil/trace.go:171","msg":"trace[1265518131] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"442.475454ms","start":"2026-04-20T08:13:32.008741Z","end":"2026-04-20T08:13:32.451216Z","steps":["trace[1265518131] 'process raft request' (duration: 435.334599ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:32.451452Z","caller":"traceutil/trace.go:171","msg":"trace[1095174206] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"343.886792ms","start":"2026-04-20T08:13:32.107543Z","end":"2026-04-20T08:13:32.45143Z","steps":["trace[1095174206] 'process raft request' (duration: 343.574345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:32.451496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.219612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T08:13:32.451459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:32.008713Z","time spent":"442.645618ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:13:32.451696Z","caller":"traceutil/trace.go:171","msg":"trace[847400573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1863; }","duration":"193.471968ms","start":"2026-04-20T08:13:32.258205Z","end":"2026-04-20T08:13:32.451677Z","steps":["trace[847400573] 'agreement among raft nodes before linearized reading' (duration: 193.13743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:32.451576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:32.107504Z","time spent":"344.016136ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:13:32.451278Z","caller":"traceutil/trace.go:171","msg":"trace[370899482] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1937; }","duration":"192.986956ms","start":"2026-04-20T08:13:32.258263Z","end":"2026-04-20T08:13:32.45125Z","steps":["trace[370899482] 'read index received' (duration: 185.819702ms)","trace[370899482] 'applied index is now lower than readState.Index' (duration: 7.166104ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:13:32.452348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.811964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-20T08:13:32.452396Z","caller":"traceutil/trace.go:171","msg":"trace[1368816587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1863; }","duration":"186.881886ms","start":"2026-04-20T08:13:32.265501Z","end":"2026-04-20T08:13:32.452383Z","steps":["trace[1368816587] 'agreement among raft nodes before linearized reading' (duration: 186.739083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:32.452499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.08875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:13:32.452553Z","caller":"traceutil/trace.go:171","msg":"trace[2023299782] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1863; }","duration":"144.184533ms","start":"2026-04-20T08:13:32.308355Z","end":"2026-04-20T08:13:32.45254Z","steps":["trace[2023299782] 'agreement among raft nodes before linearized reading' (duration: 144.0868ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:32.959876Z","caller":"traceutil/trace.go:171","msg":"trace[1512777746] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"279.276204ms","start":"2026-04-20T08:13:32.680575Z","end":"2026-04-20T08:13:32.959851Z","steps":["trace[1512777746] 'read index received' (duration: 264.455892ms)","trace[1512777746] 'applied index is now lower than readState.Index' (duration: 14.818562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:13:32.960124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.52317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T08:13:32.960194Z","caller":"traceutil/trace.go:171","msg":"trace[363813785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"279.671563ms","start":"2026-04-20T08:13:32.680508Z","end":"2026-04-20T08:13:32.96018Z","steps":["trace[363813785] 'agreement among raft nodes before linearized reading' (duration: 279.434007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:32.960212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.460539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T08:13:32.960301Z","caller":"traceutil/trace.go:171","msg":"trace[61454871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"279.601902ms","start":"2026-04-20T08:13:32.680676Z","end":"2026-04-20T08:13:32.960278Z","steps":["trace[61454871] 'agreement among raft nodes before linearized reading' (duration: 279.364726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:32.960319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.244903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-30715905-529b-4dfe-9dad-17273ca545f2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T08:13:32.960435Z","caller":"traceutil/trace.go:171","msg":"trace[1990469787] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-30715905-529b-4dfe-9dad-17273ca545f2; range_end:; response_count:1; response_revision:1865; }","duration":"111.420937ms","start":"2026-04-20T08:13:32.848987Z","end":"2026-04-20T08:13:32.960408Z","steps":["trace[1990469787] 'agreement among raft nodes before linearized reading' (duration: 111.075819ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:44.631056Z","caller":"traceutil/trace.go:171","msg":"trace[1521757392] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2036; }","duration":"126.715909ms","start":"2026-04-20T08:13:44.504316Z","end":"2026-04-20T08:13:44.631032Z","steps":["trace[1521757392] 'read index received' (duration: 57.107006ms)","trace[1521757392] 'applied index is now lower than readState.Index' (duration: 69.607853ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:44.631193Z","caller":"traceutil/trace.go:171","msg":"trace[1078905209] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"131.557671ms","start":"2026-04-20T08:13:44.499623Z","end":"2026-04-20T08:13:44.63118Z","steps":["trace[1078905209] 'process raft request' (duration: 126.977775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:44.631441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.37867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T08:13:44.631484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.144899ms","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-04-20T08:13:44.631541Z","caller":"traceutil/trace.go:171","msg":"trace[468575465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1958; }","duration":"116.523763ms","start":"2026-04-20T08:13:44.514993Z","end":"2026-04-20T08:13:44.631517Z","steps":["trace[468575465] 'agreement among raft nodes before linearized reading' (duration: 116.248647ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:44.631594Z","caller":"traceutil/trace.go:171","msg":"trace[1373961267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1958; }","duration":"127.302722ms","start":"2026-04-20T08:13:44.504248Z","end":"2026-04-20T08:13:44.63155Z","steps":["trace[1373961267] 'agreement among raft nodes before linearized reading' (duration: 127.053546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:48.131261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.187736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:13:48.131373Z","caller":"traceutil/trace.go:171","msg":"trace[1853130454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1990; }","duration":"297.3471ms","start":"2026-04-20T08:13:47.834Z","end":"2026-04-20T08:13:48.131347Z","steps":["trace[1853130454] 'range keys from in-memory index tree' (duration: 297.102355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:48.131369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.100183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T08:13:48.131444Z","caller":"traceutil/trace.go:171","msg":"trace[829641448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1990; }","duration":"234.220885ms","start":"2026-04-20T08:13:47.897204Z","end":"2026-04-20T08:13:48.131425Z","steps":["trace[829641448] 'range keys from in-memory index tree' (duration: 233.925418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:48.131668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.008906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T08:13:48.131767Z","caller":"traceutil/trace.go:171","msg":"trace[539026350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1990; }","duration":"479.149838ms","start":"2026-04-20T08:13:47.652595Z","end":"2026-04-20T08:13:48.131745Z","steps":["trace[539026350] 'range keys from in-memory index tree' (duration: 478.80491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:13:48.131828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:13:47.652582Z","time spent":"479.236461ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-20T08:14:21.341549Z","caller":"traceutil/trace.go:171","msg":"trace[1567328523] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"103.969595ms","start":"2026-04-20T08:14:21.237538Z","end":"2026-04-20T08:14:21.341508Z","steps":["trace[1567328523] 'process raft request' (duration: 103.75394ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:21.47361Z","caller":"traceutil/trace.go:171","msg":"trace[1168856819] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"128.238874ms","start":"2026-04-20T08:14:21.345314Z","end":"2026-04-20T08:14:21.473553Z","steps":["trace[1168856819] 'process raft request' (duration: 115.50205ms)","trace[1168856819] 'compare' (duration: 12.578609ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:27.059895Z","caller":"traceutil/trace.go:171","msg":"trace[1058215812] linearizableReadLoop","detail":"{readStateIndex:2295; appliedIndex:2294; }","duration":"135.519643ms","start":"2026-04-20T08:14:26.924355Z","end":"2026-04-20T08:14:27.059874Z","steps":["trace[1058215812] 'read index received' (duration: 135.332039ms)","trace[1058215812] 'applied index is now lower than readState.Index' (duration: 186.814µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:27.059991Z","caller":"traceutil/trace.go:171","msg":"trace[1058445975] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"181.118623ms","start":"2026-04-20T08:14:26.878829Z","end":"2026-04-20T08:14:27.059948Z","steps":["trace[1058445975] 'process raft request' (duration: 180.914838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:27.060246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.880911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T08:14:27.060244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.836792ms","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-04-20T08:14:27.060341Z","caller":"traceutil/trace.go:171","msg":"trace[1305031024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2207; }","duration":"136.023604ms","start":"2026-04-20T08:14:26.924275Z","end":"2026-04-20T08:14:27.060299Z","steps":["trace[1305031024] 'agreement among raft nodes before linearized reading' (duration: 135.701716ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:27.060363Z","caller":"traceutil/trace.go:171","msg":"trace[226445488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2207; }","duration":"104.011266ms","start":"2026-04-20T08:14:26.956342Z","end":"2026-04-20T08:14:27.060353Z","steps":["trace[226445488] 'agreement among raft nodes before linearized reading' (duration: 103.666108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:27.438358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.111843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:14:27.438738Z","caller":"traceutil/trace.go:171","msg":"trace[980356569] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"373.31626ms","start":"2026-04-20T08:14:27.065294Z","end":"2026-04-20T08:14:27.438611Z","steps":["trace[980356569] 'process raft request' (duration: 191.857049ms)","trace[980356569] 'compare' (duration: 180.720964ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:27.438877Z","caller":"traceutil/trace.go:171","msg":"trace[486963155] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"373.286159ms","start":"2026-04-20T08:14:27.065529Z","end":"2026-04-20T08:14:27.438816Z","steps":["trace[486963155] 'process raft request' (duration: 372.941941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:27.439054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:27.065498Z","time spent":"373.468083ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:14:27.439113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:27.065264Z","time spent":"373.601536ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:14:27.860466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.003105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:14:27.86067Z","caller":"traceutil/trace.go:171","msg":"trace[1498630355] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"481.81367ms","start":"2026-04-20T08:14:27.378838Z","end":"2026-04-20T08:14:27.860651Z","steps":["trace[1498630355] 'process raft request' (duration: 374.537908ms)","trace[1498630355] 'compare' (duration: 106.863222ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:14:27.860743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:27.378814Z","time spent":"481.899181ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:14:27.861246Z","caller":"traceutil/trace.go:171","msg":"trace[1240640931] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2297; }","duration":"372.098513ms","start":"2026-04-20T08:14:27.48911Z","end":"2026-04-20T08:14:27.861208Z","steps":["trace[1240640931] 'read index received' (duration: 264.239037ms)","trace[1240640931] 'applied index is now lower than readState.Index' (duration: 107.858386ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:27.861351Z","caller":"traceutil/trace.go:171","msg":"trace[1775247357] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"250.549403ms","start":"2026-04-20T08:14:27.610788Z","end":"2026-04-20T08:14:27.861337Z","steps":["trace[1775247357] 'process raft request' (duration: 250.279056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:27.861347Z","caller":"traceutil/trace.go:171","msg":"trace[549813221] transaction","detail":"{read_only:false; response_revision:2211; number_of_response:1; }","duration":"418.147233ms","start":"2026-04-20T08:14:27.443135Z","end":"2026-04-20T08:14:27.861282Z","steps":["trace[549813221] 'process raft request' (duration: 417.434267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:27.861492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.379349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.203\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T08:14:27.861531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.345108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T08:14:27.861539Z","caller":"traceutil/trace.go:171","msg":"trace[1355804555] range","detail":"{range_begin:/registry/masterleases/199.19.213.203; range_end:; response_count:1; response_revision:2212; }","duration":"372.476801ms","start":"2026-04-20T08:14:27.48905Z","end":"2026-04-20T08:14:27.861527Z","steps":["trace[1355804555] 'agreement among raft nodes before linearized reading' (duration: 372.319007ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:27.861562Z","caller":"traceutil/trace.go:171","msg":"trace[1575890294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2212; }","duration":"362.407099ms","start":"2026-04-20T08:14:27.499146Z","end":"2026-04-20T08:14:27.861553Z","steps":["trace[1575890294] 'agreement among raft nodes before linearized reading' (duration: 362.296747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:27.861582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:27.489026Z","time spent":"372.548423ms","remote":"127.0.0.1:40302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.203\" "} {"level":"warn","ts":"2026-04-20T08:14:27.861637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:27.443118Z","time spent":"418.309298ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:14:27.861667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.349047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T08:14:27.861711Z","caller":"traceutil/trace.go:171","msg":"trace[288973457] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2212; }","duration":"342.417558ms","start":"2026-04-20T08:14:27.519284Z","end":"2026-04-20T08:14:27.861701Z","steps":["trace[288973457] 'agreement among raft nodes before linearized reading' (duration: 342.324116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:27.861736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:27.519267Z","time spent":"342.4635ms","remote":"127.0.0.1:34734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T08:14:27.861591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:14:27.499132Z","time spent":"362.45323ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T08:15:14.253827Z","caller":"traceutil/trace.go:171","msg":"trace[761530404] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"228.697727ms","start":"2026-04-20T08:15:14.025102Z","end":"2026-04-20T08:15:14.2538Z","steps":["trace[761530404] 'process raft request' (duration: 228.537983ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:14.255179Z","caller":"traceutil/trace.go:171","msg":"trace[986321321] linearizableReadLoop","detail":"{readStateIndex:2740; appliedIndex:2738; }","duration":"174.476073ms","start":"2026-04-20T08:15:14.080681Z","end":"2026-04-20T08:15:14.255157Z","steps":["trace[986321321] 'read index received' (duration: 172.981739ms)","trace[986321321] 'applied index is now lower than readState.Index' (duration: 1.493304ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:14.255243Z","caller":"traceutil/trace.go:171","msg":"trace[784481683] transaction","detail":"{read_only:false; response_revision:2634; number_of_response:1; }","duration":"223.373247ms","start":"2026-04-20T08:15:14.031845Z","end":"2026-04-20T08:15:14.255218Z","steps":["trace[784481683] 'process raft request' (duration: 223.186422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:14.255378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.674828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T08:15:14.255409Z","caller":"traceutil/trace.go:171","msg":"trace[1573635890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2634; }","duration":"174.76347ms","start":"2026-04-20T08:15:14.080638Z","end":"2026-04-20T08:15:14.255401Z","steps":["trace[1573635890] 'agreement among raft nodes before linearized reading' (duration: 174.624607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:14.25548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.619576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-20T08:15:14.255543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.127865ms","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-04-20T08:15:14.255568Z","caller":"traceutil/trace.go:171","msg":"trace[2135705731] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2634; }","duration":"127.209646ms","start":"2026-04-20T08:15:14.12835Z","end":"2026-04-20T08:15:14.25556Z","steps":["trace[2135705731] 'agreement among raft nodes before linearized reading' (duration: 127.162875ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:14.255564Z","caller":"traceutil/trace.go:171","msg":"trace[728681370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2634; }","duration":"174.7811ms","start":"2026-04-20T08:15:14.080764Z","end":"2026-04-20T08:15:14.255545Z","steps":["trace[728681370] 'agreement among raft nodes before linearized reading' (duration: 174.585176ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:48.002627Z","caller":"traceutil/trace.go:171","msg":"trace[1634693001] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"178.748961ms","start":"2026-04-20T08:15:47.823848Z","end":"2026-04-20T08:15:48.002597Z","steps":["trace[1634693001] 'process raft request' (duration: 178.573497ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:48.017055Z","caller":"traceutil/trace.go:171","msg":"trace[1457389893] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"190.666852ms","start":"2026-04-20T08:15:47.826352Z","end":"2026-04-20T08:15:48.017019Z","steps":["trace[1457389893] 'process raft request' (duration: 190.478747ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:48.494083Z","caller":"traceutil/trace.go:171","msg":"trace[338621848] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"103.562609ms","start":"2026-04-20T08:15:48.390469Z","end":"2026-04-20T08:15:48.494032Z","steps":["trace[338621848] 'process raft request' (duration: 103.406575ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:48.521153Z","caller":"traceutil/trace.go:171","msg":"trace[771571435] linearizableReadLoop","detail":"{readStateIndex:2953; appliedIndex:2952; }","duration":"106.228189ms","start":"2026-04-20T08:15:48.414905Z","end":"2026-04-20T08:15:48.521134Z","steps":["trace[771571435] 'read index received' (duration: 79.759806ms)","trace[771571435] 'applied index is now lower than readState.Index' (duration: 26.467403ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:48.521243Z","caller":"traceutil/trace.go:171","msg":"trace[1340382280] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"127.645697ms","start":"2026-04-20T08:15:48.393565Z","end":"2026-04-20T08:15:48.521211Z","steps":["trace[1340382280] 'process raft request' (duration: 127.484103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:15:48.521403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.475044ms","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-04-20T08:15:48.521455Z","caller":"traceutil/trace.go:171","msg":"trace[2066922920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2839; }","duration":"106.605098ms","start":"2026-04-20T08:15:48.414837Z","end":"2026-04-20T08:15:48.521442Z","steps":["trace[2066922920] 'agreement among raft nodes before linearized reading' (duration: 106.415604ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:48.635386Z","caller":"traceutil/trace.go:171","msg":"trace[328815132] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"109.917923ms","start":"2026-04-20T08:15:48.525445Z","end":"2026-04-20T08:15:48.635363Z","steps":["trace[328815132] 'process raft request' (duration: 109.783779ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:15:48.636434Z","caller":"traceutil/trace.go:171","msg":"trace[283939219] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"109.77345ms","start":"2026-04-20T08:15:48.526637Z","end":"2026-04-20T08:15:48.63641Z","steps":["trace[283939219] 'process raft request' (duration: 109.644507ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:18:23.815548Z","caller":"traceutil/trace.go:171","msg":"trace[11547247] transaction","detail":"{read_only:false; response_revision:3858; number_of_response:1; }","duration":"157.903698ms","start":"2026-04-20T08:18:23.65762Z","end":"2026-04-20T08:18:23.815523Z","steps":["trace[11547247] 'process raft request' (duration: 157.753814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:18:24.117894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.551823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:18:24.11812Z","caller":"traceutil/trace.go:171","msg":"trace[1103335160] transaction","detail":"{read_only:false; response_revision:3860; number_of_response:1; }","duration":"223.775316ms","start":"2026-04-20T08:18:23.894331Z","end":"2026-04-20T08:18:24.118106Z","steps":["trace[1103335160] 'process raft request' (duration: 223.725585ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:18:24.11813Z","caller":"traceutil/trace.go:171","msg":"trace[1976312620] transaction","detail":"{read_only:false; response_revision:3859; number_of_response:1; }","duration":"250.429998ms","start":"2026-04-20T08:18:23.867657Z","end":"2026-04-20T08:18:24.118087Z","steps":["trace[1976312620] 'process raft request' (duration: 36.62865ms)","trace[1976312620] 'compare' (duration: 213.328557ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:18:24.11845Z","caller":"traceutil/trace.go:171","msg":"trace[1372976332] linearizableReadLoop","detail":"{readStateIndex:4011; appliedIndex:4010; }","duration":"248.709018ms","start":"2026-04-20T08:18:23.86951Z","end":"2026-04-20T08:18:24.118219Z","steps":["trace[1372976332] 'read index received' (duration: 34.815148ms)","trace[1372976332] 'applied index is now lower than readState.Index' (duration: 213.89063ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:18:24.118691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.166588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vwq6p\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T08:18:24.118766Z","caller":"traceutil/trace.go:171","msg":"trace[1667319065] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vwq6p; range_end:; response_count:1; response_revision:3860; }","duration":"249.291561ms","start":"2026-04-20T08:18:23.869453Z","end":"2026-04-20T08:18:24.118744Z","steps":["trace[1667319065] 'agreement among raft nodes before linearized reading' (duration: 249.041155ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:38.771892Z","caller":"traceutil/trace.go:171","msg":"trace[1592682573] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"151.408864ms","start":"2026-04-20T08:19:38.620469Z","end":"2026-04-20T08:19:38.771878Z","steps":["trace[1592682573] 'process raft request' (duration: 151.290561ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:38.771965Z","caller":"traceutil/trace.go:171","msg":"trace[832683974] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"151.501756ms","start":"2026-04-20T08:19:38.620438Z","end":"2026-04-20T08:19:38.771939Z","steps":["trace[832683974] 'process raft request' (duration: 151.23348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:38.772008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.513356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-20T08:19:38.772047Z","caller":"traceutil/trace.go:171","msg":"trace[562128238] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4731; }","duration":"151.563247ms","start":"2026-04-20T08:19:38.620476Z","end":"2026-04-20T08:19:38.772039Z","steps":["trace[562128238] 'agreement among raft nodes before linearized reading' (duration: 151.427334ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:38.771884Z","caller":"traceutil/trace.go:171","msg":"trace[317672122] linearizableReadLoop","detail":"{readStateIndex:4916; appliedIndex:4914; }","duration":"151.387304ms","start":"2026-04-20T08:19:38.620479Z","end":"2026-04-20T08:19:38.771866Z","steps":["trace[317672122] 'read index received' (duration: 43.790495ms)","trace[317672122] 'applied index is now lower than readState.Index' (duration: 107.594359ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:19:38.772242Z","caller":"traceutil/trace.go:171","msg":"trace[1608903726] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"150.993755ms","start":"2026-04-20T08:19:38.62124Z","end":"2026-04-20T08:19:38.772234Z","steps":["trace[1608903726] 'process raft request' (duration: 150.574285ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:42.903978Z","caller":"traceutil/trace.go:171","msg":"trace[1916423167] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"104.048356ms","start":"2026-04-20T08:19:42.799889Z","end":"2026-04-20T08:19:42.903937Z","steps":["trace[1916423167] 'process raft request' (duration: 99.746598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:42.904159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.680074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-wwgh9-tls-zvfp4\" ","response":"range_response_count:1 size:2625"} {"level":"info","ts":"2026-04-20T08:19:42.904246Z","caller":"traceutil/trace.go:171","msg":"trace[1752592348] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-wwgh9-tls-zvfp4; range_end:; response_count:1; response_revision:4854; }","duration":"105.840448ms","start":"2026-04-20T08:19:42.798386Z","end":"2026-04-20T08:19:42.904226Z","steps":["trace[1752592348] 'agreement among raft nodes before linearized reading' (duration: 105.588332ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:44.015344Z","caller":"traceutil/trace.go:171","msg":"trace[1349813196] linearizableReadLoop","detail":"{readStateIndex:5073; appliedIndex:5072; }","duration":"139.692438ms","start":"2026-04-20T08:19:43.875622Z","end":"2026-04-20T08:19:44.015315Z","steps":["trace[1349813196] 'read index received' (duration: 139.493482ms)","trace[1349813196] 'applied index is now lower than readState.Index' (duration: 197.276µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:19:44.015414Z","caller":"traceutil/trace.go:171","msg":"trace[141853805] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"151.979427ms","start":"2026-04-20T08:19:43.863395Z","end":"2026-04-20T08:19:44.015374Z","steps":["trace[141853805] 'process raft request' (duration: 151.69289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:44.015594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.951253ms","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-04-20T08:19:44.015652Z","caller":"traceutil/trace.go:171","msg":"trace[1669086532] 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:4883; }","duration":"140.064416ms","start":"2026-04-20T08:19:43.875572Z","end":"2026-04-20T08:19:44.015637Z","steps":["trace[1669086532] 'agreement among raft nodes before linearized reading' (duration: 139.870982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:46.644093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.715621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vwq6p\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T08:19:46.644206Z","caller":"traceutil/trace.go:171","msg":"trace[638482083] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vwq6p; range_end:; response_count:1; response_revision:5011; }","duration":"111.902095ms","start":"2026-04-20T08:19:46.532282Z","end":"2026-04-20T08:19:46.644184Z","steps":["trace[638482083] 'range keys from in-memory index tree' (duration: 111.487207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:48.99011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.479361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:19:48.990198Z","caller":"traceutil/trace.go:171","msg":"trace[89599866] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5053; }","duration":"128.588654ms","start":"2026-04-20T08:19:48.861587Z","end":"2026-04-20T08:19:48.990176Z","steps":["trace[89599866] 'count revisions from in-memory index tree' (duration: 128.39373ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:58.172574Z","caller":"traceutil/trace.go:171","msg":"trace[199395887] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"118.517716ms","start":"2026-04-20T08:19:58.054026Z","end":"2026-04-20T08:19:58.172544Z","steps":["trace[199395887] 'process raft request' (duration: 118.25001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:19:58.217833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.305803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T08:19:58.217934Z","caller":"traceutil/trace.go:171","msg":"trace[569663998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5240; }","duration":"115.456066ms","start":"2026-04-20T08:19:58.102455Z","end":"2026-04-20T08:19:58.217911Z","steps":["trace[569663998] 'agreement among raft nodes before linearized reading' (duration: 115.1911ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:19:58.339918Z","caller":"traceutil/trace.go:171","msg":"trace[847549154] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"109.107833ms","start":"2026-04-20T08:19:58.230779Z","end":"2026-04-20T08:19:58.339887Z","steps":["trace[847549154] 'process raft request' (duration: 71.458356ms)","trace[847549154] 'compare' (duration: 36.91817ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:19:58.340169Z","caller":"traceutil/trace.go:171","msg":"trace[1653214519] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"108.52064ms","start":"2026-04-20T08:19:58.231637Z","end":"2026-04-20T08:19:58.340158Z","steps":["trace[1653214519] 'process raft request' (duration: 107.65641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:20:06.491927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.784506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-20T08:20:06.492049Z","caller":"traceutil/trace.go:171","msg":"trace[1690147605] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5416; }","duration":"163.952701ms","start":"2026-04-20T08:20:06.328069Z","end":"2026-04-20T08:20:06.492021Z","steps":["trace[1690147605] 'range keys from in-memory index tree' (duration: 163.55446ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:07.055314Z","caller":"traceutil/trace.go:171","msg":"trace[1384139738] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"118.495356ms","start":"2026-04-20T08:20:06.936793Z","end":"2026-04-20T08:20:07.055288Z","steps":["trace[1384139738] 'process raft request' (duration: 118.341852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:20:17.61882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.059862ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:20:17.619034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.922064ms","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-04-20T08:20:17.619144Z","caller":"traceutil/trace.go:171","msg":"trace[1831157706] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5580; }","duration":"124.291848ms","start":"2026-04-20T08:20:17.49471Z","end":"2026-04-20T08:20:17.619002Z","steps":["trace[1831157706] 'range keys from in-memory index tree' (duration: 124.035002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:20:17.619229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.306625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T08:20:17.619263Z","caller":"traceutil/trace.go:171","msg":"trace[735700889] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"183.308391ms","start":"2026-04-20T08:20:17.435921Z","end":"2026-04-20T08:20:17.619229Z","steps":["trace[735700889] 'process raft request' (duration: 128.253268ms)","trace[735700889] 'compare' (duration: 54.773166ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:20:17.619298Z","caller":"traceutil/trace.go:171","msg":"trace[1042544951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5580; }","duration":"225.424588ms","start":"2026-04-20T08:20:17.393858Z","end":"2026-04-20T08:20:17.619283Z","steps":["trace[1042544951] 'range keys from in-memory index tree' (duration: 225.067979ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:17.619184Z","caller":"traceutil/trace.go:171","msg":"trace[1062320361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5580; }","duration":"235.147779ms","start":"2026-04-20T08:20:17.384012Z","end":"2026-04-20T08:20:17.61916Z","steps":["trace[1062320361] 'range keys from in-memory index tree' (duration: 234.646218ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:17.619403Z","caller":"traceutil/trace.go:171","msg":"trace[1818356996] linearizableReadLoop","detail":"{readStateIndex:5788; appliedIndex:5786; }","duration":"111.557058ms","start":"2026-04-20T08:20:17.50781Z","end":"2026-04-20T08:20:17.619367Z","steps":["trace[1818356996] 'read index received' (duration: 56.782282ms)","trace[1818356996] 'applied index is now lower than readState.Index' (duration: 54.771386ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:20:17.619475Z","caller":"traceutil/trace.go:171","msg":"trace[2070276789] transaction","detail":"{read_only:false; response_revision:5582; number_of_response:1; }","duration":"143.24676ms","start":"2026-04-20T08:20:17.476197Z","end":"2026-04-20T08:20:17.619443Z","steps":["trace[2070276789] 'process raft request' (duration: 142.972473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:20:17.619623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.804214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.203\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T08:20:17.61967Z","caller":"traceutil/trace.go:171","msg":"trace[1932338017] range","detail":"{range_begin:/registry/masterleases/199.19.213.203; range_end:; response_count:1; response_revision:5582; }","duration":"111.909696ms","start":"2026-04-20T08:20:17.507749Z","end":"2026-04-20T08:20:17.619659Z","steps":["trace[1932338017] 'agreement among raft nodes before linearized reading' (duration: 111.712422ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:57.635255Z","caller":"traceutil/trace.go:171","msg":"trace[377568599] linearizableReadLoop","detail":"{readStateIndex:6134; appliedIndex:6133; }","duration":"105.983546ms","start":"2026-04-20T08:20:57.529244Z","end":"2026-04-20T08:20:57.635228Z","steps":["trace[377568599] 'read index received' (duration: 93.607064ms)","trace[377568599] 'applied index is now lower than readState.Index' (duration: 12.375412ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:20:57.635315Z","caller":"traceutil/trace.go:171","msg":"trace[1926906313] transaction","detail":"{read_only:false; response_revision:5917; number_of_response:1; }","duration":"110.097932ms","start":"2026-04-20T08:20:57.525179Z","end":"2026-04-20T08:20:57.635277Z","steps":["trace[1926906313] 'process raft request' (duration: 97.726368ms)","trace[1926906313] 'compare' (duration: 12.177688ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:20:57.635548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.280734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-20T08:20:57.635596Z","caller":"traceutil/trace.go:171","msg":"trace[630939125] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5917; }","duration":"106.362696ms","start":"2026-04-20T08:20:57.529223Z","end":"2026-04-20T08:20:57.635586Z","steps":["trace[630939125] 'agreement among raft nodes before linearized reading' (duration: 106.151611ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:21:15.159443Z","caller":"traceutil/trace.go:171","msg":"trace[2032406926] linearizableReadLoop","detail":"{readStateIndex:6274; appliedIndex:6273; }","duration":"132.223688ms","start":"2026-04-20T08:21:15.027194Z","end":"2026-04-20T08:21:15.159418Z","steps":["trace[2032406926] 'read index received' (duration: 129.599547ms)","trace[2032406926] 'applied index is now lower than readState.Index' (duration: 2.623371ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:21:15.159587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.377551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:21:15.159618Z","caller":"traceutil/trace.go:171","msg":"trace[1627600127] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:6054; }","duration":"132.465193ms","start":"2026-04-20T08:21:15.027143Z","end":"2026-04-20T08:21:15.159609Z","steps":["trace[1627600127] 'agreement among raft nodes before linearized reading' (duration: 132.368141ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:21:15.159844Z","caller":"traceutil/trace.go:171","msg":"trace[401854201] transaction","detail":"{read_only:false; response_revision:6054; number_of_response:1; }","duration":"174.186408ms","start":"2026-04-20T08:21:14.985628Z","end":"2026-04-20T08:21:15.159815Z","steps":["trace[401854201] 'process raft request' (duration: 171.141019ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:21:24.437817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3035} {"level":"info","ts":"2026-04-20T08:21:24.524291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3035,"took":"84.824412ms","hash":306022950,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":31662080,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-20T08:21:24.524366Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":306022950,"revision":3035,"compact-revision":-1} {"level":"info","ts":"2026-04-20T08:26:24.444592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6125} {"level":"info","ts":"2026-04-20T08:26:24.546556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6125,"took":"100.35034ms","hash":1672503901,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":23355392,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-20T08:26:24.546636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1672503901,"revision":6125,"compact-revision":3035} {"level":"info","ts":"2026-04-20T08:26:26.334357Z","caller":"traceutil/trace.go:171","msg":"trace[936852568] linearizableReadLoop","detail":"{readStateIndex:8625; appliedIndex:8624; }","duration":"174.984636ms","start":"2026-04-20T08:26:26.159356Z","end":"2026-04-20T08:26:26.33434Z","steps":["trace[936852568] 'read index received' (duration: 174.853852ms)","trace[936852568] 'applied index is now lower than readState.Index' (duration: 129.754µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:26:26.334457Z","caller":"traceutil/trace.go:171","msg":"trace[1863007661] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"188.30237ms","start":"2026-04-20T08:26:26.146107Z","end":"2026-04-20T08:26:26.3344Z","steps":["trace[1863007661] 'process raft request' (duration: 188.119555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:26.334536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.171121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T08:26:26.334557Z","caller":"traceutil/trace.go:171","msg":"trace[1127195453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8327; }","duration":"175.248453ms","start":"2026-04-20T08:26:26.159302Z","end":"2026-04-20T08:26:26.334551Z","steps":["trace[1127195453] 'agreement among raft nodes before linearized reading' (duration: 175.13888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:26.334563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.920825ms","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-04-20T08:26:26.334645Z","caller":"traceutil/trace.go:171","msg":"trace[586486272] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8327; }","duration":"168.060918ms","start":"2026-04-20T08:26:26.166564Z","end":"2026-04-20T08:26:26.334625Z","steps":["trace[586486272] 'agreement among raft nodes before linearized reading' (duration: 167.912895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:26:26.666583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.129763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:26:26.666712Z","caller":"traceutil/trace.go:171","msg":"trace[1808631067] transaction","detail":"{read_only:false; response_revision:8328; number_of_response:1; }","duration":"327.967053ms","start":"2026-04-20T08:26:26.338723Z","end":"2026-04-20T08:26:26.66669Z","steps":["trace[1808631067] 'process raft request' (duration: 169.629305ms)","trace[1808631067] 'compare' (duration: 157.970109ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:26:26.666787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:26:26.338709Z","time spent":"328.043544ms","remote":"127.0.0.1:40558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:26:56.587349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.014655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T08:26:56.587448Z","caller":"traceutil/trace.go:171","msg":"trace[481128651] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8542; }","duration":"130.165948ms","start":"2026-04-20T08:26:56.457261Z","end":"2026-04-20T08:26:56.587427Z","steps":["trace[481128651] 'count revisions from in-memory index tree' (duration: 129.911483ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:26:59.886186Z","caller":"traceutil/trace.go:171","msg":"trace[72603164] transaction","detail":"{read_only:false; response_revision:8558; number_of_response:1; }","duration":"158.053861ms","start":"2026-04-20T08:26:59.728104Z","end":"2026-04-20T08:26:59.886158Z","steps":["trace[72603164] 'process raft request' (duration: 157.855357ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:00.236831Z","caller":"traceutil/trace.go:171","msg":"trace[989023495] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"132.193634ms","start":"2026-04-20T08:27:00.104601Z","end":"2026-04-20T08:27:00.236795Z","steps":["trace[989023495] 'process raft request' (duration: 102.159522ms)","trace[989023495] 'compare' (duration: 29.860578ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:09.991564Z","caller":"traceutil/trace.go:171","msg":"trace[39212574] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"117.12021ms","start":"2026-04-20T08:27:09.874421Z","end":"2026-04-20T08:27:09.991541Z","steps":["trace[39212574] 'process raft request' (duration: 116.958926ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:10.114538Z","caller":"traceutil/trace.go:171","msg":"trace[1436564151] linearizableReadLoop","detail":"{readStateIndex:8966; appliedIndex:8964; }","duration":"163.31573ms","start":"2026-04-20T08:27:09.951188Z","end":"2026-04-20T08:27:10.114504Z","steps":["trace[1436564151] 'read index received' (duration: 40.178753ms)","trace[1436564151] 'applied index is now lower than readState.Index' (duration: 123.135196ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:10.114726Z","caller":"traceutil/trace.go:171","msg":"trace[973274553] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"190.6266ms","start":"2026-04-20T08:27:09.924059Z","end":"2026-04-20T08:27:10.114686Z","steps":["trace[973274553] 'process raft request' (duration: 112.074676ms)","trace[973274553] 'compare' (duration: 78.136734ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:27:10.114967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.76089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-20T08:27:10.115061Z","caller":"traceutil/trace.go:171","msg":"trace[1970907879] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8653; }","duration":"163.909083ms","start":"2026-04-20T08:27:09.951134Z","end":"2026-04-20T08:27:10.115043Z","steps":["trace[1970907879] 'agreement among raft nodes before linearized reading' (duration: 163.666307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:27:10.147793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.031173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T08:27:10.147851Z","caller":"traceutil/trace.go:171","msg":"trace[873502119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8654; }","duration":"178.144297ms","start":"2026-04-20T08:27:09.969693Z","end":"2026-04-20T08:27:10.147837Z","steps":["trace[873502119] 'agreement among raft nodes before linearized reading' (duration: 177.997332ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:10.148307Z","caller":"traceutil/trace.go:171","msg":"trace[1503193971] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"140.675595ms","start":"2026-04-20T08:27:10.007597Z","end":"2026-04-20T08:27:10.148272Z","steps":["trace[1503193971] 'process raft request' (duration: 139.915137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:10.897931Z","caller":"traceutil/trace.go:171","msg":"trace[677253990] linearizableReadLoop","detail":"{readStateIndex:8975; appliedIndex:8974; }","duration":"194.444556ms","start":"2026-04-20T08:27:10.703461Z","end":"2026-04-20T08:27:10.897906Z","steps":["trace[677253990] 'read index received' (duration: 194.18733ms)","trace[677253990] 'applied index is now lower than readState.Index' (duration: 255.986µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:10.898019Z","caller":"traceutil/trace.go:171","msg":"trace[478211475] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"302.841888ms","start":"2026-04-20T08:27:10.595122Z","end":"2026-04-20T08:27:10.897964Z","steps":["trace[478211475] 'process raft request' (duration: 302.546982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:27:10.89817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.699781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T08:27:10.898174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.999882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T08:27:10.898302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:27:10.595099Z","time spent":"303.036442ms","remote":"127.0.0.1:50658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:27:10.898374Z","caller":"traceutil/trace.go:171","msg":"trace[935022841] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8662; }","duration":"137.166046ms","start":"2026-04-20T08:27:10.761128Z","end":"2026-04-20T08:27:10.898294Z","steps":["trace[935022841] 'agreement among raft nodes before linearized reading' (duration: 136.958681ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:10.898345Z","caller":"traceutil/trace.go:171","msg":"trace[1520741268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8662; }","duration":"194.836875ms","start":"2026-04-20T08:27:10.703418Z","end":"2026-04-20T08:27:10.898255Z","steps":["trace[1520741268] 'agreement among raft nodes before linearized reading' (duration: 194.585439ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:11.060644Z","caller":"traceutil/trace.go:171","msg":"trace[2101697634] linearizableReadLoop","detail":"{readStateIndex:8976; appliedIndex:8975; }","duration":"101.824782ms","start":"2026-04-20T08:27:10.958791Z","end":"2026-04-20T08:27:11.060615Z","steps":["trace[2101697634] 'read index received' (duration: 88.376727ms)","trace[2101697634] 'applied index is now lower than readState.Index' (duration: 13.446095ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:11.060838Z","caller":"traceutil/trace.go:171","msg":"trace[1932529975] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"157.62873ms","start":"2026-04-20T08:27:10.903056Z","end":"2026-04-20T08:27:11.060685Z","steps":["trace[1932529975] 'process raft request' (duration: 144.032752ms)","trace[1932529975] 'compare' (duration: 13.189559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:27:11.060871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.071608ms","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-04-20T08:27:11.061118Z","caller":"traceutil/trace.go:171","msg":"trace[1115235346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8663; }","duration":"102.340805ms","start":"2026-04-20T08:27:10.958722Z","end":"2026-04-20T08:27:11.061063Z","steps":["trace[1115235346] 'agreement among raft nodes before linearized reading' (duration: 101.998097ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:11.367148Z","caller":"traceutil/trace.go:171","msg":"trace[481881296] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"206.211993ms","start":"2026-04-20T08:27:11.160901Z","end":"2026-04-20T08:27:11.367113Z","steps":["trace[481881296] 'process raft request' (duration: 147.581722ms)","trace[481881296] 'compare' (duration: 58.490288ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:27:11.378159Z","caller":"traceutil/trace.go:171","msg":"trace[1739313909] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"143.816327ms","start":"2026-04-20T08:27:11.234314Z","end":"2026-04-20T08:27:11.37813Z","steps":["trace[1739313909] 'process raft request' (duration: 143.713335ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:11.378196Z","caller":"traceutil/trace.go:171","msg":"trace[1037135456] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"191.395478ms","start":"2026-04-20T08:27:11.186734Z","end":"2026-04-20T08:27:11.378129Z","steps":["trace[1037135456] 'process raft request' (duration: 191.148582ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:27:51.34982Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002489.wal"} {"level":"info","ts":"2026-04-20T08:28:27.821986Z","caller":"traceutil/trace.go:171","msg":"trace[604318985] transaction","detail":"{read_only:false; response_revision:9411; number_of_response:1; }","duration":"158.986611ms","start":"2026-04-20T08:28:27.662973Z","end":"2026-04-20T08:28:27.82196Z","steps":["trace[604318985] 'process raft request' (duration: 158.607063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:28:27.822151Z","caller":"traceutil/trace.go:171","msg":"trace[1104520506] transaction","detail":"{read_only:false; response_revision:9410; number_of_response:1; }","duration":"160.422974ms","start":"2026-04-20T08:28:27.661697Z","end":"2026-04-20T08:28:27.82212Z","steps":["trace[1104520506] 'process raft request' (duration: 159.763408ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:29:01.73823Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b5a1dacc8ba86a4f","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T08:29:01.751656Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T08:29:01.751733Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T08:31:24.453995Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8317} {"level":"info","ts":"2026-04-20T08:31:24.535463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8317,"took":"79.177257ms","hash":3056624391,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":24977408,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T08:31:24.535545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3056624391,"revision":8317,"compact-revision":6125} {"level":"info","ts":"2026-04-20T08:36:24.461023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10711} {"level":"info","ts":"2026-04-20T08:36:24.549296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10711,"took":"85.643884ms","hash":1606150838,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":20578304,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-20T08:36:24.549367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1606150838,"revision":10711,"compact-revision":8317}