{"level":"warn","ts":"2026-05-12T11:21:37.612619Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T11:21:37.612691Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-05-12T11:21:37.612752Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T11:21:37.612764Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-12T11:21:37.61278Z","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-05-12T11:21:37.613364Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-05-12T11:21:37.613443Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-05-12T11:21:37.617938Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.2638ms"} {"level":"info","ts":"2026-05-12T11:21:37.623757Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-05-12T11:21:37.623824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T11:21:37.62385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-05-12T11:21:37.623865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T11:21:37.623877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-05-12T11:21:37.623914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-05-12T11:21:37.630931Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T11:21:37.632215Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T11:21:37.633361Z","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-05-12T11:21:37.635551Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T11:21:37.63564Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T11:21:37.636427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-05-12T11:21:37.63652Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-12T11:21:37.636599Z","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-05-12T11:21:37.636623Z","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-05-12T11:21:37.636629Z","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-05-12T11:21:37.640698Z","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-05-12T11:21:37.640882Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T11:21:37.640905Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T11:21:37.641249Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-12T11:21:37.641277Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-12T11:21:38.525103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T11:21:38.525183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T11:21:38.525237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-05-12T11:21:38.525258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-05-12T11:21:38.525269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-12T11:21:38.525285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-05-12T11:21:38.525304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-12T11:21:38.526452Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T11:21:38.526504Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T11:21:38.52656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T11:21:38.526591Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:21:38.526879Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T11:21:38.526914Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T11:21:38.528377Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T11:21:38.528746Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:21:38.528853Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:21:38.528889Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:21:38.529576Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"warn","ts":"2026-05-12T11:21:44.225803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.325856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:21:44.226015Z","caller":"traceutil/trace.go:171","msg":"trace[1563994372] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:255; }","duration":"206.096108ms","start":"2026-05-12T11:21:44.0199Z","end":"2026-05-12T11:21:44.225996Z","steps":["trace[1563994372] 'read index received' (duration: 131.013186ms)","trace[1563994372] 'applied index is now lower than readState.Index' (duration: 75.081802ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:21:44.22604Z","caller":"traceutil/trace.go:171","msg":"trace[895664779] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"281.318122ms","start":"2026-05-12T11:21:43.944671Z","end":"2026-05-12T11:21:44.225989Z","steps":["trace[895664779] 'process raft request' (duration: 65.286872ms)","trace[895664779] 'compare' (duration: 215.216783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:21:44.226229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.293544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:21:44.226261Z","caller":"traceutil/trace.go:171","msg":"trace[1977654148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:249; }","duration":"206.404098ms","start":"2026-05-12T11:21:44.019848Z","end":"2026-05-12T11:21:44.226252Z","steps":["trace[1977654148] 'agreement among raft nodes before linearized reading' (duration: 206.319126ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:21:44.226145Z","caller":"traceutil/trace.go:171","msg":"trace[34088633] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"279.022175ms","start":"2026-05-12T11:21:43.947112Z","end":"2026-05-12T11:21:44.226134Z","steps":["trace[34088633] 'process raft request' (duration: 278.820549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:21:44.22643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.871938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-12T11:21:44.226531Z","caller":"traceutil/trace.go:171","msg":"trace[252805388] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:249; }","duration":"130.032213ms","start":"2026-05-12T11:21:44.096472Z","end":"2026-05-12T11:21:44.226504Z","steps":["trace[252805388] 'agreement among raft nodes before linearized reading' (duration: 129.864497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:21:44.226705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.766171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:21:44.226774Z","caller":"traceutil/trace.go:171","msg":"trace[330387950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:249; }","duration":"125.864554ms","start":"2026-05-12T11:21:44.100896Z","end":"2026-05-12T11:21:44.226761Z","steps":["trace[330387950] 'agreement among raft nodes before linearized reading' (duration: 125.74566ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:21:44.372602Z","caller":"traceutil/trace.go:171","msg":"trace[186185857] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"142.096156ms","start":"2026-05-12T11:21:44.230474Z","end":"2026-05-12T11:21:44.37257Z","steps":["trace[186185857] 'process raft request' (duration: 141.951751ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:21:44.449229Z","caller":"traceutil/trace.go:171","msg":"trace[1589730341] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"179.322575ms","start":"2026-05-12T11:21:44.269879Z","end":"2026-05-12T11:21:44.449202Z","steps":["trace[1589730341] 'process raft request' (duration: 179.218592ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:21:44.449291Z","caller":"traceutil/trace.go:171","msg":"trace[1003079317] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"217.628443ms","start":"2026-05-12T11:21:44.231616Z","end":"2026-05-12T11:21:44.449244Z","steps":["trace[1003079317] 'process raft request' (duration: 217.331213ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.227553Z","caller":"traceutil/trace.go:171","msg":"trace[2035087262] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"279.064868ms","start":"2026-05-12T11:22:17.948468Z","end":"2026-05-12T11:22:18.227533Z","steps":["trace[2035087262] 'process raft request' (duration: 278.799629ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.227669Z","caller":"traceutil/trace.go:171","msg":"trace[220070588] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"278.017692ms","start":"2026-05-12T11:22:17.949592Z","end":"2026-05-12T11:22:18.22761Z","steps":["trace[220070588] 'process raft request' (duration: 277.819596ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.227719Z","caller":"traceutil/trace.go:171","msg":"trace[856027714] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"276.822331ms","start":"2026-05-12T11:22:17.950872Z","end":"2026-05-12T11:22:18.227695Z","steps":["trace[856027714] 'process raft request' (duration: 276.589063ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.22777Z","caller":"traceutil/trace.go:171","msg":"trace[141998272] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"268.460505ms","start":"2026-05-12T11:22:17.959231Z","end":"2026-05-12T11:22:18.227692Z","steps":["trace[141998272] 'process raft request' (duration: 268.275929ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.227823Z","caller":"traceutil/trace.go:171","msg":"trace[198381603] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"266.24244ms","start":"2026-05-12T11:22:17.961556Z","end":"2026-05-12T11:22:18.227798Z","steps":["trace[198381603] 'process raft request' (duration: 265.989201ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.227991Z","caller":"traceutil/trace.go:171","msg":"trace[1053786475] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"264.751819ms","start":"2026-05-12T11:22:17.963214Z","end":"2026-05-12T11:22:18.227966Z","steps":["trace[1053786475] 'process raft request' (duration: 264.370046ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.227996Z","caller":"traceutil/trace.go:171","msg":"trace[1578529772] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"262.4102ms","start":"2026-05-12T11:22:17.965568Z","end":"2026-05-12T11:22:18.227978Z","steps":["trace[1578529772] 'process raft request' (duration: 262.151901ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.22807Z","caller":"traceutil/trace.go:171","msg":"trace[757155694] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"227.760827ms","start":"2026-05-12T11:22:18.000301Z","end":"2026-05-12T11:22:18.228062Z","steps":["trace[757155694] 'process raft request' (duration: 227.464747ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.228169Z","caller":"traceutil/trace.go:171","msg":"trace[1751304770] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"220.039353ms","start":"2026-05-12T11:22:18.008111Z","end":"2026-05-12T11:22:18.22815Z","steps":["trace[1751304770] 'process raft request' (duration: 219.853657ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.264798Z","caller":"traceutil/trace.go:171","msg":"trace[415012526] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"247.524181ms","start":"2026-05-12T11:22:18.01723Z","end":"2026-05-12T11:22:18.264755Z","steps":["trace[415012526] 'process raft request' (duration: 247.243931ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.265208Z","caller":"traceutil/trace.go:171","msg":"trace[870627597] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:514; }","duration":"203.685135ms","start":"2026-05-12T11:22:18.061498Z","end":"2026-05-12T11:22:18.265183Z","steps":["trace[870627597] 'read index received' (duration: 165.788322ms)","trace[870627597] 'applied index is now lower than readState.Index' (duration: 37.895833ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:18.265338Z","caller":"traceutil/trace.go:171","msg":"trace[735629397] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"241.6301ms","start":"2026-05-12T11:22:18.023693Z","end":"2026-05-12T11:22:18.265323Z","steps":["trace[735629397] 'process raft request' (duration: 240.896715ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.265384Z","caller":"traceutil/trace.go:171","msg":"trace[2032470634] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"222.28822ms","start":"2026-05-12T11:22:18.043088Z","end":"2026-05-12T11:22:18.265377Z","steps":["trace[2032470634] 'process raft request' (duration: 221.634398ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:18.26543Z","caller":"traceutil/trace.go:171","msg":"trace[1257703139] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"125.046931ms","start":"2026-05-12T11:22:18.140362Z","end":"2026-05-12T11:22:18.265409Z","steps":["trace[1257703139] 'process raft request' (duration: 124.693079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:18.265484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.950304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:18.266138Z","caller":"traceutil/trace.go:171","msg":"trace[1233465970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"204.644317ms","start":"2026-05-12T11:22:18.06147Z","end":"2026-05-12T11:22:18.266115Z","steps":["trace[1233465970] 'agreement among raft nodes before linearized reading' (duration: 203.950103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:18.294173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.13112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:18.29435Z","caller":"traceutil/trace.go:171","msg":"trace[788053704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"192.348537ms","start":"2026-05-12T11:22:18.101976Z","end":"2026-05-12T11:22:18.294325Z","steps":["trace[788053704] 'agreement among raft nodes before linearized reading' (duration: 192.073557ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:23.544113Z","caller":"traceutil/trace.go:171","msg":"trace[1213762834] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"118.965158ms","start":"2026-05-12T11:22:23.425124Z","end":"2026-05-12T11:22:23.544089Z","steps":["trace[1213762834] 'process raft request' (duration: 118.786972ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:24.833735Z","caller":"traceutil/trace.go:171","msg":"trace[987925051] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"166.387093ms","start":"2026-05-12T11:22:24.667301Z","end":"2026-05-12T11:22:24.833688Z","steps":["trace[987925051] 'process raft request' (duration: 152.179077ms)","trace[987925051] 'compare' (duration: 13.970898ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:24.834421Z","caller":"traceutil/trace.go:171","msg":"trace[1559456128] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:617; }","duration":"136.217791ms","start":"2026-05-12T11:22:24.698186Z","end":"2026-05-12T11:22:24.834404Z","steps":["trace[1559456128] 'read index received' (duration: 55.571772ms)","trace[1559456128] 'applied index is now lower than readState.Index' (duration: 80.645369ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:24.834561Z","caller":"traceutil/trace.go:171","msg":"trace[1847331253] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"162.543311ms","start":"2026-05-12T11:22:24.671973Z","end":"2026-05-12T11:22:24.834516Z","steps":["trace[1847331253] 'process raft request' (duration: 161.731623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:24.834695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.48944ms","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-05-12T11:22:24.834839Z","caller":"traceutil/trace.go:171","msg":"trace[127548367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"136.674256ms","start":"2026-05-12T11:22:24.698128Z","end":"2026-05-12T11:22:24.834802Z","steps":["trace[127548367] 'agreement among raft nodes before linearized reading' (duration: 136.410507ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:22:25.227839Z","caller":"traceutil/trace.go:171","msg":"trace[618723889] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"384.60943ms","start":"2026-05-12T11:22:24.843189Z","end":"2026-05-12T11:22:25.227799Z","steps":["trace[618723889] 'process raft request' (duration: 384.410324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:25.2283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:24.843164Z","time spent":"384.764826ms","remote":"127.0.0.1:35610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:22:25.262537Z","caller":"traceutil/trace.go:171","msg":"trace[1656221402] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"161.964863ms","start":"2026-05-12T11:22:25.100542Z","end":"2026-05-12T11:22:25.262507Z","steps":["trace[1656221402] 'read index received' (duration: 127.092279ms)","trace[1656221402] 'applied index is now lower than readState.Index' (duration: 34.871134ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:22:25.262668Z","caller":"traceutil/trace.go:171","msg":"trace[2090179164] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"376.356428ms","start":"2026-05-12T11:22:24.886247Z","end":"2026-05-12T11:22:25.262603Z","steps":["trace[2090179164] 'process raft request' (duration: 376.12008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:25.262817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.270174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:25.262887Z","caller":"traceutil/trace.go:171","msg":"trace[2111058158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"162.390107ms","start":"2026-05-12T11:22:25.100483Z","end":"2026-05-12T11:22:25.262873Z","steps":["trace[2111058158] 'agreement among raft nodes before linearized reading' (duration: 162.17847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:22:25.262933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:22:24.886221Z","time spent":"376.572836ms","remote":"127.0.0.1:35648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:22:41.330017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.760828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:22:41.330455Z","caller":"traceutil/trace.go:171","msg":"trace[1228895122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"229.242246ms","start":"2026-05-12T11:22:41.101196Z","end":"2026-05-12T11:22:41.330438Z","steps":["trace[1228895122] 'range keys from in-memory index tree' (duration: 228.638829ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:23:09.660131Z","caller":"traceutil/trace.go:171","msg":"trace[274229043] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"176.2102ms","start":"2026-05-12T11:23:09.483896Z","end":"2026-05-12T11:23:09.660106Z","steps":["trace[274229043] 'process raft request' (duration: 176.081289ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:04.920517Z","caller":"traceutil/trace.go:171","msg":"trace[191920974] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"145.889612ms","start":"2026-05-12T11:24:04.774577Z","end":"2026-05-12T11:24:04.920466Z","steps":["trace[191920974] 'process raft request' (duration: 145.585536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:05.249353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.477375ms","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-05-12T11:24:05.249444Z","caller":"traceutil/trace.go:171","msg":"trace[1584228058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1875; }","duration":"173.614249ms","start":"2026-05-12T11:24:05.075808Z","end":"2026-05-12T11:24:05.249422Z","steps":["trace[1584228058] 'range keys from in-memory index tree' (duration: 173.305342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:05.249434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.985505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:24:05.249532Z","caller":"traceutil/trace.go:171","msg":"trace[780657379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"148.137229ms","start":"2026-05-12T11:24:05.101373Z","end":"2026-05-12T11:24:05.24951Z","steps":["trace[780657379] 'range keys from in-memory index tree' (duration: 147.838693ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:05.370884Z","caller":"traceutil/trace.go:171","msg":"trace[285312310] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"117.288341ms","start":"2026-05-12T11:24:05.253573Z","end":"2026-05-12T11:24:05.370861Z","steps":["trace[285312310] 'process raft request' (duration: 117.135008ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:05.375529Z","caller":"traceutil/trace.go:171","msg":"trace[541404367] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1946; }","duration":"101.463834ms","start":"2026-05-12T11:24:05.274045Z","end":"2026-05-12T11:24:05.375509Z","steps":["trace[541404367] 'read index received' (duration: 96.75926ms)","trace[541404367] 'applied index is now lower than readState.Index' (duration: 4.703604ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:24:05.375597Z","caller":"traceutil/trace.go:171","msg":"trace[108284820] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"121.677479ms","start":"2026-05-12T11:24:05.253905Z","end":"2026-05-12T11:24:05.375583Z","steps":["trace[108284820] 'process raft request' (duration: 121.483054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:05.375898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.826541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-12T11:24:05.375989Z","caller":"traceutil/trace.go:171","msg":"trace[1522252410] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1878; }","duration":"101.955503ms","start":"2026-05-12T11:24:05.274014Z","end":"2026-05-12T11:24:05.37597Z","steps":["trace[1522252410] 'agreement among raft nodes before linearized reading' (duration: 101.693528ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:39.969799Z","caller":"traceutil/trace.go:171","msg":"trace[1314476201] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"276.800382ms","start":"2026-05-12T11:24:39.692949Z","end":"2026-05-12T11:24:39.969749Z","steps":["trace[1314476201] 'process raft request' (duration: 276.463254ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:39.970083Z","caller":"traceutil/trace.go:171","msg":"trace[990465703] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2199; }","duration":"133.883109ms","start":"2026-05-12T11:24:39.836177Z","end":"2026-05-12T11:24:39.97006Z","steps":["trace[990465703] 'read index received' (duration: 133.11789ms)","trace[990465703] 'applied index is now lower than readState.Index' (duration: 764.399µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:24:39.970078Z","caller":"traceutil/trace.go:171","msg":"trace[632257999] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"276.970156ms","start":"2026-05-12T11:24:39.693059Z","end":"2026-05-12T11:24:39.970029Z","steps":["trace[632257999] 'process raft request' (duration: 276.616788ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:39.970463Z","caller":"traceutil/trace.go:171","msg":"trace[1519418040] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"276.367172ms","start":"2026-05-12T11:24:39.69404Z","end":"2026-05-12T11:24:39.970407Z","steps":["trace[1519418040] 'process raft request' (duration: 275.971813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:39.97052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.151685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-12T11:24:39.970712Z","caller":"traceutil/trace.go:171","msg":"trace[854881636] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2124; }","duration":"134.40321ms","start":"2026-05-12T11:24:39.836283Z","end":"2026-05-12T11:24:39.970687Z","steps":["trace[854881636] 'agreement among raft nodes before linearized reading' (duration: 134.132164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:24:39.970961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.772339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:111206"} {"level":"info","ts":"2026-05-12T11:24:39.971018Z","caller":"traceutil/trace.go:171","msg":"trace[940095560] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2124; }","duration":"134.897373ms","start":"2026-05-12T11:24:39.836102Z","end":"2026-05-12T11:24:39.971Z","steps":["trace[940095560] 'agreement among raft nodes before linearized reading' (duration: 134.614336ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:24:39.970411Z","caller":"traceutil/trace.go:171","msg":"trace[85361421] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"276.674869ms","start":"2026-05-12T11:24:39.693703Z","end":"2026-05-12T11:24:39.970378Z","steps":["trace[85361421] 'process raft request' (duration: 276.200218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:44.629276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.235452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:25:44.629388Z","caller":"traceutil/trace.go:171","msg":"trace[249040111] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"184.292489ms","start":"2026-05-12T11:25:44.445075Z","end":"2026-05-12T11:25:44.629367Z","steps":["trace[249040111] 'process raft request' (duration: 44.873332ms)","trace[249040111] 'compare' (duration: 139.118329ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:30:08.984311Z","caller":"traceutil/trace.go:171","msg":"trace[189392282] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"134.308546ms","start":"2026-05-12T11:30:08.849971Z","end":"2026-05-12T11:30:08.98428Z","steps":["trace[189392282] 'process raft request' (duration: 134.142443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:08.984825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.408112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-dfv28-tls\" ","response":"range_response_count:1 size:2464"} {"level":"info","ts":"2026-05-12T11:30:08.984918Z","caller":"traceutil/trace.go:171","msg":"trace[1924807330] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-dfv28-tls; range_end:; response_count:1; response_revision:4652; }","duration":"143.571475ms","start":"2026-05-12T11:30:08.841327Z","end":"2026-05-12T11:30:08.984898Z","steps":["trace[1924807330] 'agreement among raft nodes before linearized reading' (duration: 143.234118ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:08.984518Z","caller":"traceutil/trace.go:171","msg":"trace[187413884] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4829; }","duration":"107.542212ms","start":"2026-05-12T11:30:08.876733Z","end":"2026-05-12T11:30:08.984276Z","steps":["trace[187413884] 'read index received' (duration: 107.507392ms)","trace[187413884] 'applied index is now lower than readState.Index' (duration: 32.52µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:30:08.995335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.881241ms","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-05-12T11:30:08.995416Z","caller":"traceutil/trace.go:171","msg":"trace[1612755833] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4653; }","duration":"112.020284ms","start":"2026-05-12T11:30:08.883378Z","end":"2026-05-12T11:30:08.995399Z","steps":["trace[1612755833] 'agreement among raft nodes before linearized reading' (duration: 111.330049ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:13.151273Z","caller":"traceutil/trace.go:171","msg":"trace[278265081] linearizableReadLoop","detail":"{readStateIndex:4932; appliedIndex:4931; }","duration":"145.928461ms","start":"2026-05-12T11:30:13.005322Z","end":"2026-05-12T11:30:13.151251Z","steps":["trace[278265081] 'read index received' (duration: 145.736067ms)","trace[278265081] 'applied index is now lower than readState.Index' (duration: 191.574µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:30:13.151304Z","caller":"traceutil/trace.go:171","msg":"trace[1934350303] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"147.071647ms","start":"2026-05-12T11:30:13.004213Z","end":"2026-05-12T11:30:13.151285Z","steps":["trace[1934350303] 'process raft request' (duration: 146.863152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:13.151483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.140426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7fd6e678-231c-4950-a68b-bc0f8d3a3468\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-12T11:30:13.151525Z","caller":"traceutil/trace.go:171","msg":"trace[44270428] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7fd6e678-231c-4950-a68b-bc0f8d3a3468; range_end:; response_count:1; response_revision:4753; }","duration":"146.211097ms","start":"2026-05-12T11:30:13.005303Z","end":"2026-05-12T11:30:13.151514Z","steps":["trace[44270428] 'agreement among raft nodes before linearized reading' (duration: 146.073784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:26.328916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.630476ms","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-05-12T11:30:26.329004Z","caller":"traceutil/trace.go:171","msg":"trace[2018604236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4913; }","duration":"235.764338ms","start":"2026-05-12T11:30:26.093221Z","end":"2026-05-12T11:30:26.328985Z","steps":["trace[2018604236] 'range keys from in-memory index tree' (duration: 235.461081ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.339949Z","caller":"traceutil/trace.go:171","msg":"trace[2105681694] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"115.506764ms","start":"2026-05-12T11:30:26.224416Z","end":"2026-05-12T11:30:26.339923Z","steps":["trace[2105681694] 'process raft request' (duration: 115.359721ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.877448Z","caller":"traceutil/trace.go:171","msg":"trace[1749611802] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"112.882724ms","start":"2026-05-12T11:30:26.764538Z","end":"2026-05-12T11:30:26.877421Z","steps":["trace[1749611802] 'read index received' (duration: 112.69308ms)","trace[1749611802] 'applied index is now lower than readState.Index' (duration: 188.594µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:30:26.877706Z","caller":"traceutil/trace.go:171","msg":"trace[417648765] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"114.440651ms","start":"2026-05-12T11:30:26.763214Z","end":"2026-05-12T11:30:26.877655Z","steps":["trace[417648765] 'process raft request' (duration: 114.010751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:26.877802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.239763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7fd6e678-231c-4950-a68b-bc0f8d3a3468\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-12T11:30:26.877839Z","caller":"traceutil/trace.go:171","msg":"trace[531975319] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7fd6e678-231c-4950-a68b-bc0f8d3a3468; range_end:; response_count:1; response_revision:4916; }","duration":"113.325764ms","start":"2026-05-12T11:30:26.764501Z","end":"2026-05-12T11:30:26.877827Z","steps":["trace[531975319] 'agreement among raft nodes before linearized reading' (duration: 113.204912ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.991016Z","caller":"traceutil/trace.go:171","msg":"trace[1727435217] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5100; }","duration":"113.468767ms","start":"2026-05-12T11:30:26.877512Z","end":"2026-05-12T11:30:26.990981Z","steps":["trace[1727435217] 'read index received' (duration: 18.336683ms)","trace[1727435217] 'applied index is now lower than readState.Index' (duration: 95.125304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:30:26.991177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.88844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T11:30:26.991369Z","caller":"traceutil/trace.go:171","msg":"trace[1658487132] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4917; }","duration":"115.151437ms","start":"2026-05-12T11:30:26.876193Z","end":"2026-05-12T11:30:26.991344Z","steps":["trace[1658487132] 'agreement among raft nodes before linearized reading' (duration: 114.797979ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.991423Z","caller":"traceutil/trace.go:171","msg":"trace[1937285766] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"104.0402ms","start":"2026-05-12T11:30:26.88737Z","end":"2026-05-12T11:30:26.991411Z","steps":["trace[1937285766] 'process raft request' (duration: 103.640751ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.991123Z","caller":"traceutil/trace.go:171","msg":"trace[753029291] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"103.913577ms","start":"2026-05-12T11:30:26.887181Z","end":"2026-05-12T11:30:26.991095Z","steps":["trace[753029291] 'process raft request' (duration: 103.692942ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.992143Z","caller":"traceutil/trace.go:171","msg":"trace[1591971538] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"103.063208ms","start":"2026-05-12T11:30:26.88907Z","end":"2026-05-12T11:30:26.992133Z","steps":["trace[1591971538] 'process raft request' (duration: 102.246558ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:26.992284Z","caller":"traceutil/trace.go:171","msg":"trace[1551505694] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"102.366222ms","start":"2026-05-12T11:30:26.889908Z","end":"2026-05-12T11:30:26.992274Z","steps":["trace[1551505694] 'process raft request' (duration: 102.054125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:30:26.992451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.503665ms","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-05-12T11:30:26.992611Z","caller":"traceutil/trace.go:171","msg":"trace[1170878283] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4920; }","duration":"203.72182ms","start":"2026-05-12T11:30:26.78886Z","end":"2026-05-12T11:30:26.992582Z","steps":["trace[1170878283] 'agreement among raft nodes before linearized reading' (duration: 202.302887ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:27.463409Z","caller":"traceutil/trace.go:171","msg":"trace[1964966138] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"125.864287ms","start":"2026-05-12T11:30:27.337505Z","end":"2026-05-12T11:30:27.463369Z","steps":["trace[1964966138] 'process raft request' (duration: 125.731894ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:30:27.463802Z","caller":"traceutil/trace.go:171","msg":"trace[1712023979] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"129.428269ms","start":"2026-05-12T11:30:27.334265Z","end":"2026-05-12T11:30:27.463693Z","steps":["trace[1712023979] 'process raft request' (duration: 80.767576ms)","trace[1712023979] 'compare' (duration: 47.976258ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:31:24.277922Z","caller":"traceutil/trace.go:171","msg":"trace[348863582] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"139.348494ms","start":"2026-05-12T11:31:24.138499Z","end":"2026-05-12T11:31:24.277848Z","steps":["trace[348863582] 'process raft request' (duration: 108.112194ms)","trace[348863582] 'compare' (duration: 30.989774ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:31:38.551149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-05-12T11:31:38.61653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"62.792074ms","hash":1677215161,"current-db-size-bytes":52752384,"current-db-size":"53 MB","current-db-size-in-use-bytes":28766208,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-12T11:31:38.616608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1677215161,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-05-12T11:36:38.557103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5527} {"level":"info","ts":"2026-05-12T11:36:38.632919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5527,"took":"73.903276ms","hash":1359450176,"current-db-size-bytes":52752384,"current-db-size":"53 MB","current-db-size-in-use-bytes":18276352,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-12T11:36:38.633007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1359450176,"revision":5527,"compact-revision":2866}