{"level":"warn","ts":"2026-05-27T06:04:22.792075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T06:04:22.79229Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T06:04:22.792378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T06:04:22.792394Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-27T06:04:22.792416Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T06:04:22.79317Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-05-27T06:04:22.793261Z","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.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T06:04:22.798917Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.468119ms"} {"level":"info","ts":"2026-05-27T06:04:22.805501Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-05-27T06:04:22.80561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T06:04:22.805639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-05-27T06:04:22.805652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T06:04:22.805671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-05-27T06:04:22.805715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-05-27T06:04:22.810174Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T06:04:22.81164Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T06:04:22.812882Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T06:04:22.815557Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T06:04:22.815857Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T06:04:22.816074Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T06:04:22.816248Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T06:04:22.816271Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T06:04:22.816937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-05-27T06:04:22.817598Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-27T06:04:22.820993Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T06:04:22.821208Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-27T06:04:22.8213Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-27T06:04:22.82186Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T06:04:22.82195Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T06:04:23.105984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T06:04:23.106066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T06:04:23.106132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-05-27T06:04:23.106153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-05-27T06:04:23.106171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-27T06:04:23.106203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-05-27T06:04:23.106222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-27T06:04:23.107319Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T06:04:23.107338Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T06:04:23.107401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T06:04:23.107396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T06:04:23.107663Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T06:04:23.107731Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T06:04:23.109129Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T06:04:23.109273Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T06:04:23.109323Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T06:04:23.112148Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T06:04:23.112205Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"warn","ts":"2026-05-27T06:04:25.273479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.356482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-27T06:04:25.273702Z","caller":"traceutil/trace.go:171","msg":"trace[1821652484] linearizableReadLoop","detail":"{readStateIndex:73; appliedIndex:71; }","duration":"200.055836ms","start":"2026-05-27T06:04:25.073629Z","end":"2026-05-27T06:04:25.273685Z","steps":["trace[1821652484] 'read index received' (duration: 85.014265ms)","trace[1821652484] 'applied index is now lower than readState.Index' (duration: 115.040301ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:25.273803Z","caller":"traceutil/trace.go:171","msg":"trace[786682710] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"228.710443ms","start":"2026-05-27T06:04:25.045041Z","end":"2026-05-27T06:04:25.273751Z","steps":["trace[786682710] 'process raft request' (duration: 113.588009ms)","trace[786682710] 'compare' (duration: 114.176367ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:04:25.27388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.241382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-27T06:04:25.273919Z","caller":"traceutil/trace.go:171","msg":"trace[1048083513] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:69; }","duration":"200.324494ms","start":"2026-05-27T06:04:25.073579Z","end":"2026-05-27T06:04:25.273904Z","steps":["trace[1048083513] 'agreement among raft nodes before linearized reading' (duration: 200.176539ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:25.273911Z","caller":"traceutil/trace.go:171","msg":"trace[1318615379] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"217.975254ms","start":"2026-05-27T06:04:25.055858Z","end":"2026-05-27T06:04:25.273833Z","steps":["trace[1318615379] 'process raft request' (duration: 217.755307ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:34.997011Z","caller":"traceutil/trace.go:171","msg":"trace[1804790120] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"114.948818ms","start":"2026-05-27T06:04:34.882037Z","end":"2026-05-27T06:04:34.996985Z","steps":["trace[1804790120] 'process raft request' (duration: 114.798284ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:34.997036Z","caller":"traceutil/trace.go:171","msg":"trace[1669088167] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:411; }","duration":"114.183107ms","start":"2026-05-27T06:04:34.882811Z","end":"2026-05-27T06:04:34.996995Z","steps":["trace[1669088167] 'read index received' (duration: 114.169016ms)","trace[1669088167] 'applied index is now lower than readState.Index' (duration: 10.821µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:04:34.997248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.404204ms","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-05-27T06:04:34.997299Z","caller":"traceutil/trace.go:171","msg":"trace[393649423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:398; }","duration":"114.536327ms","start":"2026-05-27T06:04:34.882751Z","end":"2026-05-27T06:04:34.997287Z","steps":["trace[393649423] 'agreement among raft nodes before linearized reading' (duration: 114.367342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:04:35.239199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.143786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:04:35.2395Z","caller":"traceutil/trace.go:171","msg":"trace[505579110] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:411; }","duration":"142.817313ms","start":"2026-05-27T06:04:35.096635Z","end":"2026-05-27T06:04:35.239453Z","steps":["trace[505579110] 'read index received' (duration: 10.016879ms)","trace[505579110] 'applied index is now lower than readState.Index' (duration: 132.798614ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:04:35.239505Z","caller":"traceutil/trace.go:171","msg":"trace[15003218] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"283.888457ms","start":"2026-05-27T06:04:34.955577Z","end":"2026-05-27T06:04:35.239465Z","steps":["trace[15003218] 'process raft request' (duration: 151.130364ms)","trace[15003218] 'compare' (duration: 131.95834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:04:35.239603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.980738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:04:35.239569Z","caller":"traceutil/trace.go:171","msg":"trace[1795471864] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"237.25638ms","start":"2026-05-27T06:04:35.002265Z","end":"2026-05-27T06:04:35.239521Z","steps":["trace[1795471864] 'process raft request' (duration: 237.101066ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:04:35.239643Z","caller":"traceutil/trace.go:171","msg":"trace[1473321305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"143.078732ms","start":"2026-05-27T06:04:35.096553Z","end":"2026-05-27T06:04:35.239632Z","steps":["trace[1473321305] 'agreement among raft nodes before linearized reading' (duration: 143.002489ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.57767Z","caller":"traceutil/trace.go:171","msg":"trace[2019319485] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"156.167556ms","start":"2026-05-27T06:05:02.421476Z","end":"2026-05-27T06:05:02.577643Z","steps":["trace[2019319485] 'process raft request' (duration: 156.080783ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.586248Z","caller":"traceutil/trace.go:171","msg":"trace[346508034] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"150.587349ms","start":"2026-05-27T06:05:02.435642Z","end":"2026-05-27T06:05:02.586227Z","steps":["trace[346508034] 'process raft request' (duration: 150.477916ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.587198Z","caller":"traceutil/trace.go:171","msg":"trace[1483914891] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"171.497796ms","start":"2026-05-27T06:05:02.415638Z","end":"2026-05-27T06:05:02.587136Z","steps":["trace[1483914891] 'process raft request' (duration: 131.080691ms)","trace[1483914891] 'compare' (duration: 30.556568ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:05:02.587317Z","caller":"traceutil/trace.go:171","msg":"trace[153975269] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"138.580382ms","start":"2026-05-27T06:05:02.448681Z","end":"2026-05-27T06:05:02.587261Z","steps":["trace[153975269] 'process raft request' (duration: 137.736168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:05:02.587381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.622204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T06:05:02.587419Z","caller":"traceutil/trace.go:171","msg":"trace[2132533775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"117.702596ms","start":"2026-05-27T06:05:02.469707Z","end":"2026-05-27T06:05:02.58741Z","steps":["trace[2132533775] 'agreement among raft nodes before linearized reading' (duration: 117.607433ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.587579Z","caller":"traceutil/trace.go:171","msg":"trace[1392886682] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"167.255587ms","start":"2026-05-27T06:05:02.420162Z","end":"2026-05-27T06:05:02.587417Z","steps":["trace[1392886682] 'process raft request' (duration: 157.322408ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.58764Z","caller":"traceutil/trace.go:171","msg":"trace[503554033] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"155.080485ms","start":"2026-05-27T06:05:02.432552Z","end":"2026-05-27T06:05:02.587632Z","steps":["trace[503554033] 'process raft request' (duration: 153.249944ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.587706Z","caller":"traceutil/trace.go:171","msg":"trace[1593632315] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"153.888811ms","start":"2026-05-27T06:05:02.432613Z","end":"2026-05-27T06:05:02.586501Z","steps":["trace[1593632315] 'process raft request' (duration: 153.433999ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:02.587285Z","caller":"traceutil/trace.go:171","msg":"trace[1006304713] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:572; }","duration":"117.475379ms","start":"2026-05-27T06:05:02.469752Z","end":"2026-05-27T06:05:02.587227Z","steps":["trace[1006304713] 'read index received' (duration: 77.263089ms)","trace[1006304713] 'applied index is now lower than readState.Index' (duration: 40.21169ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:05:02.587779Z","caller":"traceutil/trace.go:171","msg":"trace[82899123] transaction","detail":"{read_only:false; number_of_response:1; response_revision:555; }","duration":"150.668372ms","start":"2026-05-27T06:05:02.437092Z","end":"2026-05-27T06:05:02.58776Z","steps":["trace[82899123] 'process raft request' (duration: 149.062737ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:03.288341Z","caller":"traceutil/trace.go:171","msg":"trace[2002434088] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"105.564545ms","start":"2026-05-27T06:05:03.182745Z","end":"2026-05-27T06:05:03.288309Z","steps":["trace[2002434088] 'process raft request' (duration: 66.958491ms)","trace[2002434088] 'compare' (duration: 38.45548ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:05:09.075952Z","caller":"traceutil/trace.go:171","msg":"trace[1694831990] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"133.689904ms","start":"2026-05-27T06:05:08.942224Z","end":"2026-05-27T06:05:09.075914Z","steps":["trace[1694831990] 'read index received' (duration: 132.567213ms)","trace[1694831990] 'applied index is now lower than readState.Index' (duration: 1.121421ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:05:09.076081Z","caller":"traceutil/trace.go:171","msg":"trace[155420420] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"135.475525ms","start":"2026-05-27T06:05:08.940557Z","end":"2026-05-27T06:05:09.076032Z","steps":["trace[155420420] 'process raft request' (duration: 134.517748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:05:09.076212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.951031ms","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-05-27T06:05:09.076264Z","caller":"traceutil/trace.go:171","msg":"trace[1883469849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"134.064675ms","start":"2026-05-27T06:05:08.942185Z","end":"2026-05-27T06:05:09.07625Z","steps":["trace[1883469849] 'agreement among raft nodes before linearized reading' (duration: 133.872139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:05:09.667086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.63442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rb9cf\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-27T06:05:09.667157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.707784ms","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-05-27T06:05:09.667231Z","caller":"traceutil/trace.go:171","msg":"trace[1093743023] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rb9cf; range_end:; response_count:1; response_revision:642; }","duration":"312.857316ms","start":"2026-05-27T06:05:09.354344Z","end":"2026-05-27T06:05:09.667201Z","steps":["trace[1093743023] 'range keys from in-memory index tree' (duration: 312.367682ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:09.667266Z","caller":"traceutil/trace.go:171","msg":"trace[62601245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"175.877819ms","start":"2026-05-27T06:05:09.491362Z","end":"2026-05-27T06:05:09.66724Z","steps":["trace[62601245] 'range keys from in-memory index tree' (duration: 175.387046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:05:09.667338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:05:09.354319Z","time spent":"313.007281ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-rb9cf\" "} {"level":"info","ts":"2026-05-27T06:05:09.677821Z","caller":"traceutil/trace.go:171","msg":"trace[804186372] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"107.550971ms","start":"2026-05-27T06:05:09.570247Z","end":"2026-05-27T06:05:09.677798Z","steps":["trace[804186372] 'process raft request' (duration: 106.559593ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:05:53.666417Z","caller":"traceutil/trace.go:171","msg":"trace[555273908] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"228.478657ms","start":"2026-05-27T06:05:53.437905Z","end":"2026-05-27T06:05:53.666383Z","steps":["trace[555273908] 'process raft request' (duration: 221.498001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:06:42.698455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.250008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-27T06:06:42.698576Z","caller":"traceutil/trace.go:171","msg":"trace[2079255422] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1888; }","duration":"157.399862ms","start":"2026-05-27T06:06:42.541128Z","end":"2026-05-27T06:06:42.698528Z","steps":["trace[2079255422] 'range keys from in-memory index tree' (duration: 157.032911ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:06:42.698692Z","caller":"traceutil/trace.go:171","msg":"trace[245606141] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"139.403197ms","start":"2026-05-27T06:06:42.559241Z","end":"2026-05-27T06:06:42.698644Z","steps":["trace[245606141] 'process raft request' (duration: 121.599097ms)","trace[245606141] 'compare' (duration: 17.309656ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:06:43.109201Z","caller":"traceutil/trace.go:171","msg":"trace[1968182372] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"212.15107ms","start":"2026-05-27T06:06:42.896947Z","end":"2026-05-27T06:06:43.109098Z","steps":["trace[1968182372] 'process raft request' (duration: 211.938835ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:06:43.110325Z","caller":"traceutil/trace.go:171","msg":"trace[1701853435] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"137.990796ms","start":"2026-05-27T06:06:42.972311Z","end":"2026-05-27T06:06:43.110302Z","steps":["trace[1701853435] 'process raft request' (duration: 137.813501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:07:34.317033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.833235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T06:07:34.317415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.622749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-27T06:07:34.317176Z","caller":"traceutil/trace.go:171","msg":"trace[1500963986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2220; }","duration":"221.05566ms","start":"2026-05-27T06:07:34.096087Z","end":"2026-05-27T06:07:34.317142Z","steps":["trace[1500963986] 'range keys from in-memory index tree' (duration: 220.720161ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:07:34.317563Z","caller":"traceutil/trace.go:171","msg":"trace[1860881544] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2220; }","duration":"157.848785ms","start":"2026-05-27T06:07:34.159684Z","end":"2026-05-27T06:07:34.317533Z","steps":["trace[1860881544] 'range keys from in-memory index tree' (duration: 157.31748ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:07:34.494479Z","caller":"traceutil/trace.go:171","msg":"trace[933026037] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"126.575726ms","start":"2026-05-27T06:07:34.367875Z","end":"2026-05-27T06:07:34.49445Z","steps":["trace[933026037] 'process raft request' (duration: 126.405521ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:07:34.684238Z","caller":"traceutil/trace.go:171","msg":"trace[2073392179] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"221.224006ms","start":"2026-05-27T06:07:34.462971Z","end":"2026-05-27T06:07:34.684195Z","steps":["trace[2073392179] 'process raft request' (duration: 133.729548ms)","trace[2073392179] 'compare' (duration: 87.356934ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:08:25.056464Z","caller":"traceutil/trace.go:171","msg":"trace[491229484] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"186.569206ms","start":"2026-05-27T06:08:24.869852Z","end":"2026-05-27T06:08:25.056421Z","steps":["trace[491229484] 'process raft request' (duration: 186.317168ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:11:35.382195Z","caller":"traceutil/trace.go:171","msg":"trace[1457706961] linearizableReadLoop","detail":"{readStateIndex:4046; appliedIndex:4045; }","duration":"186.305316ms","start":"2026-05-27T06:11:35.195855Z","end":"2026-05-27T06:11:35.38216Z","steps":["trace[1457706961] 'read index received' (duration: 186.107731ms)","trace[1457706961] 'applied index is now lower than readState.Index' (duration: 196.005µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:11:35.382283Z","caller":"traceutil/trace.go:171","msg":"trace[1990493261] transaction","detail":"{read_only:false; response_revision:3896; number_of_response:1; }","duration":"201.252662ms","start":"2026-05-27T06:11:35.180993Z","end":"2026-05-27T06:11:35.382246Z","steps":["trace[1990493261] 'process raft request' (duration: 200.986014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:11:35.382425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.545553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:11:35.382474Z","caller":"traceutil/trace.go:171","msg":"trace[780314704] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3896; }","duration":"186.666326ms","start":"2026-05-27T06:11:35.195796Z","end":"2026-05-27T06:11:35.382463Z","steps":["trace[780314704] 'agreement among raft nodes before linearized reading' (duration: 186.559643ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:12:58.08135Z","caller":"traceutil/trace.go:171","msg":"trace[100962151] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"160.252906ms","start":"2026-05-27T06:12:57.921051Z","end":"2026-05-27T06:12:58.081304Z","steps":["trace[100962151] 'process raft request' (duration: 104.446227ms)","trace[100962151] 'compare' (duration: 55.655764ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:12:58.091008Z","caller":"traceutil/trace.go:171","msg":"trace[607680359] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"165.464522ms","start":"2026-05-27T06:12:57.925517Z","end":"2026-05-27T06:12:58.090981Z","steps":["trace[607680359] 'process raft request' (duration: 165.222535ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:00.25066Z","caller":"traceutil/trace.go:171","msg":"trace[979607741] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"120.201379ms","start":"2026-05-27T06:13:00.13043Z","end":"2026-05-27T06:13:00.250631Z","steps":["trace[979607741] 'process raft request' (duration: 119.973352ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:00.255897Z","caller":"traceutil/trace.go:171","msg":"trace[1826016316] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"120.032664ms","start":"2026-05-27T06:13:00.135831Z","end":"2026-05-27T06:13:00.255863Z","steps":["trace[1826016316] 'process raft request' (duration: 119.594102ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:11.409195Z","caller":"traceutil/trace.go:171","msg":"trace[1458217587] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"121.975969ms","start":"2026-05-27T06:13:11.2872Z","end":"2026-05-27T06:13:11.409176Z","steps":["trace[1458217587] 'process raft request' (duration: 121.833295ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:20.242265Z","caller":"traceutil/trace.go:171","msg":"trace[1720523204] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"155.214103ms","start":"2026-05-27T06:13:20.087023Z","end":"2026-05-27T06:13:20.242238Z","steps":["trace[1720523204] 'process raft request' (duration: 155.037418ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:13:20.242842Z","caller":"traceutil/trace.go:171","msg":"trace[538054834] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5693; }","duration":"145.345996ms","start":"2026-05-27T06:13:20.097479Z","end":"2026-05-27T06:13:20.242825Z","steps":["trace[538054834] 'read index received' (duration: 144.588804ms)","trace[538054834] 'applied index is now lower than readState.Index' (duration: 756.562µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:13:20.242913Z","caller":"traceutil/trace.go:171","msg":"trace[2048865008] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"151.746295ms","start":"2026-05-27T06:13:20.091138Z","end":"2026-05-27T06:13:20.242884Z","steps":["trace[2048865008] 'process raft request' (duration: 151.606821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:13:20.243108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.408963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48e75c6a-6b5a-44a8-94db-31dd3739f7d8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-27T06:13:20.243163Z","caller":"traceutil/trace.go:171","msg":"trace[844295679] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48e75c6a-6b5a-44a8-94db-31dd3739f7d8; range_end:; response_count:1; response_revision:5487; }","duration":"142.491505ms","start":"2026-05-27T06:13:20.100658Z","end":"2026-05-27T06:13:20.24315Z","steps":["trace[844295679] 'agreement among raft nodes before linearized reading' (duration: 142.288929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:13:20.243091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.586082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:13:20.243398Z","caller":"traceutil/trace.go:171","msg":"trace[721060710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5487; }","duration":"145.797457ms","start":"2026-05-27T06:13:20.097444Z","end":"2026-05-27T06:13:20.243242Z","steps":["trace[721060710] 'agreement among raft nodes before linearized reading' (duration: 145.504749ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:14:23.318562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3007} {"level":"info","ts":"2026-05-27T06:14:23.400276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3007,"took":"80.203837ms","hash":522631301,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T06:14:23.400326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":522631301,"revision":3007,"compact-revision":-1} {"level":"info","ts":"2026-05-27T06:19:23.326748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6088} {"level":"info","ts":"2026-05-27T06:19:23.420614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6088,"took":"92.182981ms","hash":2375420145,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":21422080,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-27T06:19:23.420693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2375420145,"revision":6088,"compact-revision":3007} {"level":"info","ts":"2026-05-27T06:19:28.160196Z","caller":"traceutil/trace.go:171","msg":"trace[636368388] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"205.476509ms","start":"2026-05-27T06:19:27.954684Z","end":"2026-05-27T06:19:28.16016Z","steps":["trace[636368388] 'process raft request' (duration: 205.208521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:19:39.870246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.117064ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T06:19:39.870593Z","caller":"traceutil/trace.go:171","msg":"trace[1977430641] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8392; }","duration":"117.486724ms","start":"2026-05-27T06:19:39.753084Z","end":"2026-05-27T06:19:39.870571Z","steps":["trace[1977430641] 'range keys from in-memory index tree' (duration: 117.094384ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:19:39.870403Z","caller":"traceutil/trace.go:171","msg":"trace[121344475] transaction","detail":"{read_only:false; response_revision:8393; number_of_response:1; }","duration":"249.525973ms","start":"2026-05-27T06:19:39.620842Z","end":"2026-05-27T06:19:39.870368Z","steps":["trace[121344475] 'process raft request' (duration: 188.570259ms)","trace[121344475] 'compare' (duration: 60.80941ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:19:42.591868Z","caller":"traceutil/trace.go:171","msg":"trace[124465001] linearizableReadLoop","detail":"{readStateIndex:8706; appliedIndex:8705; }","duration":"117.72906ms","start":"2026-05-27T06:19:42.4741Z","end":"2026-05-27T06:19:42.591829Z","steps":["trace[124465001] 'read index received' (duration: 64.617645ms)","trace[124465001] 'applied index is now lower than readState.Index' (duration: 53.110375ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:19:42.591944Z","caller":"traceutil/trace.go:171","msg":"trace[1741237009] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"121.583448ms","start":"2026-05-27T06:19:42.470308Z","end":"2026-05-27T06:19:42.591892Z","steps":["trace[1741237009] 'process raft request' (duration: 68.37903ms)","trace[1741237009] 'compare' (duration: 52.984732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:19:42.59213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.05298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T06:19:42.592171Z","caller":"traceutil/trace.go:171","msg":"trace[89318421] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:8407; }","duration":"118.160593ms","start":"2026-05-27T06:19:42.474001Z","end":"2026-05-27T06:19:42.592161Z","steps":["trace[89318421] 'agreement among raft nodes before linearized reading' (duration: 117.992678ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:08.792892Z","caller":"traceutil/trace.go:171","msg":"trace[272475876] linearizableReadLoop","detail":"{readStateIndex:8908; appliedIndex:8907; }","duration":"261.806498ms","start":"2026-05-27T06:20:08.531063Z","end":"2026-05-27T06:20:08.79287Z","steps":["trace[272475876] 'read index received' (duration: 261.672535ms)","trace[272475876] 'applied index is now lower than readState.Index' (duration: 132.903µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:20:08.79294Z","caller":"traceutil/trace.go:171","msg":"trace[1242196310] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"274.47492ms","start":"2026-05-27T06:20:08.518441Z","end":"2026-05-27T06:20:08.792916Z","steps":["trace[1242196310] 'process raft request' (duration: 274.286504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:08.793178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.037915ms","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-05-27T06:20:08.793315Z","caller":"traceutil/trace.go:171","msg":"trace[855211148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8602; }","duration":"262.265401ms","start":"2026-05-27T06:20:08.531027Z","end":"2026-05-27T06:20:08.793292Z","steps":["trace[855211148] 'agreement among raft nodes before linearized reading' (duration: 261.942332ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:08.91685Z","caller":"traceutil/trace.go:171","msg":"trace[793931419] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"119.227729ms","start":"2026-05-27T06:20:08.797606Z","end":"2026-05-27T06:20:08.916833Z","steps":["trace[793931419] 'process raft request' (duration: 119.198509ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:08.91687Z","caller":"traceutil/trace.go:171","msg":"trace[1856370785] transaction","detail":"{read_only:false; response_revision:8603; number_of_response:1; }","duration":"243.843279ms","start":"2026-05-27T06:20:08.672999Z","end":"2026-05-27T06:20:08.916842Z","steps":["trace[1856370785] 'process raft request' (duration: 242.649116ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:08.916932Z","caller":"traceutil/trace.go:171","msg":"trace[1202910737] linearizableReadLoop","detail":"{readStateIndex:8909; appliedIndex:8908; }","duration":"123.94093ms","start":"2026-05-27T06:20:08.792963Z","end":"2026-05-27T06:20:08.916904Z","steps":["trace[1202910737] 'read index received' (duration: 122.691306ms)","trace[1202910737] 'applied index is now lower than readState.Index' (duration: 1.246924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:20:08.917115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.266527ms","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-05-27T06:20:08.917156Z","caller":"traceutil/trace.go:171","msg":"trace[1480636386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8604; }","duration":"380.350908ms","start":"2026-05-27T06:20:08.536795Z","end":"2026-05-27T06:20:08.917146Z","steps":["trace[1480636386] 'agreement among raft nodes before linearized reading' (duration: 380.198634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:08.91719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:20:08.536773Z","time spent":"380.4093ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T06:20:08.917161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.355389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T06:20:08.917259Z","caller":"traceutil/trace.go:171","msg":"trace[1864636627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8604; }","duration":"197.497853ms","start":"2026-05-27T06:20:08.71974Z","end":"2026-05-27T06:20:08.917238Z","steps":["trace[1864636627] 'agreement among raft nodes before linearized reading' (duration: 197.220475ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:13.039099Z","caller":"traceutil/trace.go:171","msg":"trace[687461308] linearizableReadLoop","detail":"{readStateIndex:8929; appliedIndex:8928; }","duration":"104.307436ms","start":"2026-05-27T06:20:12.934769Z","end":"2026-05-27T06:20:13.039076Z","steps":["trace[687461308] 'read index received' (duration: 104.149421ms)","trace[687461308] 'applied index is now lower than readState.Index' (duration: 156.905µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:20:13.039222Z","caller":"traceutil/trace.go:171","msg":"trace[1346922184] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"106.146327ms","start":"2026-05-27T06:20:12.933061Z","end":"2026-05-27T06:20:13.039207Z","steps":["trace[1346922184] 'process raft request' (duration: 105.87631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:13.039397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.407231ms","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-05-27T06:20:13.039447Z","caller":"traceutil/trace.go:171","msg":"trace[223572796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8622; }","duration":"103.486263ms","start":"2026-05-27T06:20:12.935949Z","end":"2026-05-27T06:20:13.039435Z","steps":["trace[223572796] 'agreement among raft nodes before linearized reading' (duration: 103.337028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:13.039591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.82582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T06:20:13.039625Z","caller":"traceutil/trace.go:171","msg":"trace[1173077877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8622; }","duration":"104.882112ms","start":"2026-05-27T06:20:12.934735Z","end":"2026-05-27T06:20:13.039617Z","steps":["trace[1173077877] 'agreement among raft nodes before linearized reading' (duration: 104.783469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:21.2234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.409486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T06:20:21.22387Z","caller":"traceutil/trace.go:171","msg":"trace[441679378] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"138.554367ms","start":"2026-05-27T06:20:21.085301Z","end":"2026-05-27T06:20:21.223856Z","steps":["trace[441679378] 'process raft request' (duration: 14.630616ms)","trace[441679378] 'compare' (duration: 123.324414ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:20:21.223959Z","caller":"traceutil/trace.go:171","msg":"trace[185059836] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"138.567807ms","start":"2026-05-27T06:20:21.085386Z","end":"2026-05-27T06:20:21.223954Z","steps":["trace[185059836] 'process raft request' (duration: 138.084953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:20:21.468503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.885047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T06:20:21.468558Z","caller":"traceutil/trace.go:171","msg":"trace[1603607257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8711; }","duration":"115.97704ms","start":"2026-05-27T06:20:21.352569Z","end":"2026-05-27T06:20:21.468546Z","steps":["trace[1603607257] 'range keys from in-memory index tree' (duration: 115.721713ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:22.068955Z","caller":"traceutil/trace.go:171","msg":"trace[2128372974] transaction","detail":"{read_only:false; response_revision:8717; number_of_response:1; }","duration":"123.809017ms","start":"2026-05-27T06:20:21.945121Z","end":"2026-05-27T06:20:22.06893Z","steps":["trace[2128372974] 'process raft request' (duration: 123.655453ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:20:22.180285Z","caller":"traceutil/trace.go:171","msg":"trace[536084369] transaction","detail":"{read_only:false; response_revision:8718; number_of_response:1; }","duration":"107.240477ms","start":"2026-05-27T06:20:22.073005Z","end":"2026-05-27T06:20:22.180245Z","steps":["trace[536084369] 'process raft request' (duration: 84.407053ms)","trace[536084369] 'compare' (duration: 22.634408ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:20:56.300202Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249c.wal"} {"level":"info","ts":"2026-05-27T06:21:40.338833Z","caller":"traceutil/trace.go:171","msg":"trace[594973127] transaction","detail":"{read_only:false; response_revision:9471; number_of_response:1; }","duration":"119.931439ms","start":"2026-05-27T06:21:40.218869Z","end":"2026-05-27T06:21:40.3388Z","steps":["trace[594973127] 'process raft request' (duration: 119.566579ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:22:05.565871Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T06:22:05.571193Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-27T06:22:05.57127Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-27T06:24:23.33262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8168} {"level":"info","ts":"2026-05-27T06:24:23.391001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8168,"took":"56.239246ms","hash":3330347751,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":29577216,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T06:24:23.391145Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3330347751,"revision":8168,"compact-revision":6088} {"level":"info","ts":"2026-05-27T06:25:22.667472Z","caller":"traceutil/trace.go:171","msg":"trace[2077715204] transaction","detail":"{read_only:false; response_revision:11315; number_of_response:1; }","duration":"186.268425ms","start":"2026-05-27T06:25:22.48116Z","end":"2026-05-27T06:25:22.667428Z","steps":["trace[2077715204] 'process raft request' (duration: 186.015508ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:29:23.339114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10781} {"level":"info","ts":"2026-05-27T06:29:23.420439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10781,"took":"78.953495ms","hash":3284290259,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":28102656,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-27T06:29:23.420512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3284290259,"revision":10781,"compact-revision":8168} {"level":"info","ts":"2026-05-27T06:31:41.028514Z","caller":"traceutil/trace.go:171","msg":"trace[1888563965] transaction","detail":"{read_only:false; response_revision:13860; number_of_response:1; }","duration":"189.068602ms","start":"2026-05-27T06:31:40.839421Z","end":"2026-05-27T06:31:41.028489Z","steps":["trace[1888563965] 'process raft request' (duration: 188.841326ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:32:05.173884Z","caller":"traceutil/trace.go:171","msg":"trace[2133096387] linearizableReadLoop","detail":"{readStateIndex:14601; appliedIndex:14600; }","duration":"150.143485ms","start":"2026-05-27T06:32:05.023715Z","end":"2026-05-27T06:32:05.173859Z","steps":["trace[2133096387] 'read index received' (duration: 149.930279ms)","trace[2133096387] 'applied index is now lower than readState.Index' (duration: 211.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:32:05.173984Z","caller":"traceutil/trace.go:171","msg":"trace[568144138] transaction","detail":"{read_only:false; response_revision:14118; number_of_response:1; }","duration":"174.619042ms","start":"2026-05-27T06:32:04.99933Z","end":"2026-05-27T06:32:05.17395Z","steps":["trace[568144138] 'process raft request' (duration: 174.289303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:32:05.174201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.447474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-47jd6\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-27T06:32:05.174277Z","caller":"traceutil/trace.go:171","msg":"trace[387988773] range","detail":"{range_begin:/registry/pods/openstack/keepalived-47jd6; range_end:; response_count:1; response_revision:14118; }","duration":"150.573497ms","start":"2026-05-27T06:32:05.023663Z","end":"2026-05-27T06:32:05.174237Z","steps":["trace[387988773] 'agreement among raft nodes before linearized reading' (duration: 150.32516ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:32:05.438925Z","caller":"traceutil/trace.go:171","msg":"trace[2064729547] transaction","detail":"{read_only:false; response_revision:14120; number_of_response:1; }","duration":"242.685836ms","start":"2026-05-27T06:32:05.19622Z","end":"2026-05-27T06:32:05.438906Z","steps":["trace[2064729547] 'process raft request' (duration: 242.556522ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:32:05.441459Z","caller":"traceutil/trace.go:171","msg":"trace[740488167] transaction","detail":"{read_only:false; response_revision:14121; number_of_response:1; }","duration":"111.045433ms","start":"2026-05-27T06:32:05.330386Z","end":"2026-05-27T06:32:05.441431Z","steps":["trace[740488167] 'process raft request' (duration: 110.830527ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:33:10.841958Z","caller":"traceutil/trace.go:171","msg":"trace[1805895069] transaction","detail":"{read_only:false; response_revision:15025; number_of_response:1; }","duration":"170.703468ms","start":"2026-05-27T06:33:10.67123Z","end":"2026-05-27T06:33:10.841934Z","steps":["trace[1805895069] 'process raft request' (duration: 170.541153ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:33:10.9217Z","caller":"traceutil/trace.go:171","msg":"trace[1492068049] transaction","detail":"{read_only:false; response_revision:15026; number_of_response:1; }","duration":"114.538701ms","start":"2026-05-27T06:33:10.807138Z","end":"2026-05-27T06:33:10.921676Z","steps":["trace[1492068049] 'process raft request' (duration: 90.608479ms)","trace[1492068049] 'compare' (duration: 23.778358ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:33:10.921952Z","caller":"traceutil/trace.go:171","msg":"trace[1100763105] transaction","detail":"{read_only:false; response_revision:15027; number_of_response:1; }","duration":"114.45605ms","start":"2026-05-27T06:33:10.807481Z","end":"2026-05-27T06:33:10.921937Z","steps":["trace[1100763105] 'process raft request' (duration: 114.162791ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:34:23.344666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12869} {"level":"info","ts":"2026-05-27T06:34:23.421401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12869,"took":"74.02488ms","hash":952506470,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":31186944,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-27T06:34:23.421473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":952506470,"revision":12869,"compact-revision":10781} {"level":"info","ts":"2026-05-27T06:39:23.350971Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15466} {"level":"info","ts":"2026-05-27T06:39:23.44633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15466,"took":"92.553172ms","hash":123119845,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":32288768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T06:39:23.44661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":123119845,"revision":15466,"compact-revision":12869} {"level":"info","ts":"2026-05-27T06:39:26.711256Z","caller":"traceutil/trace.go:171","msg":"trace[2068489043] transaction","detail":"{read_only:false; response_revision:17728; number_of_response:1; }","duration":"132.119447ms","start":"2026-05-27T06:39:26.579122Z","end":"2026-05-27T06:39:26.711242Z","steps":["trace[2068489043] 'process raft request' (duration: 132.014734ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:39:26.786254Z","caller":"traceutil/trace.go:171","msg":"trace[1878597470] linearizableReadLoop","detail":"{readStateIndex:18332; appliedIndex:18330; }","duration":"157.751936ms","start":"2026-05-27T06:39:26.628478Z","end":"2026-05-27T06:39:26.78623Z","steps":["trace[1878597470] 'read index received' (duration: 82.678168ms)","trace[1878597470] 'applied index is now lower than readState.Index' (duration: 75.072828ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:39:26.786408Z","caller":"traceutil/trace.go:171","msg":"trace[2087836192] transaction","detail":"{read_only:false; response_revision:17729; number_of_response:1; }","duration":"185.291469ms","start":"2026-05-27T06:39:26.601078Z","end":"2026-05-27T06:39:26.78637Z","steps":["trace[2087836192] 'process raft request' (duration: 152.80548ms)","trace[2087836192] 'compare' (duration: 32.228992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:39:26.786482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.988593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-27T06:39:26.786525Z","caller":"traceutil/trace.go:171","msg":"trace[319886306] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:17729; }","duration":"158.071815ms","start":"2026-05-27T06:39:26.628442Z","end":"2026-05-27T06:39:26.786514Z","steps":["trace[319886306] 'agreement among raft nodes before linearized reading' (duration: 157.925401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:26.786644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.048841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-27T06:39:26.786649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.546525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T06:39:26.786699Z","caller":"traceutil/trace.go:171","msg":"trace[325079928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17729; }","duration":"134.111443ms","start":"2026-05-27T06:39:26.652573Z","end":"2026-05-27T06:39:26.786684Z","steps":["trace[325079928] 'agreement among raft nodes before linearized reading' (duration: 133.921477ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:39:26.786733Z","caller":"traceutil/trace.go:171","msg":"trace[661294727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17729; }","duration":"134.665887ms","start":"2026-05-27T06:39:26.652048Z","end":"2026-05-27T06:39:26.786714Z","steps":["trace[661294727] 'agreement among raft nodes before linearized reading' (duration: 134.39574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:27.168118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.814228ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-27T06:39:27.168202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:39:26.788493Z","time spent":"379.70428ms","remote":"127.0.0.1:59318","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-27T06:39:27.200623Z","caller":"traceutil/trace.go:171","msg":"trace[1794663747] transaction","detail":"{read_only:false; response_revision:17730; number_of_response:1; }","duration":"409.775072ms","start":"2026-05-27T06:39:26.790804Z","end":"2026-05-27T06:39:27.200579Z","steps":["trace[1794663747] 'process raft request' (duration: 409.361891ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:39:27.200621Z","caller":"traceutil/trace.go:171","msg":"trace[159329062] transaction","detail":"{read_only:false; response_revision:17731; number_of_response:1; }","duration":"409.274349ms","start":"2026-05-27T06:39:26.791307Z","end":"2026-05-27T06:39:27.200581Z","steps":["trace[159329062] 'process raft request' (duration: 409.166456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:27.200793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:39:26.790783Z","time spent":"409.921887ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:39:27.200939Z","caller":"traceutil/trace.go:171","msg":"trace[1029570266] transaction","detail":"{read_only:false; response_revision:17732; number_of_response:1; }","duration":"409.466974ms","start":"2026-05-27T06:39:26.791435Z","end":"2026-05-27T06:39:27.200902Z","steps":["trace[1029570266] 'process raft request' (duration: 409.093174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:27.201093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.5355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:39:27.201148Z","caller":"traceutil/trace.go:171","msg":"trace[2023334779] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:17732; }","duration":"321.666914ms","start":"2026-05-27T06:39:26.87947Z","end":"2026-05-27T06:39:27.201137Z","steps":["trace[2023334779] 'agreement among raft nodes before linearized reading' (duration: 321.501279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:27.201181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:39:26.879449Z","time spent":"321.723596ms","remote":"127.0.0.1:41654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:39:27.201192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:39:26.791424Z","time spent":"409.625398ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:39:27.200915Z","caller":"traceutil/trace.go:171","msg":"trace[1596149776] linearizableReadLoop","detail":"{readStateIndex:18336; appliedIndex:18332; }","duration":"321.316785ms","start":"2026-05-27T06:39:26.87953Z","end":"2026-05-27T06:39:27.200847Z","steps":["trace[1596149776] 'read index received' (duration: 108.770991ms)","trace[1596149776] 'applied index is now lower than readState.Index' (duration: 212.542054ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:39:27.201457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:39:26.791287Z","time spent":"409.436523ms","remote":"127.0.0.1:59560","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-05-27T06:39:27.209701Z","caller":"traceutil/trace.go:171","msg":"trace[1396667029] transaction","detail":"{read_only:false; response_revision:17734; number_of_response:1; }","duration":"173.535463ms","start":"2026-05-27T06:39:27.036149Z","end":"2026-05-27T06:39:27.209684Z","steps":["trace[1396667029] 'process raft request' (duration: 173.480522ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:39:27.209862Z","caller":"traceutil/trace.go:171","msg":"trace[1968114186] transaction","detail":"{read_only:false; response_revision:17733; number_of_response:1; }","duration":"206.586358ms","start":"2026-05-27T06:39:27.003158Z","end":"2026-05-27T06:39:27.209744Z","steps":["trace[1968114186] 'process raft request' (duration: 206.381712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:27.209877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.489371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:39:27.209959Z","caller":"traceutil/trace.go:171","msg":"trace[623395588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17735; }","duration":"113.599984ms","start":"2026-05-27T06:39:27.096346Z","end":"2026-05-27T06:39:27.209946Z","steps":["trace[623395588] 'agreement among raft nodes before linearized reading' (duration: 113.476201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:27.210063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.126502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T06:39:27.210201Z","caller":"traceutil/trace.go:171","msg":"trace[66082067] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:17735; }","duration":"229.258515ms","start":"2026-05-27T06:39:26.980909Z","end":"2026-05-27T06:39:27.210167Z","steps":["trace[66082067] 'agreement among raft nodes before linearized reading' (duration: 228.983558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:27.210272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.617993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-27T06:39:27.210472Z","caller":"traceutil/trace.go:171","msg":"trace[1383091113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17735; }","duration":"312.837389ms","start":"2026-05-27T06:39:26.897612Z","end":"2026-05-27T06:39:27.210449Z","steps":["trace[1383091113] 'agreement among raft nodes before linearized reading' (duration: 312.198352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:39:27.210632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:39:26.897596Z","time spent":"313.012645ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T06:43:06.387809Z","caller":"traceutil/trace.go:171","msg":"trace[1691655299] transaction","detail":"{read_only:false; response_revision:18970; number_of_response:1; }","duration":"183.516116ms","start":"2026-05-27T06:43:06.204245Z","end":"2026-05-27T06:43:06.387761Z","steps":["trace[1691655299] 'process raft request' (duration: 183.245208ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:06.388772Z","caller":"traceutil/trace.go:171","msg":"trace[312601579] transaction","detail":"{read_only:false; response_revision:18971; number_of_response:1; }","duration":"184.447571ms","start":"2026-05-27T06:43:06.204303Z","end":"2026-05-27T06:43:06.388751Z","steps":["trace[312601579] 'process raft request' (duration: 184.312917ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:06.389052Z","caller":"traceutil/trace.go:171","msg":"trace[1374756247] linearizableReadLoop","detail":"{readStateIndex:19626; appliedIndex:19624; }","duration":"184.531104ms","start":"2026-05-27T06:43:06.204456Z","end":"2026-05-27T06:43:06.388987Z","steps":["trace[1374756247] 'read index received' (duration: 183.023812ms)","trace[1374756247] 'applied index is now lower than readState.Index' (duration: 1.505572ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:06.389191Z","caller":"traceutil/trace.go:171","msg":"trace[281870719] transaction","detail":"{read_only:false; response_revision:18972; number_of_response:1; }","duration":"184.078801ms","start":"2026-05-27T06:43:06.205102Z","end":"2026-05-27T06:43:06.389181Z","steps":["trace[281870719] 'process raft request' (duration: 183.608168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:06.389642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.161452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-27T06:43:06.389787Z","caller":"traceutil/trace.go:171","msg":"trace[385349607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18972; }","duration":"185.308115ms","start":"2026-05-27T06:43:06.204417Z","end":"2026-05-27T06:43:06.389725Z","steps":["trace[385349607] 'agreement among raft nodes before linearized reading' (duration: 185.050968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:12.29101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970757911208732,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:12.791484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970757911208732,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:13.292467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970757911208732,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:13.792732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970757911208732,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:14.09642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000312302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-27T06:43:14.09651Z","caller":"traceutil/trace.go:171","msg":"trace[708013448] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000448906s","start":"2026-05-27T06:43:12.096036Z","end":"2026-05-27T06:43:14.096485Z","steps":["trace[708013448] 'agreement among raft nodes before linearized reading' (duration: 2.000330403s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.096573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.096017Z","time spent":"2.000542638s","remote":"127.0.0.1:59286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T06:43:14.293317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970757911208732,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:14.367529Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.841758425s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T06:43:14.368032Z","caller":"traceutil/trace.go:171","msg":"trace[791938725] transaction","detail":"{read_only:false; response_revision:18996; number_of_response:1; }","duration":"2.84233588s","start":"2026-05-27T06:43:11.52567Z","end":"2026-05-27T06:43:14.368006Z","steps":["trace[791938725] 'process raft request' (duration: 2.842157775s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.36828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:11.525651Z","time spent":"2.842457364s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:14.664827Z","caller":"traceutil/trace.go:171","msg":"trace[467406635] transaction","detail":"{read_only:false; response_revision:18997; number_of_response:1; }","duration":"3.125320237s","start":"2026-05-27T06:43:11.539479Z","end":"2026-05-27T06:43:14.6648Z","steps":["trace[467406635] 'process raft request' (duration: 3.125023628s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.664852Z","caller":"traceutil/trace.go:171","msg":"trace[562405472] transaction","detail":"{read_only:false; response_revision:18998; number_of_response:1; }","duration":"2.679927049s","start":"2026-05-27T06:43:11.984892Z","end":"2026-05-27T06:43:14.664819Z","steps":["trace[562405472] 'process raft request' (duration: 2.679761265s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.664988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:11.53945Z","time spent":"3.125456771s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:14.665194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:11.984867Z","time spent":"2.680065602s","remote":"127.0.0.1:59560","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-05-27T06:43:14.785565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.849632ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T06:43:14.786144Z","caller":"traceutil/trace.go:171","msg":"trace[1183218241] linearizableReadLoop","detail":"{readStateIndex:19658; appliedIndex:19651; }","duration":"2.995527698s","start":"2026-05-27T06:43:11.790584Z","end":"2026-05-27T06:43:14.786111Z","steps":["trace[1183218241] 'read index received' (duration: 2.577221818s)","trace[1183218241] 'applied index is now lower than readState.Index' (duration: 418.30514ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:14.786282Z","caller":"traceutil/trace.go:171","msg":"trace[714381654] transaction","detail":"{read_only:false; response_revision:19000; number_of_response:1; }","duration":"1.744035475s","start":"2026-05-27T06:43:13.042196Z","end":"2026-05-27T06:43:14.786231Z","steps":["trace[714381654] 'process raft request' (duration: 1.743718476s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.786308Z","caller":"traceutil/trace.go:171","msg":"trace[516257973] transaction","detail":"{read_only:false; response_revision:19001; number_of_response:1; }","duration":"1.67448209s","start":"2026-05-27T06:43:13.111794Z","end":"2026-05-27T06:43:14.786276Z","steps":["trace[516257973] 'process raft request' (duration: 1.674268714s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.786427Z","caller":"traceutil/trace.go:171","msg":"trace[1827958525] transaction","detail":"{read_only:false; response_revision:18999; number_of_response:1; }","duration":"1.789192344s","start":"2026-05-27T06:43:12.997215Z","end":"2026-05-27T06:43:14.786407Z","steps":["trace[1827958525] 'process raft request' (duration: 1.788574497s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.786463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.995878167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-27T06:43:14.786458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.111778Z","time spent":"1.674603054s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:14.786494Z","caller":"traceutil/trace.go:171","msg":"trace[175002692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19001; }","duration":"2.99598136s","start":"2026-05-27T06:43:11.790502Z","end":"2026-05-27T06:43:14.786483Z","steps":["trace[175002692] 'agreement among raft nodes before linearized reading' (duration: 2.995863887s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.786492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.042178Z","time spent":"1.744192219s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:14.786619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.824729023s","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:8"} {"level":"warn","ts":"2026-05-27T06:43:14.786521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:11.790475Z","time spent":"2.996039722s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T06:43:14.786674Z","caller":"traceutil/trace.go:171","msg":"trace[2007899470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19001; }","duration":"2.824855737s","start":"2026-05-27T06:43:11.961804Z","end":"2026-05-27T06:43:14.786659Z","steps":["trace[2007899470] 'agreement among raft nodes before linearized reading' (duration: 2.824716143s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.78668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.361846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T06:43:14.786717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:11.961767Z","time spent":"2.824939839s","remote":"127.0.0.1:53248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:43:14.786516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.997189Z","time spent":"1.789278615s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:14.786731Z","caller":"traceutil/trace.go:171","msg":"trace[1617587399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19001; }","duration":"689.443018ms","start":"2026-05-27T06:43:14.097274Z","end":"2026-05-27T06:43:14.786717Z","steps":["trace[1617587399] 'agreement among raft nodes before linearized reading' (duration: 689.356076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.786772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:14.097257Z","time spent":"689.503049ms","remote":"127.0.0.1:59292","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-05-27T06:43:14.78679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.006056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:14.786843Z","caller":"traceutil/trace.go:171","msg":"trace[1600521918] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:19001; }","duration":"467.104428ms","start":"2026-05-27T06:43:14.319721Z","end":"2026-05-27T06:43:14.786825Z","steps":["trace[1600521918] 'agreement among raft nodes before linearized reading' (duration: 467.008166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.786821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.85306999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-05-27T06:43:14.786873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:14.319693Z","time spent":"467.1711ms","remote":"127.0.0.1:60806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-05-27T06:43:14.7869Z","caller":"traceutil/trace.go:171","msg":"trace[178896736] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19001; }","duration":"1.853211544s","start":"2026-05-27T06:43:12.93367Z","end":"2026-05-27T06:43:14.786881Z","steps":["trace[178896736] 'agreement among raft nodes before linearized reading' (duration: 1.852937096s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.786946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.933639Z","time spent":"1.853298536s","remote":"127.0.0.1:59466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:14.786948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135682936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-27T06:43:14.786959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.374901943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-27T06:43:14.786971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.872530498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mvmsp\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-05-27T06:43:14.787005Z","caller":"traceutil/trace.go:171","msg":"trace[226349026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19001; }","duration":"2.374982745s","start":"2026-05-27T06:43:12.412008Z","end":"2026-05-27T06:43:14.786991Z","steps":["trace[226349026] 'agreement among raft nodes before linearized reading' (duration: 2.374861802s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.787004Z","caller":"traceutil/trace.go:171","msg":"trace[1743232867] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:19001; }","duration":"2.135777479s","start":"2026-05-27T06:43:12.651207Z","end":"2026-05-27T06:43:14.786984Z","steps":["trace[1743232867] 'agreement among raft nodes before linearized reading' (duration: 2.135649845s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.786998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.422566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-27T06:43:14.787041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.411985Z","time spent":"2.375048157s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T06:43:14.787043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.651184Z","time spent":"2.135850581s","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":4,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-05-27T06:43:14.787047Z","caller":"traceutil/trace.go:171","msg":"trace[1860302986] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mvmsp; range_end:; response_count:1; response_revision:19001; }","duration":"1.872640811s","start":"2026-05-27T06:43:12.914381Z","end":"2026-05-27T06:43:14.787022Z","steps":["trace[1860302986] 'agreement among raft nodes before linearized reading' (duration: 1.872383004s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.787064Z","caller":"traceutil/trace.go:171","msg":"trace[1248343324] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:19001; }","duration":"809.555029ms","start":"2026-05-27T06:43:13.977493Z","end":"2026-05-27T06:43:14.787048Z","steps":["trace[1248343324] 'agreement among raft nodes before linearized reading' (duration: 809.392555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.787079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764690366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-05-27T06:43:14.787085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.375017036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T06:43:14.787096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.914361Z","time spent":"1.872723763s","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-mvmsp\" "} {"level":"warn","ts":"2026-05-27T06:43:14.787111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.977469Z","time spent":"809.628392ms","remote":"127.0.0.1:59766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":41,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-27T06:43:14.787099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.378050139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-27T06:43:14.787146Z","caller":"traceutil/trace.go:171","msg":"trace[1030115412] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19001; }","duration":"1.764791909s","start":"2026-05-27T06:43:13.022332Z","end":"2026-05-27T06:43:14.787124Z","steps":["trace[1030115412] 'agreement among raft nodes before linearized reading' (duration: 1.764515491s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.787155Z","caller":"traceutil/trace.go:171","msg":"trace[1963812412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19001; }","duration":"2.375125909s","start":"2026-05-27T06:43:12.412008Z","end":"2026-05-27T06:43:14.787134Z","steps":["trace[1963812412] 'agreement among raft nodes before linearized reading' (duration: 2.374913853s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.787196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.411987Z","time spent":"2.375200971s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T06:43:14.787186Z","caller":"traceutil/trace.go:171","msg":"trace[482975785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19001; }","duration":"2.378178633s","start":"2026-05-27T06:43:12.408991Z","end":"2026-05-27T06:43:14.78717Z","steps":["trace[482975785] 'agreement among raft nodes before linearized reading' (duration: 2.377994348s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.787212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:13.022314Z","time spent":"1.764867371s","remote":"127.0.0.1:59466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:14.787241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.408966Z","time spent":"2.378262235s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T06:43:14.787446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852753552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-05-27T06:43:14.787602Z","caller":"traceutil/trace.go:171","msg":"trace[551592702] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19001; }","duration":"1.852924866s","start":"2026-05-27T06:43:12.934623Z","end":"2026-05-27T06:43:14.787548Z","steps":["trace[551592702] 'agreement among raft nodes before linearized reading' (duration: 1.852482484s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.787691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.934593Z","time spent":"1.85305602s","remote":"127.0.0.1:59466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-05-27T06:43:14.786674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.368569368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T06:43:14.788576Z","caller":"traceutil/trace.go:171","msg":"trace[937185937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19001; }","duration":"2.3704822s","start":"2026-05-27T06:43:12.418072Z","end":"2026-05-27T06:43:14.788554Z","steps":["trace[937185937] 'agreement among raft nodes before linearized reading' (duration: 2.368540416s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.788611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:12.41806Z","time spent":"2.370539002s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T06:43:14.99734Z","caller":"traceutil/trace.go:171","msg":"trace[1256175944] transaction","detail":"{read_only:false; response_revision:19003; number_of_response:1; }","duration":"205.675399ms","start":"2026-05-27T06:43:14.791628Z","end":"2026-05-27T06:43:14.997304Z","steps":["trace[1256175944] 'process raft request' (duration: 156.423387ms)","trace[1256175944] 'compare' (duration: 49.010275ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:14.997401Z","caller":"traceutil/trace.go:171","msg":"trace[26819299] transaction","detail":"{read_only:false; response_revision:19005; number_of_response:1; }","duration":"204.854646ms","start":"2026-05-27T06:43:14.792523Z","end":"2026-05-27T06:43:14.997378Z","steps":["trace[26819299] 'process raft request' (duration: 204.766993ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.997515Z","caller":"traceutil/trace.go:171","msg":"trace[1819757130] transaction","detail":"{read_only:false; response_revision:19004; number_of_response:1; }","duration":"205.798862ms","start":"2026-05-27T06:43:14.791693Z","end":"2026-05-27T06:43:14.997492Z","steps":["trace[1819757130] 'process raft request' (duration: 205.531045ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.997768Z","caller":"traceutil/trace.go:171","msg":"trace[1951718595] transaction","detail":"{read_only:false; response_revision:19006; number_of_response:1; }","duration":"204.237909ms","start":"2026-05-27T06:43:14.793498Z","end":"2026-05-27T06:43:14.997736Z","steps":["trace[1951718595] 'process raft request' (duration: 203.840178ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.997916Z","caller":"traceutil/trace.go:171","msg":"trace[1111028271] linearizableReadLoop","detail":"{readStateIndex:19661; appliedIndex:19658; }","duration":"205.37115ms","start":"2026-05-27T06:43:14.792528Z","end":"2026-05-27T06:43:14.997899Z","steps":["trace[1111028271] 'read index received' (duration: 23.626573ms)","trace[1111028271] 'applied index is now lower than readState.Index' (duration: 181.743187ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:14.998066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.200088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:14.998106Z","caller":"traceutil/trace.go:171","msg":"trace[658266323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19007; }","duration":"204.27677ms","start":"2026-05-27T06:43:14.793819Z","end":"2026-05-27T06:43:14.998096Z","steps":["trace[658266323] 'agreement among raft nodes before linearized reading' (duration: 204.185157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.998164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.068231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T06:43:14.998209Z","caller":"traceutil/trace.go:171","msg":"trace[732427423] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:19007; }","duration":"154.193994ms","start":"2026-05-27T06:43:14.844005Z","end":"2026-05-27T06:43:14.998199Z","steps":["trace[732427423] 'agreement among raft nodes before linearized reading' (duration: 154.085101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.998238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.673255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-27T06:43:14.998293Z","caller":"traceutil/trace.go:171","msg":"trace[129803130] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19007; }","duration":"108.779859ms","start":"2026-05-27T06:43:14.8895Z","end":"2026-05-27T06:43:14.99828Z","steps":["trace[129803130] 'agreement among raft nodes before linearized reading' (duration: 108.568943ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:14.99809Z","caller":"traceutil/trace.go:171","msg":"trace[67906141] transaction","detail":"{read_only:false; response_revision:19007; number_of_response:1; }","duration":"203.067437ms","start":"2026-05-27T06:43:14.794969Z","end":"2026-05-27T06:43:14.998036Z","steps":["trace[67906141] 'process raft request' (duration: 202.664046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:14.999172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.623065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942597"} {"level":"info","ts":"2026-05-27T06:43:14.99923Z","caller":"traceutil/trace.go:171","msg":"trace[1436087655] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19007; }","duration":"206.716518ms","start":"2026-05-27T06:43:14.792502Z","end":"2026-05-27T06:43:14.999219Z","steps":["trace[1436087655] 'agreement among raft nodes before linearized reading' (duration: 205.496544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:47.455068Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970757911209256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:47.956268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970757911209256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T06:43:48.114532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.231811859s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T06:43:48.134211Z","caller":"traceutil/trace.go:171","msg":"trace[926855222] transaction","detail":"{read_only:false; response_revision:19165; number_of_response:1; }","duration":"1.251548585s","start":"2026-05-27T06:43:46.882632Z","end":"2026-05-27T06:43:48.134181Z","steps":["trace[926855222] 'process raft request' (duration: 1.232090777s)","trace[926855222] 'compare' (duration: 19.329534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:48.134387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.88261Z","time spent":"1.251689648s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T06:43:48.146617Z","caller":"traceutil/trace.go:171","msg":"trace[1507794761] linearizableReadLoop","detail":"{readStateIndex:19830; appliedIndex:19828; }","duration":"1.191636738s","start":"2026-05-27T06:43:46.954958Z","end":"2026-05-27T06:43:48.146595Z","steps":["trace[1507794761] 'read index received' (duration: 1.159773957s)","trace[1507794761] 'applied index is now lower than readState.Index' (duration: 31.862131ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:48.146715Z","caller":"traceutil/trace.go:171","msg":"trace[1895831277] transaction","detail":"{read_only:false; response_revision:19166; number_of_response:1; }","duration":"1.255633257s","start":"2026-05-27T06:43:46.891043Z","end":"2026-05-27T06:43:48.146677Z","steps":["trace[1895831277] 'process raft request' (duration: 1.255411511s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.146758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050761261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:48.146792Z","caller":"traceutil/trace.go:171","msg":"trace[482692938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19166; }","duration":"1.050854373s","start":"2026-05-27T06:43:47.095929Z","end":"2026-05-27T06:43:48.146784Z","steps":["trace[482692938] 'agreement among raft nodes before linearized reading' (duration: 1.050777631s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.146824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.095907Z","time spent":"1.050911085s","remote":"127.0.0.1:59286","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-05-27T06:43:48.146871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.891011Z","time spent":"1.255775421s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T06:43:48.146932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.630557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-27T06:43:48.14694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.759384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-27T06:43:48.14696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.309603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mvmsp\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-05-27T06:43:48.146974Z","caller":"traceutil/trace.go:171","msg":"trace[1011057891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19166; }","duration":"999.712539ms","start":"2026-05-27T06:43:47.147251Z","end":"2026-05-27T06:43:48.146964Z","steps":["trace[1011057891] 'agreement among raft nodes before linearized reading' (duration: 999.572825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.147006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.147231Z","time spent":"999.769191ms","remote":"127.0.0.1:59560","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-05-27T06:43:48.147006Z","caller":"traceutil/trace.go:171","msg":"trace[39783331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19166; }","duration":"989.858247ms","start":"2026-05-27T06:43:47.157131Z","end":"2026-05-27T06:43:48.14699Z","steps":["trace[39783331] 'agreement among raft nodes before linearized reading' (duration: 989.626741ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.147015Z","caller":"traceutil/trace.go:171","msg":"trace[626463687] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mvmsp; range_end:; response_count:1; response_revision:19166; }","duration":"758.366164ms","start":"2026-05-27T06:43:47.388637Z","end":"2026-05-27T06:43:48.147003Z","steps":["trace[626463687] 'agreement among raft nodes before linearized reading' (duration: 758.172669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.146971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191983837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-27T06:43:48.147004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005878999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-27T06:43:48.147108Z","caller":"traceutil/trace.go:171","msg":"trace[1718418903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19166; }","duration":"1.192179013s","start":"2026-05-27T06:43:46.954904Z","end":"2026-05-27T06:43:48.147083Z","steps":["trace[1718418903] 'agreement among raft nodes before linearized reading' (duration: 1.191804262s)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.147122Z","caller":"traceutil/trace.go:171","msg":"trace[2104729810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19166; }","duration":"1.006032824s","start":"2026-05-27T06:43:47.141062Z","end":"2026-05-27T06:43:48.147095Z","steps":["trace[2104729810] 'agreement among raft nodes before linearized reading' (duration: 1.005757136s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.147136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000516372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-27T06:43:48.147168Z","caller":"traceutil/trace.go:171","msg":"trace[1916267130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19166; }","duration":"1.000590904s","start":"2026-05-27T06:43:47.146569Z","end":"2026-05-27T06:43:48.14716Z","steps":["trace[1916267130] 'agreement among raft nodes before linearized reading' (duration: 1.000220133s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.147174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.141048Z","time spent":"1.006117227s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T06:43:48.147187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.146487Z","time spent":"1.000695576s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T06:43:48.14718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:46.954881Z","time spent":"1.192281226s","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T06:43:48.147043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.388568Z","time spent":"758.469737ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-mvmsp\" "} {"level":"warn","ts":"2026-05-27T06:43:48.147046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T06:43:47.157121Z","time spent":"989.917838ms","remote":"127.0.0.1:59560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T06:43:48.306195Z","caller":"traceutil/trace.go:171","msg":"trace[1080561742] transaction","detail":"{read_only:false; response_revision:19168; number_of_response:1; }","duration":"154.623667ms","start":"2026-05-27T06:43:48.151549Z","end":"2026-05-27T06:43:48.306173Z","steps":["trace[1080561742] 'process raft request' (duration: 107.883594ms)","trace[1080561742] 'compare' (duration: 46.578608ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:48.306586Z","caller":"traceutil/trace.go:171","msg":"trace[1678031554] transaction","detail":"{read_only:false; response_revision:19170; number_of_response:1; }","duration":"154.790992ms","start":"2026-05-27T06:43:48.151778Z","end":"2026-05-27T06:43:48.306569Z","steps":["trace[1678031554] 'process raft request' (duration: 154.635017ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.306572Z","caller":"traceutil/trace.go:171","msg":"trace[432603711] transaction","detail":"{read_only:false; response_revision:19169; number_of_response:1; }","duration":"154.958916ms","start":"2026-05-27T06:43:48.151549Z","end":"2026-05-27T06:43:48.306508Z","steps":["trace[432603711] 'process raft request' (duration: 154.584326ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.306668Z","caller":"traceutil/trace.go:171","msg":"trace[430292532] linearizableReadLoop","detail":"{readStateIndex:19834; appliedIndex:19830; }","duration":"154.432392ms","start":"2026-05-27T06:43:48.152215Z","end":"2026-05-27T06:43:48.306647Z","steps":["trace[430292532] 'read index received' (duration: 2.943702ms)","trace[430292532] 'applied index is now lower than readState.Index' (duration: 151.48743ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:48.306769Z","caller":"traceutil/trace.go:171","msg":"trace[884602543] transaction","detail":"{read_only:false; response_revision:19171; number_of_response:1; }","duration":"154.229446ms","start":"2026-05-27T06:43:48.152523Z","end":"2026-05-27T06:43:48.306753Z","steps":["trace[884602543] 'process raft request' (duration: 153.978059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.306833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.661597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T06:43:48.30689Z","caller":"traceutil/trace.go:171","msg":"trace[1540009207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19171; }","duration":"150.745539ms","start":"2026-05-27T06:43:48.156131Z","end":"2026-05-27T06:43:48.306877Z","steps":["trace[1540009207] 'agreement among raft nodes before linearized reading' (duration: 150.648007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:48.307677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.45594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942597"} {"level":"info","ts":"2026-05-27T06:43:48.307736Z","caller":"traceutil/trace.go:171","msg":"trace[2092545424] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19171; }","duration":"155.535612ms","start":"2026-05-27T06:43:48.152188Z","end":"2026-05-27T06:43:48.307724Z","steps":["trace[2092545424] 'agreement among raft nodes before linearized reading' (duration: 154.566985ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:48.625309Z","caller":"traceutil/trace.go:171","msg":"trace[1102289676] transaction","detail":"{read_only:false; response_revision:19172; number_of_response:1; }","duration":"140.818155ms","start":"2026-05-27T06:43:48.484468Z","end":"2026-05-27T06:43:48.625286Z","steps":["trace[1102289676] 'process raft request' (duration: 140.66773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.390356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.483391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T06:43:52.390439Z","caller":"traceutil/trace.go:171","msg":"trace[420728077] linearizableReadLoop","detail":"{readStateIndex:19850; appliedIndex:19849; }","duration":"150.373539ms","start":"2026-05-27T06:43:52.240055Z","end":"2026-05-27T06:43:52.390428Z","steps":["trace[420728077] 'read index received' (duration: 20.707843ms)","trace[420728077] 'applied index is now lower than readState.Index' (duration: 129.664856ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:52.390721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.915352ms","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:6"} {"level":"info","ts":"2026-05-27T06:43:52.390756Z","caller":"traceutil/trace.go:171","msg":"trace[1659695600] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:19185; }","duration":"138.994844ms","start":"2026-05-27T06:43:52.251751Z","end":"2026-05-27T06:43:52.390746Z","steps":["trace[1659695600] 'agreement among raft nodes before linearized reading' (duration: 138.945213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T06:43:52.390782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.729299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T06:43:52.39084Z","caller":"traceutil/trace.go:171","msg":"trace[31223328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19185; }","duration":"150.825081ms","start":"2026-05-27T06:43:52.240001Z","end":"2026-05-27T06:43:52.390826Z","steps":["trace[31223328] 'agreement among raft nodes before linearized reading' (duration: 150.676107ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.390809Z","caller":"traceutil/trace.go:171","msg":"trace[808644950] transaction","detail":"{read_only:false; response_revision:19185; number_of_response:1; }","duration":"158.907615ms","start":"2026-05-27T06:43:52.231842Z","end":"2026-05-27T06:43:52.39075Z","steps":["trace[808644950] 'process raft request' (duration: 28.967521ms)","trace[808644950] 'compare' (duration: 129.389679ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:52.553019Z","caller":"traceutil/trace.go:171","msg":"trace[1039278081] transaction","detail":"{read_only:false; response_revision:19187; number_of_response:1; }","duration":"157.520676ms","start":"2026-05-27T06:43:52.395465Z","end":"2026-05-27T06:43:52.552986Z","steps":["trace[1039278081] 'process raft request' (duration: 129.987324ms)","trace[1039278081] 'compare' (duration: 27.335746ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:52.553102Z","caller":"traceutil/trace.go:171","msg":"trace[369652352] transaction","detail":"{read_only:false; response_revision:19188; number_of_response:1; }","duration":"156.877618ms","start":"2026-05-27T06:43:52.396199Z","end":"2026-05-27T06:43:52.553076Z","steps":["trace[369652352] 'process raft request' (duration: 156.719324ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.55315Z","caller":"traceutil/trace.go:171","msg":"trace[558007674] transaction","detail":"{read_only:false; response_revision:19189; number_of_response:1; }","duration":"156.799896ms","start":"2026-05-27T06:43:52.396337Z","end":"2026-05-27T06:43:52.553136Z","steps":["trace[558007674] 'process raft request' (duration: 156.626631ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.553314Z","caller":"traceutil/trace.go:171","msg":"trace[430335692] linearizableReadLoop","detail":"{readStateIndex:19855; appliedIndex:19851; }","duration":"148.788535ms","start":"2026-05-27T06:43:52.404512Z","end":"2026-05-27T06:43:52.553301Z","steps":["trace[430335692] 'read index received' (duration: 12.381222ms)","trace[430335692] 'applied index is now lower than readState.Index' (duration: 136.406123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:52.553517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.99153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mvmsp\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-05-27T06:43:52.553559Z","caller":"traceutil/trace.go:171","msg":"trace[612922654] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mvmsp; range_end:; response_count:1; response_revision:19190; }","duration":"149.061322ms","start":"2026-05-27T06:43:52.404487Z","end":"2026-05-27T06:43:52.553548Z","steps":["trace[612922654] 'agreement among raft nodes before linearized reading' (duration: 148.892198ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:52.553834Z","caller":"traceutil/trace.go:171","msg":"trace[2100829537] transaction","detail":"{read_only:false; response_revision:19190; number_of_response:1; }","duration":"157.28263ms","start":"2026-05-27T06:43:52.396503Z","end":"2026-05-27T06:43:52.55382Z","steps":["trace[2100829537] 'process raft request' (duration: 156.490568ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:53.377269Z","caller":"traceutil/trace.go:171","msg":"trace[1542803289] transaction","detail":"{read_only:false; response_revision:19193; number_of_response:1; }","duration":"111.667608ms","start":"2026-05-27T06:43:53.265584Z","end":"2026-05-27T06:43:53.377252Z","steps":["trace[1542803289] 'process raft request' (duration: 111.551465ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:54.704054Z","caller":"traceutil/trace.go:171","msg":"trace[1763954111] linearizableReadLoop","detail":"{readStateIndex:19865; appliedIndex:19861; }","duration":"130.5505ms","start":"2026-05-27T06:43:54.573487Z","end":"2026-05-27T06:43:54.704038Z","steps":["trace[1763954111] 'read index received' (duration: 15.572471ms)","trace[1763954111] 'applied index is now lower than readState.Index' (duration: 114.977399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T06:43:54.704232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.731325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-mvmsp\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-05-27T06:43:54.70426Z","caller":"traceutil/trace.go:171","msg":"trace[341220480] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-mvmsp; range_end:; response_count:1; response_revision:19200; }","duration":"130.798877ms","start":"2026-05-27T06:43:54.573455Z","end":"2026-05-27T06:43:54.704254Z","steps":["trace[341220480] 'agreement among raft nodes before linearized reading' (duration: 130.665254ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:54.704373Z","caller":"traceutil/trace.go:171","msg":"trace[1817100922] transaction","detail":"{read_only:false; response_revision:19198; number_of_response:1; }","duration":"142.375188ms","start":"2026-05-27T06:43:54.56196Z","end":"2026-05-27T06:43:54.704335Z","steps":["trace[1817100922] 'process raft request' (duration: 126.748875ms)","trace[1817100922] 'compare' (duration: 15.000825ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T06:43:54.70451Z","caller":"traceutil/trace.go:171","msg":"trace[1840583900] transaction","detail":"{read_only:false; response_revision:19199; number_of_response:1; }","duration":"142.011578ms","start":"2026-05-27T06:43:54.56247Z","end":"2026-05-27T06:43:54.704482Z","steps":["trace[1840583900] 'process raft request' (duration: 141.509464ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:54.704539Z","caller":"traceutil/trace.go:171","msg":"trace[1981495667] transaction","detail":"{read_only:false; response_revision:19200; number_of_response:1; }","duration":"141.598526ms","start":"2026-05-27T06:43:54.562788Z","end":"2026-05-27T06:43:54.704387Z","steps":["trace[1981495667] 'process raft request' (duration: 141.221636ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:43:55.894884Z","caller":"traceutil/trace.go:171","msg":"trace[128485817] transaction","detail":"{read_only:false; response_revision:19204; number_of_response:1; }","duration":"125.13305ms","start":"2026-05-27T06:43:55.76972Z","end":"2026-05-27T06:43:55.894853Z","steps":["trace[128485817] 'process raft request' (duration: 124.946455ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T06:44:20.714394Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T06:44:20.719258Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-27T06:44:20.719383Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-27T06:44:23.36405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17716} {"level":"info","ts":"2026-05-27T06:44:23.461013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17716,"took":"93.043374ms","hash":1326727515,"current-db-size-bytes":58630144,"current-db-size":"59 MB","current-db-size-in-use-bytes":27271168,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-27T06:44:23.46106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1326727515,"revision":17716,"compact-revision":15466}