{"level":"warn","ts":"2026-03-05T06:39:00.73552Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T06:39:00.73566Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.206:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.206:2380","--initial-cluster=instance=https://162.253.55.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.206: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-03-05T06:39:00.735738Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T06:39:00.735759Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-03-05T06:39:00.73578Z","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-03-05T06:39:00.736564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"]} {"level":"info","ts":"2026-03-05T06:39:00.736653Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.206: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-03-05T06:39:00.753215Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.240712ms"} {"level":"info","ts":"2026-03-05T06:39:00.764578Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cfc9fec265e3384a","cluster-id":"9866fe2b0d49ed88"} {"level":"info","ts":"2026-03-05T06:39:00.764705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T06:39:00.764738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 0"} {"level":"info","ts":"2026-03-05T06:39:00.764763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cfc9fec265e3384a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T06:39:00.764784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became follower at term 1"} {"level":"info","ts":"2026-03-05T06:39:00.764854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"warn","ts":"2026-03-05T06:39:00.77458Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T06:39:00.778277Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T06:39:00.795879Z","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-03-05T06:39:00.81448Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cfc9fec265e3384a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T06:39:00.814712Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cfc9fec265e3384a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T06:39:00.814836Z","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-03-05T06:39:00.814963Z","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-03-05T06:39:00.814981Z","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-03-05T06:39:00.81781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a switched to configuration voters=(14972778547056425034)"} {"level":"info","ts":"2026-03-05T06:39:00.818469Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","added-peer-id":"cfc9fec265e3384a","added-peer-peer-urls":["https://162.253.55.206:2380"]} {"level":"info","ts":"2026-03-05T06:39:00.82078Z","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-03-05T06:39:00.820937Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-03-05T06:39:00.820985Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.206:2380"} {"level":"info","ts":"2026-03-05T06:39:00.821287Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cfc9fec265e3384a","initial-advertise-peer-urls":["https://162.253.55.206:2380"],"listen-peer-urls":["https://162.253.55.206:2380"],"advertise-client-urls":["https://162.253.55.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T06:39:00.821349Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T06:39:01.165154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T06:39:01.165273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T06:39:01.16531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgPreVoteResp from cfc9fec265e3384a at term 1"} {"level":"info","ts":"2026-03-05T06:39:01.165331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became candidate at term 2"} {"level":"info","ts":"2026-03-05T06:39:01.165359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a received MsgVoteResp from cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-03-05T06:39:01.16537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cfc9fec265e3384a became leader at term 2"} {"level":"info","ts":"2026-03-05T06:39:01.165384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cfc9fec265e3384a elected leader cfc9fec265e3384a at term 2"} {"level":"info","ts":"2026-03-05T06:39:01.169154Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T06:39:01.172649Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cfc9fec265e3384a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.206:2379]}","request-path":"/0/members/cfc9fec265e3384a/attributes","cluster-id":"9866fe2b0d49ed88","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T06:39:01.172864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T06:39:01.173341Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9866fe2b0d49ed88","local-member-id":"cfc9fec265e3384a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T06:39:01.173371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T06:39:01.173415Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T06:39:01.173436Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T06:39:01.175432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T06:39:01.175552Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T06:39:01.17557Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T06:39:01.175915Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.206:2379"} {"level":"info","ts":"2026-03-05T06:39:23.438543Z","caller":"traceutil/trace.go:171","msg":"trace[970107550] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"223.739106ms","start":"2026-03-05T06:39:23.214778Z","end":"2026-03-05T06:39:23.438517Z","steps":["trace[970107550] 'read index received' (duration: 223.515791ms)","trace[970107550] 'applied index is now lower than readState.Index' (duration: 222.165µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T06:39:23.438709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.91388ms","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-03-05T06:39:23.438772Z","caller":"traceutil/trace.go:171","msg":"trace[1326869915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"224.000502ms","start":"2026-03-05T06:39:23.214761Z","end":"2026-03-05T06:39:23.438761Z","steps":["trace[1326869915] 'agreement among raft nodes before linearized reading' (duration: 223.88504ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:39:23.438802Z","caller":"traceutil/trace.go:171","msg":"trace[17158791] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"342.44622ms","start":"2026-03-05T06:39:23.096332Z","end":"2026-03-05T06:39:23.438778Z","steps":["trace[17158791] 'process raft request' (duration: 341.99023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:39:23.439275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T06:39:23.096315Z","time spent":"342.547623ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T06:39:23.468484Z","caller":"traceutil/trace.go:171","msg":"trace[2108440362] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"165.104729ms","start":"2026-03-05T06:39:23.303356Z","end":"2026-03-05T06:39:23.468461Z","steps":["trace[2108440362] 'process raft request' (duration: 164.985656ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:39:37.836619Z","caller":"traceutil/trace.go:171","msg":"trace[833891952] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"337.96144ms","start":"2026-03-05T06:39:37.498629Z","end":"2026-03-05T06:39:37.836591Z","steps":["trace[833891952] 'read index received' (duration: 337.814777ms)","trace[833891952] 'applied index is now lower than readState.Index' (duration: 145.363µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T06:39:37.836695Z","caller":"traceutil/trace.go:171","msg":"trace[510679881] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"485.079785ms","start":"2026-03-05T06:39:37.351576Z","end":"2026-03-05T06:39:37.836655Z","steps":["trace[510679881] 'process raft request' (duration: 484.841189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:39:37.836899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T06:39:37.351537Z","time spent":"485.245299ms","remote":"127.0.0.1:47170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T06:39:37.836905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.162858ms","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-03-05T06:39:37.836959Z","caller":"traceutil/trace.go:171","msg":"trace[2123030123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"206.302021ms","start":"2026-03-05T06:39:37.630642Z","end":"2026-03-05T06:39:37.836944Z","steps":["trace[2123030123] 'agreement among raft nodes before linearized reading' (duration: 206.103727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:39:37.836953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.136524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-05T06:39:37.837047Z","caller":"traceutil/trace.go:171","msg":"trace[889105032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"101.275847ms","start":"2026-03-05T06:39:37.735748Z","end":"2026-03-05T06:39:37.837024Z","steps":["trace[889105032] 'agreement among raft nodes before linearized reading' (duration: 101.014781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:39:37.837009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.343239ms","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-03-05T06:39:37.836949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.176017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T06:39:37.837131Z","caller":"traceutil/trace.go:171","msg":"trace[1428605295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"338.557864ms","start":"2026-03-05T06:39:37.498541Z","end":"2026-03-05T06:39:37.837099Z","steps":["trace[1428605295] 'agreement among raft nodes before linearized reading' (duration: 338.177345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:39:37.837187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T06:39:37.498507Z","time spent":"338.669847ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-05T06:39:37.837175Z","caller":"traceutil/trace.go:171","msg":"trace[1466813665] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:551; }","duration":"111.446012ms","start":"2026-03-05T06:39:37.72569Z","end":"2026-03-05T06:39:37.837136Z","steps":["trace[1466813665] 'agreement among raft nodes before linearized reading' (duration: 111.150245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:40:51.036636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.20606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d709b077-a69d-46a7-aa60-ec8cc7246f46\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-05T06:40:51.03675Z","caller":"traceutil/trace.go:171","msg":"trace[293198972] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d709b077-a69d-46a7-aa60-ec8cc7246f46; range_end:; response_count:1; response_revision:1602; }","duration":"305.379205ms","start":"2026-03-05T06:40:50.731349Z","end":"2026-03-05T06:40:51.036728Z","steps":["trace[293198972] 'range keys from in-memory index tree' (duration: 304.960015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:40:51.036798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T06:40:50.731328Z","time spent":"305.460326ms","remote":"127.0.0.1:47170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d709b077-a69d-46a7-aa60-ec8cc7246f46\" "} {"level":"warn","ts":"2026-03-05T06:40:51.398032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.129247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d709b077-a69d-46a7-aa60-ec8cc7246f46\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-03-05T06:40:51.398134Z","caller":"traceutil/trace.go:171","msg":"trace[124845737] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d709b077-a69d-46a7-aa60-ec8cc7246f46; range_end:; response_count:1; response_revision:1603; }","duration":"224.299001ms","start":"2026-03-05T06:40:51.173814Z","end":"2026-03-05T06:40:51.398113Z","steps":["trace[124845737] 'range keys from in-memory index tree' (duration: 224.012354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:41:24.695222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.606562ms","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-03-05T06:41:24.69533Z","caller":"traceutil/trace.go:171","msg":"trace[1037554051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1829; }","duration":"151.800197ms","start":"2026-03-05T06:41:24.54351Z","end":"2026-03-05T06:41:24.69531Z","steps":["trace[1037554051] 'range keys from in-memory index tree' (duration: 151.360676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:41:24.695314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.596726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-03-05T06:41:24.695502Z","caller":"traceutil/trace.go:171","msg":"trace[122028350] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1829; }","duration":"109.878983ms","start":"2026-03-05T06:41:24.585572Z","end":"2026-03-05T06:41:24.695451Z","steps":["trace[122028350] 'range keys from in-memory index tree' (duration: 109.414472ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:41:34.895792Z","caller":"traceutil/trace.go:171","msg":"trace[351317123] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"152.284777ms","start":"2026-03-05T06:41:34.74347Z","end":"2026-03-05T06:41:34.895755Z","steps":["trace[351317123] 'read index received' (duration: 152.024351ms)","trace[351317123] 'applied index is now lower than readState.Index' (duration: 258.936µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T06:41:34.895892Z","caller":"traceutil/trace.go:171","msg":"trace[1766086654] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"157.335475ms","start":"2026-03-05T06:41:34.738504Z","end":"2026-03-05T06:41:34.895839Z","steps":["trace[1766086654] 'process raft request' (duration: 157.020478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:41:34.896143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.596495ms","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-03-05T06:41:34.896225Z","caller":"traceutil/trace.go:171","msg":"trace[1852431954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1877; }","duration":"152.80674ms","start":"2026-03-05T06:41:34.743398Z","end":"2026-03-05T06:41:34.896205Z","steps":["trace[1852431954] 'agreement among raft nodes before linearized reading' (duration: 152.508933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:41:34.89623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.781061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-03-05T06:41:34.896305Z","caller":"traceutil/trace.go:171","msg":"trace[202575194] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1877; }","duration":"140.935124ms","start":"2026-03-05T06:41:34.75535Z","end":"2026-03-05T06:41:34.896285Z","steps":["trace[202575194] 'agreement among raft nodes before linearized reading' (duration: 140.678969ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:41:40.644677Z","caller":"traceutil/trace.go:171","msg":"trace[98164049] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"166.008807ms","start":"2026-03-05T06:41:40.478628Z","end":"2026-03-05T06:41:40.644636Z","steps":["trace[98164049] 'process raft request' (duration: 124.429782ms)","trace[98164049] 'compare' (duration: 41.345999ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T06:41:40.644919Z","caller":"traceutil/trace.go:171","msg":"trace[1389292607] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"163.900077ms","start":"2026-03-05T06:41:40.480978Z","end":"2026-03-05T06:41:40.644878Z","steps":["trace[1389292607] 'process raft request' (duration: 163.609321ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:41:41.144825Z","caller":"traceutil/trace.go:171","msg":"trace[570207950] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"128.120866ms","start":"2026-03-05T06:41:41.016655Z","end":"2026-03-05T06:41:41.144776Z","steps":["trace[570207950] 'process raft request' (duration: 64.383525ms)","trace[570207950] 'compare' (duration: 63.556487ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T06:41:46.636274Z","caller":"traceutil/trace.go:171","msg":"trace[570825280] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"129.545607ms","start":"2026-03-05T06:41:46.506707Z","end":"2026-03-05T06:41:46.636253Z","steps":["trace[570825280] 'process raft request' (duration: 129.400484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:41:46.925278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.914849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T06:41:46.925492Z","caller":"traceutil/trace.go:171","msg":"trace[589824747] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"251.499516ms","start":"2026-03-05T06:41:46.67397Z","end":"2026-03-05T06:41:46.92547Z","steps":["trace[589824747] 'process raft request' (duration: 251.411054ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:41:46.925619Z","caller":"traceutil/trace.go:171","msg":"trace[1253618358] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"253.77097ms","start":"2026-03-05T06:41:46.671772Z","end":"2026-03-05T06:41:46.925543Z","steps":["trace[1253618358] 'process raft request' (duration: 128.286948ms)","trace[1253618358] 'compare' (duration: 124.780646ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T06:45:39.198756Z","caller":"traceutil/trace.go:171","msg":"trace[1231743423] linearizableReadLoop","detail":"{readStateIndex:3586; appliedIndex:3585; }","duration":"154.14994ms","start":"2026-03-05T06:45:39.044581Z","end":"2026-03-05T06:45:39.19873Z","steps":["trace[1231743423] 'read index received' (duration: 153.966386ms)","trace[1231743423] 'applied index is now lower than readState.Index' (duration: 182.334µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T06:45:39.198847Z","caller":"traceutil/trace.go:171","msg":"trace[678131160] transaction","detail":"{read_only:false; response_revision:3450; number_of_response:1; }","duration":"169.716896ms","start":"2026-03-05T06:45:39.029102Z","end":"2026-03-05T06:45:39.198819Z","steps":["trace[678131160] 'process raft request' (duration: 169.459299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:45:39.199031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.339977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T06:45:39.19906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.446678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nz4bp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-05T06:45:39.199124Z","caller":"traceutil/trace.go:171","msg":"trace[1200098337] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nz4bp; range_end:; response_count:1; response_revision:3450; }","duration":"154.55201ms","start":"2026-03-05T06:45:39.044561Z","end":"2026-03-05T06:45:39.199113Z","steps":["trace[1200098337] 'agreement among raft nodes before linearized reading' (duration: 154.321725ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:45:39.199118Z","caller":"traceutil/trace.go:171","msg":"trace[13025519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3450; }","duration":"106.479781ms","start":"2026-03-05T06:45:39.092616Z","end":"2026-03-05T06:45:39.199096Z","steps":["trace[13025519] 'agreement among raft nodes before linearized reading' (duration: 106.330987ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:46:59.890872Z","caller":"traceutil/trace.go:171","msg":"trace[843934435] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"236.491855ms","start":"2026-03-05T06:46:59.654358Z","end":"2026-03-05T06:46:59.89085Z","steps":["trace[843934435] 'process raft request' (duration: 236.322161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:47:00.034478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.041789ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-05T06:47:00.03477Z","caller":"traceutil/trace.go:171","msg":"trace[111954183] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"136.544892ms","start":"2026-03-05T06:46:59.898207Z","end":"2026-03-05T06:47:00.034752Z","steps":["trace[111954183] 'process raft request' (duration: 136.374648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:47:20.096482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.81056ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-05T06:49:01.31738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2791} {"level":"info","ts":"2026-03-05T06:49:01.375039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2791,"took":"56.183438ms","hash":148635628,"current-db-size-bytes":41840640,"current-db-size":"42 MB","current-db-size-in-use-bytes":31457280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-05T06:49:01.37509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":148635628,"revision":2791,"compact-revision":-1} {"level":"info","ts":"2026-03-05T06:53:26.603732Z","caller":"traceutil/trace.go:171","msg":"trace[1052429083] transaction","detail":"{read_only:false; response_revision:7613; number_of_response:1; }","duration":"180.030032ms","start":"2026-03-05T06:53:26.42367Z","end":"2026-03-05T06:53:26.6037Z","steps":["trace[1052429083] 'process raft request' (duration: 160.153621ms)","trace[1052429083] 'compare' (duration: 19.260386ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T06:53:26.603762Z","caller":"traceutil/trace.go:171","msg":"trace[148971094] linearizableReadLoop","detail":"{readStateIndex:7896; appliedIndex:7895; }","duration":"179.623032ms","start":"2026-03-05T06:53:26.423873Z","end":"2026-03-05T06:53:26.603496Z","steps":["trace[148971094] 'read index received' (duration: 159.905065ms)","trace[148971094] 'applied index is now lower than readState.Index' (duration: 19.716897ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T06:53:26.603931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.047082ms","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-03-05T06:53:26.603983Z","caller":"traceutil/trace.go:171","msg":"trace[849908556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7613; }","duration":"180.173985ms","start":"2026-03-05T06:53:26.423796Z","end":"2026-03-05T06:53:26.60397Z","steps":["trace[849908556] 'agreement among raft nodes before linearized reading' (duration: 179.986951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T06:53:26.604157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.438939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-05T06:53:26.6042Z","caller":"traceutil/trace.go:171","msg":"trace[1881971656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7613; }","duration":"155.516521ms","start":"2026-03-05T06:53:26.448672Z","end":"2026-03-05T06:53:26.604189Z","steps":["trace[1881971656] 'agreement among raft nodes before linearized reading' (duration: 155.245205ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:54:01.324277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5812} {"level":"info","ts":"2026-03-05T06:54:01.413928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5812,"took":"87.727337ms","hash":977408949,"current-db-size-bytes":43003904,"current-db-size":"43 MB","current-db-size-in-use-bytes":23199744,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-05T06:54:01.414004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":977408949,"revision":5812,"compact-revision":2791} {"level":"info","ts":"2026-03-05T06:54:07.954173Z","caller":"traceutil/trace.go:171","msg":"trace[1738874283] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"114.824528ms","start":"2026-03-05T06:54:07.839322Z","end":"2026-03-05T06:54:07.954146Z","steps":["trace[1738874283] 'process raft request' (duration: 112.168665ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T06:57:46.002242Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cfc9fec265e3384a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-05T06:57:46.00751Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-05T06:57:46.007581Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-05T06:59:01.333069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7840} {"level":"info","ts":"2026-03-05T06:59:01.398588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7840,"took":"63.441441ms","hash":651194693,"current-db-size-bytes":43003904,"current-db-size":"43 MB","current-db-size-in-use-bytes":27926528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-05T06:59:01.398643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":651194693,"revision":7840,"compact-revision":5812} {"level":"info","ts":"2026-03-05T06:59:04.696247Z","caller":"traceutil/trace.go:171","msg":"trace[1982814269] transaction","detail":"{read_only:false; response_revision:10342; number_of_response:1; }","duration":"151.033995ms","start":"2026-03-05T06:59:04.545178Z","end":"2026-03-05T06:59:04.696212Z","steps":["trace[1982814269] 'process raft request' (duration: 129.416043ms)","trace[1982814269] 'compare' (duration: 21.426467ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T06:59:05.507195Z","caller":"traceutil/trace.go:171","msg":"trace[1252449552] transaction","detail":"{read_only:false; response_revision:10344; number_of_response:1; }","duration":"128.913001ms","start":"2026-03-05T06:59:05.378256Z","end":"2026-03-05T06:59:05.507169Z","steps":["trace[1252449552] 'process raft request' (duration: 128.711996ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:04:01.340884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10322} {"level":"info","ts":"2026-03-05T07:04:01.426981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10322,"took":"83.806023ms","hash":2382859565,"current-db-size-bytes":43003904,"current-db-size":"43 MB","current-db-size-in-use-bytes":24805376,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-05T07:04:01.427056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2382859565,"revision":10322,"compact-revision":7840} {"level":"info","ts":"2026-03-05T07:05:07.651672Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000003334.wal"} {"level":"warn","ts":"2026-03-05T07:05:31.481475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.185168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:05:31.481573Z","caller":"traceutil/trace.go:171","msg":"trace[15566836] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:12793; }","duration":"102.346892ms","start":"2026-03-05T07:05:31.379207Z","end":"2026-03-05T07:05:31.481554Z","steps":["trace[15566836] 'count revisions from in-memory index tree' (duration: 102.099057ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:05:31.617145Z","caller":"traceutil/trace.go:171","msg":"trace[1582741106] linearizableReadLoop","detail":"{readStateIndex:13257; appliedIndex:13256; }","duration":"218.915411ms","start":"2026-03-05T07:05:31.398204Z","end":"2026-03-05T07:05:31.61712Z","steps":["trace[1582741106] 'read index received' (duration: 218.781028ms)","trace[1582741106] 'applied index is now lower than readState.Index' (duration: 132.773µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T07:05:31.617181Z","caller":"traceutil/trace.go:171","msg":"trace[1756389567] transaction","detail":"{read_only:false; response_revision:12794; number_of_response:1; }","duration":"220.298144ms","start":"2026-03-05T07:05:31.39685Z","end":"2026-03-05T07:05:31.617148Z","steps":["trace[1756389567] 'process raft request' (duration: 220.109889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:05:31.617638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.408433ms","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-03-05T07:05:31.617714Z","caller":"traceutil/trace.go:171","msg":"trace[303073065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12794; }","duration":"219.532125ms","start":"2026-03-05T07:05:31.398165Z","end":"2026-03-05T07:05:31.617697Z","steps":["trace[303073065] 'agreement among raft nodes before linearized reading' (duration: 219.080084ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:05:31.627186Z","caller":"traceutil/trace.go:171","msg":"trace[18529097] transaction","detail":"{read_only:false; response_revision:12795; number_of_response:1; }","duration":"221.123713ms","start":"2026-03-05T07:05:31.406048Z","end":"2026-03-05T07:05:31.627172Z","steps":["trace[18529097] 'process raft request' (duration: 221.051721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:05:31.627441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.721066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:05:31.627541Z","caller":"traceutil/trace.go:171","msg":"trace[1884113553] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:12795; }","duration":"155.860599ms","start":"2026-03-05T07:05:31.471655Z","end":"2026-03-05T07:05:31.627515Z","steps":["trace[1884113553] 'agreement among raft nodes before linearized reading' (duration: 155.623203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:05:31.628388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.836726ms","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-03-05T07:05:31.628463Z","caller":"traceutil/trace.go:171","msg":"trace[1300426312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12795; }","duration":"225.932907ms","start":"2026-03-05T07:05:31.402513Z","end":"2026-03-05T07:05:31.628445Z","steps":["trace[1300426312] 'agreement among raft nodes before linearized reading' (duration: 224.675908ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:07:04.612926Z","caller":"traceutil/trace.go:171","msg":"trace[250539775] transaction","detail":"{read_only:false; response_revision:13939; number_of_response:1; }","duration":"108.886947ms","start":"2026-03-05T07:07:04.504008Z","end":"2026-03-05T07:07:04.612894Z","steps":["trace[250539775] 'process raft request' (duration: 108.602399ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:09:01.347293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12211} {"level":"info","ts":"2026-03-05T07:09:01.422708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12211,"took":"72.725891ms","hash":1658214622,"current-db-size-bytes":43003904,"current-db-size":"43 MB","current-db-size-in-use-bytes":28393472,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-05T07:09:01.422767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1658214622,"revision":12211,"compact-revision":10322} {"level":"warn","ts":"2026-03-05T07:13:04.985576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.75854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T07:13:04.985676Z","caller":"traceutil/trace.go:171","msg":"trace[1729489620] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16343; }","duration":"206.899114ms","start":"2026-03-05T07:13:04.778758Z","end":"2026-03-05T07:13:04.985657Z","steps":["trace[1729489620] 'range keys from in-memory index tree' (duration: 206.688709ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:13:05.304538Z","caller":"traceutil/trace.go:171","msg":"trace[2035717507] transaction","detail":"{read_only:false; response_revision:16344; number_of_response:1; }","duration":"139.92526ms","start":"2026-03-05T07:13:05.1646Z","end":"2026-03-05T07:13:05.304525Z","steps":["trace[2035717507] 'process raft request' (duration: 139.839098ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T07:13:05.330907Z","caller":"traceutil/trace.go:171","msg":"trace[826134267] transaction","detail":"{read_only:false; response_revision:16345; number_of_response:1; }","duration":"166.038777ms","start":"2026-03-05T07:13:05.164821Z","end":"2026-03-05T07:13:05.33086Z","steps":["trace[826134267] 'process raft request' (duration: 165.768451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:13:05.844616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.809909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-05T07:13:05.844723Z","caller":"traceutil/trace.go:171","msg":"trace[1295234003] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:16346; }","duration":"405.977843ms","start":"2026-03-05T07:13:05.438725Z","end":"2026-03-05T07:13:05.844703Z","steps":["trace[1295234003] 'count revisions from in-memory index tree' (duration: 405.728468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:13:05.844822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:13:05.438702Z","time spent":"406.055135ms","remote":"127.0.0.1:47212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":53,"response size":31,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-03-05T07:13:05.844826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.444411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-03-05T07:13:05.844906Z","caller":"traceutil/trace.go:171","msg":"trace[378416409] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16346; }","duration":"167.574134ms","start":"2026-03-05T07:13:05.677309Z","end":"2026-03-05T07:13:05.844883Z","steps":["trace[378416409] 'range keys from in-memory index tree' (duration: 167.313628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:13:05.844985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.139265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-xjnln\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-03-05T07:13:05.845039Z","caller":"traceutil/trace.go:171","msg":"trace[1099482255] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-xjnln; range_end:; response_count:1; response_revision:16346; }","duration":"345.255457ms","start":"2026-03-05T07:13:05.499768Z","end":"2026-03-05T07:13:05.845024Z","steps":["trace[1099482255] 'range keys from in-memory index tree' (duration: 344.95227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T07:13:05.845075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T07:13:05.499748Z","time spent":"345.318558ms","remote":"127.0.0.1:47170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22117,"request content":"key:\"/registry/pods/openstack/nova-compute-default-xjnln\" "} {"level":"info","ts":"2026-03-05T07:14:01.354157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14615} {"level":"info","ts":"2026-03-05T07:14:01.449864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14615,"took":"92.58141ms","hash":3718020192,"current-db-size-bytes":43868160,"current-db-size":"44 MB","current-db-size-in-use-bytes":28295168,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-05T07:14:01.449912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3718020192,"revision":14615,"compact-revision":12211} {"level":"info","ts":"2026-03-05T07:19:01.361135Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16701} {"level":"info","ts":"2026-03-05T07:19:01.452129Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16701,"took":"88.457373ms","hash":4206390919,"current-db-size-bytes":43868160,"current-db-size":"44 MB","current-db-size-in-use-bytes":21983232,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-05T07:19:01.45218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4206390919,"revision":16701,"compact-revision":14615}