{"level":"warn","ts":"2026-09-12T03:58:42.726662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-12T03:58:42.726787Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27: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.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27: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-09-12T03:58:42.726912Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-12T03:58:42.726926Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-12T03:58:42.726948Z","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-09-12T03:58:42.727704Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-09-12T03:58:42.727898Z","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://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27: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-09-12T03:58:42.737394Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.167121ms"} {"level":"info","ts":"2026-09-12T03:58:42.748492Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-09-12T03:58:42.748588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-09-12T03:58:42.748613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-09-12T03:58:42.748622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-12T03:58:42.74867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-09-12T03:58:42.74873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-09-12T03:58:42.755666Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-12T03:58:42.757199Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-12T03:58:42.75862Z","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-09-12T03:58:42.760419Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-12T03:58:42.760589Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-12T03:58:42.760698Z","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-09-12T03:58:42.760743Z","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-09-12T03:58:42.760752Z","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-09-12T03:58:42.763004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-09-12T03:58:42.763142Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-12T03:58:42.764693Z","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-09-12T03:58:42.764858Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-12T03:58:42.764896Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-12T03:58:42.765102Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-12T03:58:42.765139Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-12T03:58:42.849891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-09-12T03:58:42.849996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-12T03:58:42.850121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-09-12T03:58:42.850157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-09-12T03:58:42.850189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-12T03:58:42.85021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-09-12T03:58:42.850221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-12T03:58:42.851808Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T03:58:42.852734Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-12T03:58:42.852753Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-12T03:58:42.852874Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-12T03:58:42.853079Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-12T03:58:42.853129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-12T03:58:42.853246Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T03:58:42.853376Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T03:58:42.853434Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-12T03:58:42.855992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-09-12T03:58:42.856178Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-12T03:58:46.014185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.173736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-12T03:58:46.01434Z","caller":"traceutil/trace.go:171","msg":"trace[630953437] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"288.58665ms","start":"2026-09-12T03:58:45.725732Z","end":"2026-09-12T03:58:46.014318Z","steps":["trace[630953437] 'read index received' (duration: 63.547478ms)","trace[630953437] 'applied index is now lower than readState.Index' (duration: 225.037612ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T03:58:46.01447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.73834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-12T03:58:46.014502Z","caller":"traceutil/trace.go:171","msg":"trace[1867485321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"288.811291ms","start":"2026-09-12T03:58:45.725679Z","end":"2026-09-12T03:58:46.01449Z","steps":["trace[1867485321] 'agreement among raft nodes before linearized reading' (duration: 288.70458ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:58:46.01447Z","caller":"traceutil/trace.go:171","msg":"trace[736956560] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"328.340476ms","start":"2026-09-12T03:58:45.686035Z","end":"2026-09-12T03:58:46.014375Z","steps":["trace[736956560] 'process raft request' (duration: 103.324844ms)","trace[736956560] 'compare' (duration: 223.995345ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T03:58:46.014825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T03:58:45.686004Z","time spent":"328.659778ms","remote":"127.0.0.1:35600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-12T03:58:46.33745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.874457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-12T03:58:46.337555Z","caller":"traceutil/trace.go:171","msg":"trace[466086573] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"317.242ms","start":"2026-09-12T03:58:46.020294Z","end":"2026-09-12T03:58:46.337536Z","steps":["trace[466086573] 'process raft request' (duration: 146.193842ms)","trace[466086573] 'compare' (duration: 170.709425ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T03:58:46.337609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T03:58:46.020261Z","time spent":"317.32689ms","remote":"127.0.0.1:35600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-12T03:58:46.445254Z","caller":"traceutil/trace.go:171","msg":"trace[1309577770] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"101.463831ms","start":"2026-09-12T03:58:46.343746Z","end":"2026-09-12T03:58:46.44521Z","steps":["trace[1309577770] 'process raft request' (duration: 101.064649ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:34.534222Z","caller":"traceutil/trace.go:171","msg":"trace[1620196004] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"143.641371ms","start":"2026-09-12T03:59:34.390548Z","end":"2026-09-12T03:59:34.53419Z","steps":["trace[1620196004] 'read index received' (duration: 142.699848ms)","trace[1620196004] 'applied index is now lower than readState.Index' (duration: 940.523µs)"],"step_count":2} {"level":"warn","ts":"2026-09-12T03:59:34.534492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.925742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-llvp5\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-12T03:59:34.534575Z","caller":"traceutil/trace.go:171","msg":"trace[280210974] range","detail":"{range_begin:/registry/pods/kube-system/cilium-llvp5; range_end:; response_count:1; response_revision:617; }","duration":"144.106928ms","start":"2026-09-12T03:59:34.390451Z","end":"2026-09-12T03:59:34.534558Z","steps":["trace[280210974] 'agreement among raft nodes before linearized reading' (duration: 143.858638ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T03:59:34.534592Z","caller":"traceutil/trace.go:171","msg":"trace[71502244] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"196.010321ms","start":"2026-09-12T03:59:34.338378Z","end":"2026-09-12T03:59:34.534388Z","steps":["trace[71502244] 'process raft request' (duration: 195.185741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:35.179113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.653655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T03:59:35.179367Z","caller":"traceutil/trace.go:171","msg":"trace[948835134] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"619.785813ms","start":"2026-09-12T03:59:34.559549Z","end":"2026-09-12T03:59:35.179335Z","steps":["trace[948835134] 'process raft request' (duration: 36.103681ms)","trace[948835134] 'compare' (duration: 582.471689ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T03:59:35.179483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T03:59:34.554835Z","time spent":"624.600295ms","remote":"127.0.0.1:35470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T03:59:35.179701Z","caller":"traceutil/trace.go:171","msg":"trace[15918672] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"561.653119ms","start":"2026-09-12T03:59:34.618024Z","end":"2026-09-12T03:59:35.179677Z","steps":["trace[15918672] 'process raft request' (duration: 561.216593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:35.17978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T03:59:34.617985Z","time spent":"561.757003ms","remote":"127.0.0.1:35532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-12T03:59:35.18009Z","caller":"traceutil/trace.go:171","msg":"trace[1814756307] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"346.795798ms","start":"2026-09-12T03:59:34.833232Z","end":"2026-09-12T03:59:35.180028Z","steps":["trace[1814756307] 'read index received' (duration: 74.182µs)","trace[1814756307] 'applied index is now lower than readState.Index' (duration: 346.717226ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T03:59:35.180267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.024146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T03:59:35.180359Z","caller":"traceutil/trace.go:171","msg":"trace[1324011646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"347.13113ms","start":"2026-09-12T03:59:34.833208Z","end":"2026-09-12T03:59:35.180339Z","steps":["trace[1324011646] 'agreement among raft nodes before linearized reading' (duration: 346.934233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:35.180402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T03:59:34.833112Z","time spent":"347.283076ms","remote":"127.0.0.1:35326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-12T03:59:50.755959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.40083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m9tx5\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-09-12T03:59:50.75605Z","caller":"traceutil/trace.go:171","msg":"trace[1705157144] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m9tx5; range_end:; response_count:1; response_revision:778; }","duration":"256.534064ms","start":"2026-09-12T03:59:50.499498Z","end":"2026-09-12T03:59:50.756032Z","steps":["trace[1705157144] 'range keys from in-memory index tree' (duration: 256.276076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T03:59:50.756151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.280095ms","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-09-12T03:59:50.756425Z","caller":"traceutil/trace.go:171","msg":"trace[2118754583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"160.832471ms","start":"2026-09-12T03:59:50.595577Z","end":"2026-09-12T03:59:50.756409Z","steps":["trace[2118754583] 'range keys from in-memory index tree' (duration: 160.014636ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:00:20.831587Z","caller":"traceutil/trace.go:171","msg":"trace[355155339] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"176.515948ms","start":"2026-09-12T04:00:20.655047Z","end":"2026-09-12T04:00:20.831563Z","steps":["trace[355155339] 'process raft request' (duration: 176.393735ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:01:08.726511Z","caller":"traceutil/trace.go:171","msg":"trace[605952693] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"147.938639ms","start":"2026-09-12T04:01:08.578545Z","end":"2026-09-12T04:01:08.726484Z","steps":["trace[605952693] 'read index received' (duration: 147.727196ms)","trace[605952693] 'applied index is now lower than readState.Index' (duration: 210.733µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:01:08.7266Z","caller":"traceutil/trace.go:171","msg":"trace[792077655] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"319.18175ms","start":"2026-09-12T04:01:08.407376Z","end":"2026-09-12T04:01:08.726558Z","steps":["trace[792077655] 'process raft request' (duration: 318.952687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:08.726706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.149283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-84c2d21c-f02b-4d35-95a3-289d973d6f32\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-12T04:01:08.726744Z","caller":"traceutil/trace.go:171","msg":"trace[1833168629] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-84c2d21c-f02b-4d35-95a3-289d973d6f32; range_end:; response_count:1; response_revision:1856; }","duration":"148.231575ms","start":"2026-09-12T04:01:08.578499Z","end":"2026-09-12T04:01:08.726731Z","steps":["trace[1833168629] 'agreement among raft nodes before linearized reading' (duration: 148.085043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:08.72684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:01:08.407345Z","time spent":"319.380373ms","remote":"127.0.0.1:35548","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-09-12T04:01:18.259257Z","caller":"traceutil/trace.go:171","msg":"trace[1654133977] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"164.045307ms","start":"2026-09-12T04:01:18.095186Z","end":"2026-09-12T04:01:18.259231Z","steps":["trace[1654133977] 'process raft request' (duration: 79.472652ms)","trace[1654133977] 'compare' (duration: 84.424223ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:01:43.882942Z","caller":"traceutil/trace.go:171","msg":"trace[467110044] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"119.287645ms","start":"2026-09-12T04:01:43.763628Z","end":"2026-09-12T04:01:43.882915Z","steps":["trace[467110044] 'process raft request' (duration: 118.677986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:44.323401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.493182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-12T04:01:44.324053Z","caller":"traceutil/trace.go:171","msg":"trace[81035453] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"200.436822ms","start":"2026-09-12T04:01:44.123551Z","end":"2026-09-12T04:01:44.323988Z","steps":["trace[81035453] 'process raft request' (duration: 49.191399ms)","trace[81035453] 'compare' (duration: 150.282479ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:01:44.532961Z","caller":"traceutil/trace.go:171","msg":"trace[467665705] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"343.721428ms","start":"2026-09-12T04:01:44.189202Z","end":"2026-09-12T04:01:44.532923Z","steps":["trace[467665705] 'read index received' (duration: 331.038209ms)","trace[467665705] 'applied index is now lower than readState.Index' (duration: 12.682259ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:01:44.533319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.109463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:01:44.533363Z","caller":"traceutil/trace.go:171","msg":"trace[1870298387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2107; }","duration":"344.243874ms","start":"2026-09-12T04:01:44.189105Z","end":"2026-09-12T04:01:44.533349Z","steps":["trace[1870298387] 'agreement among raft nodes before linearized reading' (duration: 344.158953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:44.533397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:01:44.189083Z","time spent":"344.304216ms","remote":"127.0.0.1:40352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-12T04:01:44.533418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.721362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-12T04:01:44.533719Z","caller":"traceutil/trace.go:171","msg":"trace[1821547000] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2107; }","duration":"287.058728ms","start":"2026-09-12T04:01:44.246641Z","end":"2026-09-12T04:01:44.5337Z","steps":["trace[1821547000] 'agreement among raft nodes before linearized reading' (duration: 286.711192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:44.533787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.445587ms","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-09-12T04:01:44.533833Z","caller":"traceutil/trace.go:171","msg":"trace[1747844655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2107; }","duration":"118.507758ms","start":"2026-09-12T04:01:44.415313Z","end":"2026-09-12T04:01:44.533821Z","steps":["trace[1747844655] 'agreement among raft nodes before linearized reading' (duration: 118.362986ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:01:44.533698Z","caller":"traceutil/trace.go:171","msg":"trace[173381137] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"408.449137ms","start":"2026-09-12T04:01:44.124819Z","end":"2026-09-12T04:01:44.533268Z","steps":["trace[173381137] 'process raft request' (duration: 395.493535ms)","trace[173381137] 'compare' (duration: 12.375014ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:01:44.533984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.475392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-12T04:01:44.534017Z","caller":"traceutil/trace.go:171","msg":"trace[1355733052] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2107; }","duration":"243.533832ms","start":"2026-09-12T04:01:44.290475Z","end":"2026-09-12T04:01:44.534008Z","steps":["trace[1355733052] 'agreement among raft nodes before linearized reading' (duration: 243.458111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:44.534122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:01:44.124805Z","time spent":"409.207589ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-12T04:01:44.534234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.335142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-12T04:01:44.534269Z","caller":"traceutil/trace.go:171","msg":"trace[1018458822] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2107; }","duration":"136.394203ms","start":"2026-09-12T04:01:44.397866Z","end":"2026-09-12T04:01:44.534261Z","steps":["trace[1018458822] 'agreement among raft nodes before linearized reading' (duration: 136.304812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:44.534623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.495937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-12T04:01:44.534823Z","caller":"traceutil/trace.go:171","msg":"trace[950172999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2107; }","duration":"122.703681ms","start":"2026-09-12T04:01:44.412058Z","end":"2026-09-12T04:01:44.534762Z","steps":["trace[950172999] 'agreement among raft nodes before linearized reading' (duration: 121.535364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:44.846557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.922947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:114848"} {"level":"info","ts":"2026-09-12T04:01:44.846629Z","caller":"traceutil/trace.go:171","msg":"trace[1976990680] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2110; }","duration":"194.075029ms","start":"2026-09-12T04:01:44.652537Z","end":"2026-09-12T04:01:44.846612Z","steps":["trace[1976990680] 'range keys from in-memory index tree' (duration: 193.604533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:01:44.846813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.475924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-09-12T04:01:44.846863Z","caller":"traceutil/trace.go:171","msg":"trace[1077273651] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2110; }","duration":"192.581516ms","start":"2026-09-12T04:01:44.654268Z","end":"2026-09-12T04:01:44.84685Z","steps":["trace[1077273651] 'range keys from in-memory index tree' (duration: 191.913697ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:02:52.147115Z","caller":"traceutil/trace.go:171","msg":"trace[1282340246] linearizableReadLoop","detail":"{readStateIndex:2674; appliedIndex:2673; }","duration":"140.027015ms","start":"2026-09-12T04:02:52.007065Z","end":"2026-09-12T04:02:52.147092Z","steps":["trace[1282340246] 'read index received' (duration: 122.716774ms)","trace[1282340246] 'applied index is now lower than readState.Index' (duration: 17.309421ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:02:52.147229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.150377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:02:52.147261Z","caller":"traceutil/trace.go:171","msg":"trace[1073171997] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2573; }","duration":"140.239958ms","start":"2026-09-12T04:02:52.007011Z","end":"2026-09-12T04:02:52.147251Z","steps":["trace[1073171997] 'agreement among raft nodes before linearized reading' (duration: 140.168097ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:02:52.147358Z","caller":"traceutil/trace.go:171","msg":"trace[331336159] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"234.278734ms","start":"2026-09-12T04:02:51.913026Z","end":"2026-09-12T04:02:52.147304Z","steps":["trace[331336159] 'process raft request' (duration: 216.74187ms)","trace[331336159] 'compare' (duration: 16.836835ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:07:53.941495Z","caller":"traceutil/trace.go:171","msg":"trace[1763025670] linearizableReadLoop","detail":"{readStateIndex:5069; appliedIndex:5068; }","duration":"108.130918ms","start":"2026-09-12T04:07:53.833343Z","end":"2026-09-12T04:07:53.941474Z","steps":["trace[1763025670] 'read index received' (duration: 107.956385ms)","trace[1763025670] 'applied index is now lower than readState.Index' (duration: 173.643µs)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:07:53.941779Z","caller":"traceutil/trace.go:171","msg":"trace[985462463] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"109.90276ms","start":"2026-09-12T04:07:53.831864Z","end":"2026-09-12T04:07:53.941767Z","steps":["trace[985462463] 'process raft request' (duration: 109.476043ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:07:53.942081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.725529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:07:53.942114Z","caller":"traceutil/trace.go:171","msg":"trace[1080763612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4874; }","duration":"108.805181ms","start":"2026-09-12T04:07:53.833298Z","end":"2026-09-12T04:07:53.942103Z","steps":["trace[1080763612] 'agreement among raft nodes before linearized reading' (duration: 108.73912ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:07:54.148403Z","caller":"traceutil/trace.go:171","msg":"trace[649613590] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"203.279068ms","start":"2026-09-12T04:07:53.945093Z","end":"2026-09-12T04:07:54.148373Z","steps":["trace[649613590] 'process raft request' (duration: 112.858955ms)","trace[649613590] 'compare' (duration: 90.192368ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:07:54.14841Z","caller":"traceutil/trace.go:171","msg":"trace[155035119] linearizableReadLoop","detail":"{readStateIndex:5070; appliedIndex:5069; }","duration":"200.539766ms","start":"2026-09-12T04:07:53.947841Z","end":"2026-09-12T04:07:54.148381Z","steps":["trace[155035119] 'read index received' (duration: 110.121343ms)","trace[155035119] 'applied index is now lower than readState.Index' (duration: 90.416263ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:07:54.148755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.891962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a0cac15f-ca79-4cbb-8961-1512f8a75278\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-12T04:07:54.148875Z","caller":"traceutil/trace.go:171","msg":"trace[480724294] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a0cac15f-ca79-4cbb-8961-1512f8a75278; range_end:; response_count:1; response_revision:4875; }","duration":"201.034605ms","start":"2026-09-12T04:07:53.94781Z","end":"2026-09-12T04:07:54.148845Z","steps":["trace[480724294] 'agreement among raft nodes before linearized reading' (duration: 200.74221ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:07:54.18666Z","caller":"traceutil/trace.go:171","msg":"trace[1129312351] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"238.59683ms","start":"2026-09-12T04:07:53.948039Z","end":"2026-09-12T04:07:54.186636Z","steps":["trace[1129312351] 'process raft request' (duration: 238.413345ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:07:54.186846Z","caller":"traceutil/trace.go:171","msg":"trace[2011115629] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"224.942743ms","start":"2026-09-12T04:07:53.961865Z","end":"2026-09-12T04:07:54.186808Z","steps":["trace[2011115629] 'process raft request' (duration: 224.697988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:07:54.196557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.586913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-12T04:07:54.196702Z","caller":"traceutil/trace.go:171","msg":"trace[1190099792] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4878; }","duration":"226.745066ms","start":"2026-09-12T04:07:53.969896Z","end":"2026-09-12T04:07:54.196641Z","steps":["trace[1190099792] 'agreement among raft nodes before linearized reading' (duration: 226.41417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:08:00.757619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.59644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-12T04:08:00.757754Z","caller":"traceutil/trace.go:171","msg":"trace[328063510] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"560.578748ms","start":"2026-09-12T04:08:00.197143Z","end":"2026-09-12T04:08:00.757722Z","steps":["trace[328063510] 'process raft request' (duration: 179.811905ms)","trace[328063510] 'compare' (duration: 379.919847ms)"],"step_count":2} {"level":"info","ts":"2026-09-12T04:08:00.75782Z","caller":"traceutil/trace.go:171","msg":"trace[1807380695] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"543.739116ms","start":"2026-09-12T04:08:00.214063Z","end":"2026-09-12T04:08:00.757802Z","steps":["trace[1807380695] 'process raft request' (duration: 543.653164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:08:00.757885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:08:00.197122Z","time spent":"560.69855ms","remote":"127.0.0.1:35378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2581,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-12T04:08:00.757891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:08:00.214051Z","time spent":"543.802127ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-12T04:08:00.757901Z","caller":"traceutil/trace.go:171","msg":"trace[103072247] linearizableReadLoop","detail":"{readStateIndex:5210; appliedIndex:5209; }","duration":"546.886764ms","start":"2026-09-12T04:08:00.210925Z","end":"2026-09-12T04:08:00.757812Z","steps":["trace[103072247] 'read index received' (duration: 166.033439ms)","trace[103072247] 'applied index is now lower than readState.Index' (duration: 380.851405ms)"],"step_count":2} {"level":"warn","ts":"2026-09-12T04:08:00.758309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.665722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bcfc5d85-979a-4934-a08f-ddc9c2910eae\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-12T04:08:00.758354Z","caller":"traceutil/trace.go:171","msg":"trace[1224784489] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bcfc5d85-979a-4934-a08f-ddc9c2910eae; range_end:; response_count:1; response_revision:5014; }","duration":"352.745354ms","start":"2026-09-12T04:08:00.405595Z","end":"2026-09-12T04:08:00.75834Z","steps":["trace[1224784489] 'agreement among raft nodes before linearized reading' (duration: 352.622292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:08:00.758355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.406734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-12T04:08:00.758382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:08:00.405571Z","time spent":"352.804145ms","remote":"127.0.0.1:35470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bcfc5d85-979a-4934-a08f-ddc9c2910eae\" "} {"level":"warn","ts":"2026-09-12T04:08:00.758422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.534452ms","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-09-12T04:08:00.758468Z","caller":"traceutil/trace.go:171","msg":"trace[1107407950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5014; }","duration":"102.938879ms","start":"2026-09-12T04:08:00.655518Z","end":"2026-09-12T04:08:00.758457Z","steps":["trace[1107407950] 'agreement among raft nodes before linearized reading' (duration: 102.792557ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:08:00.758481Z","caller":"traceutil/trace.go:171","msg":"trace[1944586530] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5014; }","duration":"547.578968ms","start":"2026-09-12T04:08:00.210887Z","end":"2026-09-12T04:08:00.758466Z","steps":["trace[1944586530] 'agreement among raft nodes before linearized reading' (duration: 547.252583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-12T04:08:00.758524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-12T04:08:00.21087Z","time spent":"547.641079ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-12T04:08:00.758568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.420678ms","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-09-12T04:08:00.758599Z","caller":"traceutil/trace.go:171","msg":"trace[878900327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5014; }","duration":"110.482529ms","start":"2026-09-12T04:08:00.648108Z","end":"2026-09-12T04:08:00.75859Z","steps":["trace[878900327] 'agreement among raft nodes before linearized reading' (duration: 110.397508ms)"],"step_count":1} {"level":"info","ts":"2026-09-12T04:08:43.149525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2852} {"level":"info","ts":"2026-09-12T04:08:43.225106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2852,"took":"74.118139ms","hash":1356532991,"current-db-size-bytes":52690944,"current-db-size":"53 MB","current-db-size-in-use-bytes":28340224,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-12T04:08:43.225179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1356532991,"revision":2852,"compact-revision":-1} {"level":"info","ts":"2026-09-12T04:13:43.156895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5434} {"level":"info","ts":"2026-09-12T04:13:43.233433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5434,"took":"74.894052ms","hash":1680673736,"current-db-size-bytes":52690944,"current-db-size":"53 MB","current-db-size-in-use-bytes":18722816,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-12T04:13:43.233503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1680673736,"revision":5434,"compact-revision":2852}