{"level":"warn","ts":"2026-08-12T17:47:40.451048Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T17:47:40.451151Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.105:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.105:2380","--initial-cluster=instance=https://199.19.213.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.105:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-12T17:47:40.451222Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T17:47:40.451231Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-08-12T17:47:40.451249Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-12T17:47:40.451906Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"]} {"level":"info","ts":"2026-08-12T17:47:40.451979Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.105:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-12T17:47:40.455691Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.488847ms"} {"level":"info","ts":"2026-08-12T17:47:40.461068Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"eb6db91b2c71522f","cluster-id":"f43476f3367c14fb"} {"level":"info","ts":"2026-08-12T17:47:40.461145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T17:47:40.461165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 0"} {"level":"info","ts":"2026-08-12T17:47:40.46118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft eb6db91b2c71522f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T17:47:40.461186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 1"} {"level":"info","ts":"2026-08-12T17:47:40.461216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"warn","ts":"2026-08-12T17:47:40.467854Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T17:47:40.469416Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T17:47:40.470702Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-12T17:47:40.475343Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"eb6db91b2c71522f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T17:47:40.476323Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:47:40.477332Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"eb6db91b2c71522f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T17:47:40.47823Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:47:40.478315Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T17:47:40.478313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"info","ts":"2026-08-12T17:47:40.478607Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","added-peer-id":"eb6db91b2c71522f","added-peer-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-08-12T17:47:40.4806Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-12T17:47:40.48079Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-08-12T17:47:40.480844Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"eb6db91b2c71522f","initial-advertise-peer-urls":["https://199.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T17:47:40.480843Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-08-12T17:47:40.480913Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T17:47:40.861691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T17:47:40.861833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T17:47:40.861872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgPreVoteResp from eb6db91b2c71522f at term 1"} {"level":"info","ts":"2026-08-12T17:47:40.861896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became candidate at term 2"} {"level":"info","ts":"2026-08-12T17:47:40.861908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgVoteResp from eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-08-12T17:47:40.861925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became leader at term 2"} {"level":"info","ts":"2026-08-12T17:47:40.861967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: eb6db91b2c71522f elected leader eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-08-12T17:47:40.863124Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"eb6db91b2c71522f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.105:2379]}","request-path":"/0/members/eb6db91b2c71522f/attributes","cluster-id":"f43476f3367c14fb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T17:47:40.863166Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:47:40.86356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T17:47:40.863886Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T17:47:40.864033Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T17:47:40.8641Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T17:47:40.864248Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:47:40.864374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:47:40.864413Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T17:47:40.867487Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T17:47:40.870104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.105:2379"} {"level":"info","ts":"2026-08-12T17:47:42.68167Z","caller":"traceutil/trace.go:171","msg":"trace[1352783822] linearizableReadLoop","detail":"{readStateIndex:100; appliedIndex:99; }","duration":"130.941541ms","start":"2026-08-12T17:47:42.550685Z","end":"2026-08-12T17:47:42.681627Z","steps":["trace[1352783822] 'read index received' (duration: 127.034892ms)","trace[1352783822] 'applied index is now lower than readState.Index' (duration: 3.904639ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:47:42.681697Z","caller":"traceutil/trace.go:171","msg":"trace[886649592] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"187.040205ms","start":"2026-08-12T17:47:42.494616Z","end":"2026-08-12T17:47:42.681656Z","steps":["trace[886649592] 'process raft request' (duration: 183.117617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:42.681874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.164167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-12T17:47:42.682193Z","caller":"traceutil/trace.go:171","msg":"trace[29540068] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:95; }","duration":"131.517516ms","start":"2026-08-12T17:47:42.550654Z","end":"2026-08-12T17:47:42.682171Z","steps":["trace[29540068] 'agreement among raft nodes before linearized reading' (duration: 131.091504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:47:47.75474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.195532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-12T17:47:47.75474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.917444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-12T17:47:47.754837Z","caller":"traceutil/trace.go:171","msg":"trace[2043670595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:321; }","duration":"107.342615ms","start":"2026-08-12T17:47:47.647479Z","end":"2026-08-12T17:47:47.754821Z","steps":["trace[2043670595] 'range keys from in-memory index tree' (duration: 107.091409ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:47:47.75488Z","caller":"traceutil/trace.go:171","msg":"trace[971171342] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:321; }","duration":"120.11848ms","start":"2026-08-12T17:47:47.634747Z","end":"2026-08-12T17:47:47.754866Z","steps":["trace[971171342] 'range keys from in-memory index tree' (duration: 119.805122ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:48:23.843754Z","caller":"traceutil/trace.go:171","msg":"trace[49886748] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"186.776278ms","start":"2026-08-12T17:48:23.656955Z","end":"2026-08-12T17:48:23.843731Z","steps":["trace[49886748] 'process raft request' (duration: 186.646225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:48:24.381805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.499346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:48:24.382636Z","caller":"traceutil/trace.go:171","msg":"trace[35913254] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"385.638631ms","start":"2026-08-12T17:48:23.996342Z","end":"2026-08-12T17:48:24.381981Z","steps":["trace[35913254] 'read index received' (duration: 108.274052ms)","trace[35913254] 'applied index is now lower than readState.Index' (duration: 277.363149ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:48:24.382765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.800435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-12T17:48:24.382876Z","caller":"traceutil/trace.go:171","msg":"trace[580424302] range","detail":"{range_begin:/registry/masterleases/199.19.213.105; range_end:; response_count:1; response_revision:633; }","duration":"386.512415ms","start":"2026-08-12T17:48:23.996306Z","end":"2026-08-12T17:48:24.382819Z","steps":["trace[580424302] 'agreement among raft nodes before linearized reading' (duration: 385.766144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:48:24.382953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:48:23.996287Z","time spent":"386.628888ms","remote":"127.0.0.1:33806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.105\" "} {"level":"info","ts":"2026-08-12T17:48:24.383064Z","caller":"traceutil/trace.go:171","msg":"trace[247801547] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"465.893026ms","start":"2026-08-12T17:48:23.916371Z","end":"2026-08-12T17:48:24.382264Z","steps":["trace[247801547] 'process raft request' (duration: 188.30888ms)","trace[247801547] 'compare' (duration: 276.342721ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:48:24.383251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:48:23.91632Z","time spent":"466.810562ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:48:24.389208Z","caller":"traceutil/trace.go:171","msg":"trace[1395581112] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"111.328376ms","start":"2026-08-12T17:48:24.277839Z","end":"2026-08-12T17:48:24.389167Z","steps":["trace[1395581112] 'process raft request' (duration: 106.336678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:48:24.389271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.032355ms","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-08-12T17:48:24.389594Z","caller":"traceutil/trace.go:171","msg":"trace[578956635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"367.342753ms","start":"2026-08-12T17:48:24.022224Z","end":"2026-08-12T17:48:24.389566Z","steps":["trace[578956635] 'agreement among raft nodes before linearized reading' (duration: 366.88205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:48:24.389671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:48:24.022201Z","time spent":"367.431886ms","remote":"127.0.0.1:34076","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-08-12T17:48:25.085607Z","caller":"traceutil/trace.go:171","msg":"trace[1522763190] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"119.844313ms","start":"2026-08-12T17:48:24.965733Z","end":"2026-08-12T17:48:25.085577Z","steps":["trace[1522763190] 'read index received' (duration: 76.400958ms)","trace[1522763190] 'applied index is now lower than readState.Index' (duration: 43.442005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:48:25.085786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.031078ms","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-08-12T17:48:25.085747Z","caller":"traceutil/trace.go:171","msg":"trace[64216439] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"639.940111ms","start":"2026-08-12T17:48:24.445734Z","end":"2026-08-12T17:48:25.085674Z","steps":["trace[64216439] 'process raft request' (duration: 596.442745ms)","trace[64216439] 'compare' (duration: 43.216118ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:48:25.086034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:48:24.445713Z","time spent":"640.200428ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-12T17:48:25.085836Z","caller":"traceutil/trace.go:171","msg":"trace[485140270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"120.14512ms","start":"2026-08-12T17:48:24.965678Z","end":"2026-08-12T17:48:25.085823Z","steps":["trace[485140270] 'agreement among raft nodes before linearized reading' (duration: 120.005146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:48:40.153378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.305042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-flj48\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-12T17:48:40.153457Z","caller":"traceutil/trace.go:171","msg":"trace[2099311671] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-flj48; range_end:; response_count:1; response_revision:798; }","duration":"148.468946ms","start":"2026-08-12T17:48:40.004972Z","end":"2026-08-12T17:48:40.153441Z","steps":["trace[2099311671] 'range keys from in-memory index tree' (duration: 148.051334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:49:57.669687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.817929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:49:57.669952Z","caller":"traceutil/trace.go:171","msg":"trace[1778164278] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"239.254871ms","start":"2026-08-12T17:49:57.430669Z","end":"2026-08-12T17:49:57.669924Z","steps":["trace[1778164278] 'process raft request' (duration: 99.094102ms)","trace[1778164278] 'compare' (duration: 139.665775ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:49:57.670042Z","caller":"traceutil/trace.go:171","msg":"trace[200606805] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"238.933102ms","start":"2026-08-12T17:49:57.431061Z","end":"2026-08-12T17:49:57.669994Z","steps":["trace[200606805] 'process raft request' (duration: 238.741907ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:49:57.670134Z","caller":"traceutil/trace.go:171","msg":"trace[1581596307] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"237.680897ms","start":"2026-08-12T17:49:57.432439Z","end":"2026-08-12T17:49:57.67012Z","steps":["trace[1581596307] 'process raft request' (duration: 237.442821ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:49:57.670149Z","caller":"traceutil/trace.go:171","msg":"trace[1286970053] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1911; }","duration":"193.589387ms","start":"2026-08-12T17:49:57.476524Z","end":"2026-08-12T17:49:57.670114Z","steps":["trace[1286970053] 'read index received' (duration: 53.176382ms)","trace[1286970053] 'applied index is now lower than readState.Index' (duration: 140.411055ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:49:57.670432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.898046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-12T17:49:57.670478Z","caller":"traceutil/trace.go:171","msg":"trace[1395728528] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1857; }","duration":"194.007509ms","start":"2026-08-12T17:49:57.476459Z","end":"2026-08-12T17:49:57.670466Z","steps":["trace[1395728528] 'agreement among raft nodes before linearized reading' (duration: 193.811493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:49:58.028871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.637719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:49:58.029109Z","caller":"traceutil/trace.go:171","msg":"trace[1053120369] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1914; }","duration":"307.319145ms","start":"2026-08-12T17:49:57.721754Z","end":"2026-08-12T17:49:58.029073Z","steps":["trace[1053120369] 'read index received' (duration: 169.394468ms)","trace[1053120369] 'applied index is now lower than readState.Index' (duration: 137.922737ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:49:58.029198Z","caller":"traceutil/trace.go:171","msg":"trace[1622953869] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"351.555608ms","start":"2026-08-12T17:49:57.677581Z","end":"2026-08-12T17:49:58.029137Z","steps":["trace[1622953869] 'process raft request' (duration: 351.426995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:49:58.029331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.580321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-12T17:49:58.029389Z","caller":"traceutil/trace.go:171","msg":"trace[1615786860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"307.639583ms","start":"2026-08-12T17:49:57.721716Z","end":"2026-08-12T17:49:58.029356Z","steps":["trace[1615786860] 'agreement among raft nodes before linearized reading' (duration: 307.488529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:49:58.029398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:49:57.677554Z","time spent":"351.728103ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:49:58.02943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:49:57.721698Z","time spent":"307.722415ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T17:49:58.029435Z","caller":"traceutil/trace.go:171","msg":"trace[394356948] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"353.918134ms","start":"2026-08-12T17:49:57.675458Z","end":"2026-08-12T17:49:58.029376Z","steps":["trace[394356948] 'process raft request' (duration: 215.669488ms)","trace[394356948] 'compare' (duration: 137.482265ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:49:58.02971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:49:57.675431Z","time spent":"354.152191ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:50:06.670177Z","caller":"traceutil/trace.go:171","msg":"trace[717791553] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"121.326018ms","start":"2026-08-12T17:50:06.548818Z","end":"2026-08-12T17:50:06.670144Z","steps":["trace[717791553] 'process raft request' (duration: 120.65985ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:50:41.081957Z","caller":"traceutil/trace.go:171","msg":"trace[1905750340] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"182.096909ms","start":"2026-08-12T17:50:40.899823Z","end":"2026-08-12T17:50:41.08192Z","steps":["trace[1905750340] 'process raft request' (duration: 181.869293ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:50:41.341955Z","caller":"traceutil/trace.go:171","msg":"trace[3673196] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2271; }","duration":"144.327874ms","start":"2026-08-12T17:50:41.197586Z","end":"2026-08-12T17:50:41.341914Z","steps":["trace[3673196] 'read index received' (duration: 100.653446ms)","trace[3673196] 'applied index is now lower than readState.Index' (duration: 43.672818ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:50:41.342169Z","caller":"traceutil/trace.go:171","msg":"trace[1246139876] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"252.56121ms","start":"2026-08-12T17:50:41.089443Z","end":"2026-08-12T17:50:41.342004Z","steps":["trace[1246139876] 'process raft request' (duration: 208.875541ms)","trace[1246139876] 'compare' (duration: 43.37654ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:50:41.342241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.131728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-12T17:50:41.342327Z","caller":"traceutil/trace.go:171","msg":"trace[840580558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2198; }","duration":"136.275371ms","start":"2026-08-12T17:50:41.206033Z","end":"2026-08-12T17:50:41.342308Z","steps":["trace[840580558] 'agreement among raft nodes before linearized reading' (duration: 136.072746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:50:41.342241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.644344ms","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-08-12T17:50:41.342541Z","caller":"traceutil/trace.go:171","msg":"trace[1321223739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2198; }","duration":"144.986603ms","start":"2026-08-12T17:50:41.197515Z","end":"2026-08-12T17:50:41.342501Z","steps":["trace[1321223739] 'agreement among raft nodes before linearized reading' (duration: 144.573391ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:50:41.455529Z","caller":"traceutil/trace.go:171","msg":"trace[996177428] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"109.101339ms","start":"2026-08-12T17:50:41.346394Z","end":"2026-08-12T17:50:41.455495Z","steps":["trace[996177428] 'process raft request' (duration: 102.200208ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:50:41.455555Z","caller":"traceutil/trace.go:171","msg":"trace[178004608] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"108.362698ms","start":"2026-08-12T17:50:41.34717Z","end":"2026-08-12T17:50:41.455533Z","steps":["trace[178004608] 'process raft request' (duration: 108.309557ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:50:41.455603Z","caller":"traceutil/trace.go:171","msg":"trace[607564249] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"109.13824ms","start":"2026-08-12T17:50:41.346425Z","end":"2026-08-12T17:50:41.455563Z","steps":["trace[607564249] 'read index received' (duration: 39.241µs)","trace[607564249] 'applied index is now lower than readState.Index' (duration: 109.096389ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:50:41.455784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.331566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-08-12T17:50:41.455827Z","caller":"traceutil/trace.go:171","msg":"trace[1878922478] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2200; }","duration":"109.413848ms","start":"2026-08-12T17:50:41.346403Z","end":"2026-08-12T17:50:41.455817Z","steps":["trace[1878922478] 'agreement among raft nodes before linearized reading' (duration: 109.246574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:51:59.552553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.9233ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-12T17:51:59.552802Z","caller":"traceutil/trace.go:171","msg":"trace[1040996510] linearizableReadLoop","detail":"{readStateIndex:2893; appliedIndex:2892; }","duration":"108.996031ms","start":"2026-08-12T17:51:59.443773Z","end":"2026-08-12T17:51:59.552769Z","steps":["trace[1040996510] 'read index received' (duration: 53.741µs)","trace[1040996510] 'applied index is now lower than readState.Index' (duration: 108.93971ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:51:59.552988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.184827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T17:51:59.553088Z","caller":"traceutil/trace.go:171","msg":"trace[659954742] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2784; }","duration":"109.346071ms","start":"2026-08-12T17:51:59.443717Z","end":"2026-08-12T17:51:59.553063Z","steps":["trace[659954742] 'agreement among raft nodes before linearized reading' (duration: 109.151096ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:53:01.416127Z","caller":"traceutil/trace.go:171","msg":"trace[1409252509] linearizableReadLoop","detail":"{readStateIndex:3510; appliedIndex:3509; }","duration":"117.894418ms","start":"2026-08-12T17:53:01.298198Z","end":"2026-08-12T17:53:01.416092Z","steps":["trace[1409252509] 'read index received' (duration: 46.152125ms)","trace[1409252509] 'applied index is now lower than readState.Index' (duration: 71.741013ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:53:01.416317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.104674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:53:01.416352Z","caller":"traceutil/trace.go:171","msg":"trace[1546495577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3374; }","duration":"118.209236ms","start":"2026-08-12T17:53:01.298132Z","end":"2026-08-12T17:53:01.416341Z","steps":["trace[1546495577] 'agreement among raft nodes before linearized reading' (duration: 118.098193ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:53:01.416567Z","caller":"traceutil/trace.go:171","msg":"trace[941696913] transaction","detail":"{read_only:false; response_revision:3374; number_of_response:1; }","duration":"240.715912ms","start":"2026-08-12T17:53:01.175586Z","end":"2026-08-12T17:53:01.416302Z","steps":["trace[941696913] 'process raft request' (duration: 168.733953ms)","trace[941696913] 'compare' (duration: 71.606769ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:22.084642Z","caller":"traceutil/trace.go:171","msg":"trace[1557183742] linearizableReadLoop","detail":"{readStateIndex:4803; appliedIndex:4802; }","duration":"150.451762ms","start":"2026-08-12T17:54:21.934162Z","end":"2026-08-12T17:54:22.084613Z","steps":["trace[1557183742] 'read index received' (duration: 150.216825ms)","trace[1557183742] 'applied index is now lower than readState.Index' (duration: 233.797µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:22.084732Z","caller":"traceutil/trace.go:171","msg":"trace[670015954] transaction","detail":"{read_only:false; response_revision:4607; number_of_response:1; }","duration":"232.45869ms","start":"2026-08-12T17:54:21.852236Z","end":"2026-08-12T17:54:22.084694Z","steps":["trace[670015954] 'process raft request' (duration: 232.171282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:22.084908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.722479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-08-12T17:54:22.084964Z","caller":"traceutil/trace.go:171","msg":"trace[1853190118] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4607; }","duration":"150.823212ms","start":"2026-08-12T17:54:21.93412Z","end":"2026-08-12T17:54:22.084943Z","steps":["trace[1853190118] 'agreement among raft nodes before linearized reading' (duration: 150.647837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:22.085232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.23574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:790551"} {"level":"info","ts":"2026-08-12T17:54:22.08532Z","caller":"traceutil/trace.go:171","msg":"trace[1785155758] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4607; }","duration":"144.376914ms","start":"2026-08-12T17:54:21.940924Z","end":"2026-08-12T17:54:22.085301Z","steps":["trace[1785155758] 'agreement among raft nodes before linearized reading' (duration: 143.87624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:24.281413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.39976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-126b3cc2-85ff-4df0-ac2f-f142aa59b8ea\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-08-12T17:54:24.281525Z","caller":"traceutil/trace.go:171","msg":"trace[2101329268] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-126b3cc2-85ff-4df0-ac2f-f142aa59b8ea; range_end:; response_count:1; response_revision:4645; }","duration":"184.572965ms","start":"2026-08-12T17:54:24.096922Z","end":"2026-08-12T17:54:24.281495Z","steps":["trace[2101329268] 'range keys from in-memory index tree' (duration: 184.172074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:24.281643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.283169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:1 size:799"} {"level":"info","ts":"2026-08-12T17:54:24.282019Z","caller":"traceutil/trace.go:171","msg":"trace[25715649] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:1; response_revision:4645; }","duration":"119.68258ms","start":"2026-08-12T17:54:24.162307Z","end":"2026-08-12T17:54:24.28199Z","steps":["trace[25715649] 'range keys from in-memory index tree' (duration: 118.57988ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:26.032192Z","caller":"traceutil/trace.go:171","msg":"trace[1360412998] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4879; }","duration":"137.970966ms","start":"2026-08-12T17:54:25.894193Z","end":"2026-08-12T17:54:26.032164Z","steps":["trace[1360412998] 'read index received' (duration: 99.940794ms)","trace[1360412998] 'applied index is now lower than readState.Index' (duration: 38.029192ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:26.032305Z","caller":"traceutil/trace.go:171","msg":"trace[1499715964] transaction","detail":"{read_only:false; response_revision:4679; number_of_response:1; }","duration":"194.127389ms","start":"2026-08-12T17:54:25.838099Z","end":"2026-08-12T17:54:26.032227Z","steps":["trace[1499715964] 'process raft request' (duration: 156.055796ms)","trace[1499715964] 'compare' (duration: 37.874177ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:54:26.032516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.296635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7f866d45bb-gjw7n\" ","response":"range_response_count:1 size:11257"} {"level":"info","ts":"2026-08-12T17:54:26.032601Z","caller":"traceutil/trace.go:171","msg":"trace[1781554888] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7f866d45bb-gjw7n; range_end:; response_count:1; response_revision:4679; }","duration":"138.438329ms","start":"2026-08-12T17:54:25.89414Z","end":"2026-08-12T17:54:26.032578Z","steps":["trace[1781554888] 'agreement among raft nodes before linearized reading' (duration: 138.215082ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:38.434806Z","caller":"traceutil/trace.go:171","msg":"trace[601493239] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5102; }","duration":"406.637236ms","start":"2026-08-12T17:54:38.028127Z","end":"2026-08-12T17:54:38.434764Z","steps":["trace[601493239] 'read index received' (duration: 406.249866ms)","trace[601493239] 'applied index is now lower than readState.Index' (duration: 385.41µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:38.434957Z","caller":"traceutil/trace.go:171","msg":"trace[432344022] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"413.605559ms","start":"2026-08-12T17:54:38.021261Z","end":"2026-08-12T17:54:38.434867Z","steps":["trace[432344022] 'process raft request' (duration: 413.232269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:38.435229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.021231Z","time spent":"413.847526ms","remote":"127.0.0.1:33990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8033,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:54:38.435265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.085159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"warn","ts":"2026-08-12T17:54:38.435317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.830845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:54:38.435393Z","caller":"traceutil/trace.go:171","msg":"trace[919944354] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4895; }","duration":"407.267364ms","start":"2026-08-12T17:54:38.0281Z","end":"2026-08-12T17:54:38.435367Z","steps":["trace[919944354] 'agreement among raft nodes before linearized reading' (duration: 406.905154ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:38.435419Z","caller":"traceutil/trace.go:171","msg":"trace[1365853609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4895; }","duration":"136.994849ms","start":"2026-08-12T17:54:38.298397Z","end":"2026-08-12T17:54:38.435392Z","steps":["trace[1365853609] 'agreement among raft nodes before linearized reading' (duration: 136.790373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:38.435396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.869767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/monitoring/loki-headless-klcbz\" ","response":"range_response_count:1 size:1457"} {"level":"warn","ts":"2026-08-12T17:54:38.435466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.028086Z","time spent":"407.363416ms","remote":"127.0.0.1:33952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-08-12T17:54:38.435511Z","caller":"traceutil/trace.go:171","msg":"trace[1606688366] range","detail":"{range_begin:/registry/endpointslices/monitoring/loki-headless-klcbz; range_end:; response_count:1; response_revision:4895; }","duration":"405.039382ms","start":"2026-08-12T17:54:38.030447Z","end":"2026-08-12T17:54:38.435486Z","steps":["trace[1606688366] 'agreement among raft nodes before linearized reading' (duration: 404.691773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:38.435563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.030436Z","time spent":"405.119934ms","remote":"127.0.0.1:34084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1481,"request content":"key:\"/registry/endpointslices/monitoring/loki-headless-klcbz\" "} {"level":"warn","ts":"2026-08-12T17:54:38.435994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.997543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-12T17:54:38.436082Z","caller":"traceutil/trace.go:171","msg":"trace[1087928896] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4895; }","duration":"276.125526ms","start":"2026-08-12T17:54:38.159932Z","end":"2026-08-12T17:54:38.436058Z","steps":["trace[1087928896] 'agreement among raft nodes before linearized reading' (duration: 275.311844ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:38.902104Z","caller":"traceutil/trace.go:171","msg":"trace[1888342097] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"458.574711ms","start":"2026-08-12T17:54:38.443458Z","end":"2026-08-12T17:54:38.902033Z","steps":["trace[1888342097] 'process raft request' (duration: 458.361305ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:38.902322Z","caller":"traceutil/trace.go:171","msg":"trace[195553877] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"458.748366ms","start":"2026-08-12T17:54:38.443523Z","end":"2026-08-12T17:54:38.902272Z","steps":["trace[195553877] 'process raft request' (duration: 458.356725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:38.902407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.443432Z","time spent":"458.822958ms","remote":"127.0.0.1:33968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:54:38.90255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.443491Z","time spent":"458.917611ms","remote":"127.0.0.1:34084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:54:38.902462Z","caller":"traceutil/trace.go:171","msg":"trace[1539086459] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"458.610782ms","start":"2026-08-12T17:54:38.443811Z","end":"2026-08-12T17:54:38.902422Z","steps":["trace[1539086459] 'process raft request' (duration: 458.250892ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:38.902883Z","caller":"traceutil/trace.go:171","msg":"trace[828095341] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5103; }","duration":"418.477852ms","start":"2026-08-12T17:54:38.484381Z","end":"2026-08-12T17:54:38.902859Z","steps":["trace[828095341] 'read index received' (duration: 343.043816ms)","trace[828095341] 'applied index is now lower than readState.Index' (duration: 75.432776ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:54:38.902933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.443794Z","time spent":"458.971352ms","remote":"127.0.0.1:33882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:54:38.903017Z","caller":"traceutil/trace.go:171","msg":"trace[1818667475] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"399.995701ms","start":"2026-08-12T17:54:38.503006Z","end":"2026-08-12T17:54:38.903002Z","steps":["trace[1818667475] 'process raft request' (duration: 399.720443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:38.903113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.502987Z","time spent":"400.062003ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:54:38.903164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.789221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-08-12T17:54:38.90317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.959918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-08-12T17:54:38.903102Z","caller":"traceutil/trace.go:171","msg":"trace[393655137] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"459.172798ms","start":"2026-08-12T17:54:38.443854Z","end":"2026-08-12T17:54:38.903027Z","steps":["trace[393655137] 'process raft request' (duration: 458.495949ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:38.903219Z","caller":"traceutil/trace.go:171","msg":"trace[1160119163] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4901; }","duration":"418.880364ms","start":"2026-08-12T17:54:38.484321Z","end":"2026-08-12T17:54:38.903201Z","steps":["trace[1160119163] 'agreement among raft nodes before linearized reading' (duration: 418.721829ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:38.903234Z","caller":"traceutil/trace.go:171","msg":"trace[1588220204] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4901; }","duration":"171.072751ms","start":"2026-08-12T17:54:38.732143Z","end":"2026-08-12T17:54:38.903216Z","steps":["trace[1588220204] 'agreement among raft nodes before linearized reading' (duration: 170.911996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:38.903277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.484295Z","time spent":"418.970526ms","remote":"127.0.0.1:33882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-12T17:54:38.903276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.099168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-12T17:54:38.903331Z","caller":"traceutil/trace.go:171","msg":"trace[78420721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4901; }","duration":"130.15538ms","start":"2026-08-12T17:54:38.773162Z","end":"2026-08-12T17:54:38.903317Z","steps":["trace[78420721] 'agreement among raft nodes before linearized reading' (duration: 129.938694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:38.903414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.443841Z","time spent":"459.436056ms","remote":"127.0.0.1:34084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T17:54:38.903545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.255691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-12T17:54:38.903606Z","caller":"traceutil/trace.go:171","msg":"trace[1913514254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4901; }","duration":"131.368373ms","start":"2026-08-12T17:54:38.772221Z","end":"2026-08-12T17:54:38.90359Z","steps":["trace[1913514254] 'agreement among raft nodes before linearized reading' (duration: 131.124237ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:38.904928Z","caller":"traceutil/trace.go:171","msg":"trace[1799679898] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"458.830338ms","start":"2026-08-12T17:54:38.443122Z","end":"2026-08-12T17:54:38.901952Z","steps":["trace[1799679898] 'process raft request' (duration: 384.285806ms)","trace[1799679898] 'compare' (duration: 74.185912ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:54:38.905086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T17:54:38.443108Z","time spent":"461.905934ms","remote":"127.0.0.1:33968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":528,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T17:54:45.817379Z","caller":"traceutil/trace.go:171","msg":"trace[395943355] linearizableReadLoop","detail":"{readStateIndex:5274; appliedIndex:5273; }","duration":"153.359191ms","start":"2026-08-12T17:54:45.663993Z","end":"2026-08-12T17:54:45.817352Z","steps":["trace[395943355] 'read index received' (duration: 153.200017ms)","trace[395943355] 'applied index is now lower than readState.Index' (duration: 157.444µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:45.817447Z","caller":"traceutil/trace.go:171","msg":"trace[1127838750] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"159.004167ms","start":"2026-08-12T17:54:45.658394Z","end":"2026-08-12T17:54:45.817399Z","steps":["trace[1127838750] 'process raft request' (duration: 158.786911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:45.818436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.736722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-12T17:54:45.818659Z","caller":"traceutil/trace.go:171","msg":"trace[631896269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5061; }","duration":"154.74789ms","start":"2026-08-12T17:54:45.663873Z","end":"2026-08-12T17:54:45.818621Z","steps":["trace[631896269] 'agreement among raft nodes before linearized reading' (duration: 153.610409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:45.99457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.762507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T17:54:45.994843Z","caller":"traceutil/trace.go:171","msg":"trace[1156877184] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"171.137632ms","start":"2026-08-12T17:54:45.823659Z","end":"2026-08-12T17:54:45.994797Z","steps":["trace[1156877184] 'process raft request' (duration: 45.014335ms)","trace[1156877184] 'compare' (duration: 125.551922ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:45.994949Z","caller":"traceutil/trace.go:171","msg":"trace[764452237] transaction","detail":"{read_only:false; response_revision:5063; number_of_response:1; }","duration":"171.214114ms","start":"2026-08-12T17:54:45.823678Z","end":"2026-08-12T17:54:45.994892Z","steps":["trace[764452237] 'process raft request' (duration: 171.023249ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:45.995188Z","caller":"traceutil/trace.go:171","msg":"trace[1792754512] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"171.377719ms","start":"2026-08-12T17:54:45.823755Z","end":"2026-08-12T17:54:45.995133Z","steps":["trace[1792754512] 'process raft request' (duration: 171.006278ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:45.995516Z","caller":"traceutil/trace.go:171","msg":"trace[858753848] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"169.61075ms","start":"2026-08-12T17:54:45.825886Z","end":"2026-08-12T17:54:45.995497Z","steps":["trace[858753848] 'process raft request' (duration: 169.491936ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:46.26634Z","caller":"traceutil/trace.go:171","msg":"trace[995684961] linearizableReadLoop","detail":"{readStateIndex:5281; appliedIndex:5280; }","duration":"106.033283ms","start":"2026-08-12T17:54:46.16027Z","end":"2026-08-12T17:54:46.266303Z","steps":["trace[995684961] 'read index received' (duration: 53.286764ms)","trace[995684961] 'applied index is now lower than readState.Index' (duration: 52.744929ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:46.2664Z","caller":"traceutil/trace.go:171","msg":"trace[126702066] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5067; }","duration":"142.884502ms","start":"2026-08-12T17:54:46.123465Z","end":"2026-08-12T17:54:46.266349Z","steps":["trace[126702066] 'process raft request' (duration: 90.122353ms)","trace[126702066] 'compare' (duration: 52.594014ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T17:54:46.266625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.30529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T17:54:46.266746Z","caller":"traceutil/trace.go:171","msg":"trace[1665407018] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5067; }","duration":"106.510596ms","start":"2026-08-12T17:54:46.160205Z","end":"2026-08-12T17:54:46.266716Z","steps":["trace[1665407018] 'agreement among raft nodes before linearized reading' (duration: 106.244179ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:56.398651Z","caller":"traceutil/trace.go:171","msg":"trace[967101372] linearizableReadLoop","detail":"{readStateIndex:5420; appliedIndex:5419; }","duration":"141.539994ms","start":"2026-08-12T17:54:56.257069Z","end":"2026-08-12T17:54:56.398609Z","steps":["trace[967101372] 'read index received' (duration: 141.324088ms)","trace[967101372] 'applied index is now lower than readState.Index' (duration: 214.806µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:56.398734Z","caller":"traceutil/trace.go:171","msg":"trace[1683443260] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"164.460088ms","start":"2026-08-12T17:54:56.234227Z","end":"2026-08-12T17:54:56.398687Z","steps":["trace[1683443260] 'process raft request' (duration: 164.195741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T17:54:56.398858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.789001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-12T17:54:56.398896Z","caller":"traceutil/trace.go:171","msg":"trace[1421518035] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5203; }","duration":"141.896794ms","start":"2026-08-12T17:54:56.256988Z","end":"2026-08-12T17:54:56.398885Z","steps":["trace[1421518035] 'agreement among raft nodes before linearized reading' (duration: 141.73834ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T17:54:56.718936Z","caller":"traceutil/trace.go:171","msg":"trace[1904765753] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"159.164101ms","start":"2026-08-12T17:54:56.559732Z","end":"2026-08-12T17:54:56.718896Z","steps":["trace[1904765753] 'process raft request' (duration: 111.7656ms)","trace[1904765753] 'compare' (duration: 47.248846ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:54:56.862284Z","caller":"traceutil/trace.go:171","msg":"trace[1469886046] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5208; }","duration":"126.714704ms","start":"2026-08-12T17:54:56.73553Z","end":"2026-08-12T17:54:56.862245Z","steps":["trace[1469886046] 'process raft request' (duration: 94.125303ms)","trace[1469886046] 'compare' (duration: 32.496048ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T17:57:40.903852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3235} {"level":"info","ts":"2026-08-12T17:57:40.993233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3235,"took":"87.568573ms","hash":1590689168,"current-db-size-bytes":61095936,"current-db-size":"61 MB","current-db-size-in-use-bytes":33947648,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T17:57:40.993294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1590689168,"revision":3235,"compact-revision":-1}