{"level":"warn","ts":"2026-08-16T12:55:05.343843Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:55:05.343999Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-16T12:55:05.344145Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:55:05.34418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-16T12:55:05.344213Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-16T12:55:05.345216Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-08-16T12:55:05.345339Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-16T12:55:05.349131Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.505359ms"} {"level":"info","ts":"2026-08-16T12:55:05.354424Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-08-16T12:55:05.354529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:55:05.354562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-08-16T12:55:05.354657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-16T12:55:05.354676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-08-16T12:55:05.354716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-08-16T12:55:05.364191Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:55:05.365582Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:55:05.366831Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-16T12:55:05.369897Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-16T12:55:05.370046Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-16T12:55:05.370056Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:55:05.37022Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:55:05.370244Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-16T12:55:05.372155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-08-16T12:55:05.372484Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-16T12:55:05.375614Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-16T12:55:05.375747Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-16T12:55:05.375828Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-16T12:55:05.375954Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-16T12:55:05.37602Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:55:06.055247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:55:06.055345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:55:06.055404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-08-16T12:55:06.055426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:55:06.055437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-16T12:55:06.055455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-08-16T12:55:06.055469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-16T12:55:06.056485Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:55:06.056609Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:55:06.056666Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:55:06.05652Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-08-16T12:55:06.05691Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:55:06.05701Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-16T12:55:06.058034Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:55:06.05812Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:55:06.058145Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:55:06.059031Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:55:06.061428Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-08-16T12:55:21.97512Z","caller":"traceutil/trace.go:171","msg":"trace[1085042101] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"138.098033ms","start":"2026-08-16T12:55:21.836995Z","end":"2026-08-16T12:55:21.975093Z","steps":["trace[1085042101] 'process raft request' (duration: 137.886097ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:55:48.08014Z","caller":"traceutil/trace.go:171","msg":"trace[286940911] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"151.347253ms","start":"2026-08-16T12:55:47.928769Z","end":"2026-08-16T12:55:48.080116Z","steps":["trace[286940911] 'process raft request' (duration: 150.814938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:48.679353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.979323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:55:48.679433Z","caller":"traceutil/trace.go:171","msg":"trace[1453409137] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"460.024748ms","start":"2026-08-16T12:55:48.219398Z","end":"2026-08-16T12:55:48.679422Z","steps":["trace[1453409137] 'read index received' (duration: 269.430757ms)","trace[1453409137] 'applied index is now lower than readState.Index' (duration: 190.592811ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:55:48.679534Z","caller":"traceutil/trace.go:171","msg":"trace[659546557] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"464.312897ms","start":"2026-08-16T12:55:48.215211Z","end":"2026-08-16T12:55:48.679524Z","steps":["trace[659546557] 'process raft request' (duration: 273.685365ms)","trace[659546557] 'compare' (duration: 189.801338ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:55:48.679573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:48.215191Z","time spent":"464.359669ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:55:48.679868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.440039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:55:48.679914Z","caller":"traceutil/trace.go:171","msg":"trace[277193819] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:590; }","duration":"460.537432ms","start":"2026-08-16T12:55:48.219361Z","end":"2026-08-16T12:55:48.679899Z","steps":["trace[277193819] 'agreement among raft nodes before linearized reading' (duration: 460.435789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:48.679949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:48.219351Z","time spent":"460.587583ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-08-16T12:55:48.680328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.119468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-16T12:55:48.680407Z","caller":"traceutil/trace.go:171","msg":"trace[1619373966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"431.235252ms","start":"2026-08-16T12:55:48.249154Z","end":"2026-08-16T12:55:48.680389Z","steps":["trace[1619373966] 'agreement among raft nodes before linearized reading' (duration: 430.486431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:48.680449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:48.24914Z","time spent":"431.300364ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:55:48.680684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.501362ms","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-08-16T12:55:48.680779Z","caller":"traceutil/trace.go:171","msg":"trace[1804222014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"430.627745ms","start":"2026-08-16T12:55:48.25013Z","end":"2026-08-16T12:55:48.680758Z","steps":["trace[1804222014] 'agreement among raft nodes before linearized reading' (duration: 429.649008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:55:48.680835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:55:48.250124Z","time spent":"430.701427ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-16T12:56:03.103562Z","caller":"traceutil/trace.go:171","msg":"trace[1567377590] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"136.489237ms","start":"2026-08-16T12:56:02.967046Z","end":"2026-08-16T12:56:03.103535Z","steps":["trace[1567377590] 'process raft request' (duration: 136.269911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:57:16.069478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.61802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:57:16.069584Z","caller":"traceutil/trace.go:171","msg":"trace[633969573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1816; }","duration":"277.764915ms","start":"2026-08-16T12:57:15.7918Z","end":"2026-08-16T12:57:16.069565Z","steps":["trace[633969573] 'range keys from in-memory index tree' (duration: 277.549259ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:58:05.920665Z","caller":"traceutil/trace.go:171","msg":"trace[1288621639] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"113.810817ms","start":"2026-08-16T12:58:05.806825Z","end":"2026-08-16T12:58:05.920636Z","steps":["trace[1288621639] 'process raft request' (duration: 113.656203ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:58:11.807341Z","caller":"traceutil/trace.go:171","msg":"trace[341314153] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2244; }","duration":"361.381964ms","start":"2026-08-16T12:58:11.44593Z","end":"2026-08-16T12:58:11.807312Z","steps":["trace[341314153] 'read index received' (duration: 55.491443ms)","trace[341314153] 'applied index is now lower than readState.Index' (duration: 305.889181ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:58:11.807471Z","caller":"traceutil/trace.go:171","msg":"trace[1082710086] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"369.149775ms","start":"2026-08-16T12:58:11.438309Z","end":"2026-08-16T12:58:11.807458Z","steps":["trace[1082710086] 'process raft request' (duration: 368.839586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:58:11.807602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:58:11.438291Z","time spent":"369.219526ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:58:11.807746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.798317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-16T12:58:11.807826Z","caller":"traceutil/trace.go:171","msg":"trace[1344739485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2164; }","duration":"361.89945ms","start":"2026-08-16T12:58:11.445907Z","end":"2026-08-16T12:58:11.807807Z","steps":["trace[1344739485] 'agreement among raft nodes before linearized reading' (duration: 361.667483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:58:11.807886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:58:11.445897Z","time spent":"361.974452ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:58:12.06527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.20083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:58:12.065538Z","caller":"traceutil/trace.go:171","msg":"trace[1650406413] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2246; }","duration":"258.098568ms","start":"2026-08-16T12:58:11.807419Z","end":"2026-08-16T12:58:12.065517Z","steps":["trace[1650406413] 'read index received' (duration: 102.550418ms)","trace[1650406413] 'applied index is now lower than readState.Index' (duration: 155.54682ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:58:12.065638Z","caller":"traceutil/trace.go:171","msg":"trace[518578348] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"374.073105ms","start":"2026-08-16T12:58:11.691551Z","end":"2026-08-16T12:58:12.065624Z","steps":["trace[518578348] 'process raft request' (duration: 373.837027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:58:12.065731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:58:11.691523Z","time spent":"374.158288ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:58:12.065732Z","caller":"traceutil/trace.go:171","msg":"trace[435755416] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"467.276967ms","start":"2026-08-16T12:58:11.598394Z","end":"2026-08-16T12:58:12.065671Z","steps":["trace[435755416] 'process raft request' (duration: 311.556732ms)","trace[435755416] 'compare' (duration: 155.026765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:58:12.065947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:58:11.598367Z","time spent":"467.493633ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:58:12.066075Z","caller":"traceutil/trace.go:171","msg":"trace[742729585] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"254.273579ms","start":"2026-08-16T12:58:11.811743Z","end":"2026-08-16T12:58:12.066016Z","steps":["trace[742729585] 'process raft request' (duration: 253.732523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:58:12.066239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.679087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-16T12:58:12.066251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.704007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:58:12.066309Z","caller":"traceutil/trace.go:171","msg":"trace[1225878448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2167; }","duration":"595.787241ms","start":"2026-08-16T12:58:11.470505Z","end":"2026-08-16T12:58:12.066292Z","steps":["trace[1225878448] 'agreement among raft nodes before linearized reading' (duration: 595.288187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:58:12.066368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:58:11.470491Z","time spent":"595.865952ms","remote":"127.0.0.1:34640","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-08-16T12:58:12.066382Z","caller":"traceutil/trace.go:171","msg":"trace[1522293774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2167; }","duration":"275.86841ms","start":"2026-08-16T12:58:11.790486Z","end":"2026-08-16T12:58:12.066354Z","steps":["trace[1522293774] 'agreement among raft nodes before linearized reading' (duration: 275.235403ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:58:12.071015Z","caller":"traceutil/trace.go:171","msg":"trace[638623059] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"131.732944ms","start":"2026-08-16T12:58:11.938972Z","end":"2026-08-16T12:58:12.070705Z","steps":["trace[638623059] 'process raft request' (duration: 131.663462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:58:46.831926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.611667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T12:58:46.832024Z","caller":"traceutil/trace.go:171","msg":"trace[1749186336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2533; }","duration":"126.749681ms","start":"2026-08-16T12:58:46.705258Z","end":"2026-08-16T12:58:46.832008Z","steps":["trace[1749186336] 'range keys from in-memory index tree' (duration: 126.3686ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:59:20.43005Z","caller":"traceutil/trace.go:171","msg":"trace[1480039076] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"116.609033ms","start":"2026-08-16T12:59:20.313417Z","end":"2026-08-16T12:59:20.430027Z","steps":["trace[1480039076] 'process raft request' (duration: 116.453729ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:59:20.463397Z","caller":"traceutil/trace.go:171","msg":"trace[149053897] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"144.069611ms","start":"2026-08-16T12:59:20.319267Z","end":"2026-08-16T12:59:20.463337Z","steps":["trace[149053897] 'process raft request' (duration: 143.781073ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:59:20.630257Z","caller":"traceutil/trace.go:171","msg":"trace[1295589308] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"162.640127ms","start":"2026-08-16T12:59:20.467591Z","end":"2026-08-16T12:59:20.630231Z","steps":["trace[1295589308] 'process raft request' (duration: 162.589966ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:59:20.630332Z","caller":"traceutil/trace.go:171","msg":"trace[527374088] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"162.72832ms","start":"2026-08-16T12:59:20.467566Z","end":"2026-08-16T12:59:20.630294Z","steps":["trace[527374088] 'process raft request' (duration: 162.468233ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:01:52.130406Z","caller":"traceutil/trace.go:171","msg":"trace[1082602977] transaction","detail":"{read_only:false; response_revision:3735; number_of_response:1; }","duration":"155.623686ms","start":"2026-08-16T13:01:51.974764Z","end":"2026-08-16T13:01:52.130388Z","steps":["trace[1082602977] 'process raft request' (duration: 155.508213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:03:18.024689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.034191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T13:03:18.024784Z","caller":"traceutil/trace.go:171","msg":"trace[1478570826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4923; }","duration":"206.149825ms","start":"2026-08-16T13:03:17.818615Z","end":"2026-08-16T13:03:18.024765Z","steps":["trace[1478570826] 'range keys from in-memory index tree' (duration: 205.877997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:03:18.024884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.307277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-16T13:03:18.02501Z","caller":"traceutil/trace.go:171","msg":"trace[1500509709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4923; }","duration":"208.473992ms","start":"2026-08-16T13:03:17.81651Z","end":"2026-08-16T13:03:18.024984Z","steps":["trace[1500509709] 'range keys from in-memory index tree' (duration: 208.08808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:03:31.547258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.853197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:03:31.547567Z","caller":"traceutil/trace.go:171","msg":"trace[1166332415] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"195.648199ms","start":"2026-08-16T13:03:31.351896Z","end":"2026-08-16T13:03:31.547544Z","steps":["trace[1166332415] 'process raft request' (duration: 195.588017ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:31.547581Z","caller":"traceutil/trace.go:171","msg":"trace[462808164] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"216.602801ms","start":"2026-08-16T13:03:31.330941Z","end":"2026-08-16T13:03:31.547544Z","steps":["trace[462808164] 'process raft request' (duration: 96.346652ms)","trace[462808164] 'compare' (duration: 119.708593ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:03:31.547718Z","caller":"traceutil/trace.go:171","msg":"trace[704170449] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"197.355948ms","start":"2026-08-16T13:03:31.350321Z","end":"2026-08-16T13:03:31.547677Z","steps":["trace[704170449] 'process raft request' (duration: 197.0869ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:31.575603Z","caller":"traceutil/trace.go:171","msg":"trace[339663289] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"192.632134ms","start":"2026-08-16T13:03:31.382941Z","end":"2026-08-16T13:03:31.575573Z","steps":["trace[339663289] 'process raft request' (duration: 192.397907ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:31.722389Z","caller":"traceutil/trace.go:171","msg":"trace[1057337172] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"100.84621ms","start":"2026-08-16T13:03:31.62151Z","end":"2026-08-16T13:03:31.722356Z","steps":["trace[1057337172] 'process raft request' (duration: 100.607153ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:31.722487Z","caller":"traceutil/trace.go:171","msg":"trace[1994310431] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"100.605043ms","start":"2026-08-16T13:03:31.621849Z","end":"2026-08-16T13:03:31.722454Z","steps":["trace[1994310431] 'process raft request' (duration: 100.424228ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:32.015391Z","caller":"traceutil/trace.go:171","msg":"trace[649167233] linearizableReadLoop","detail":"{readStateIndex:5403; appliedIndex:5402; }","duration":"223.616609ms","start":"2026-08-16T13:03:31.791749Z","end":"2026-08-16T13:03:32.015366Z","steps":["trace[649167233] 'read index received' (duration: 182.451715ms)","trace[649167233] 'applied index is now lower than readState.Index' (duration: 41.163604ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:03:32.015539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.780223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:03:32.015574Z","caller":"traceutil/trace.go:171","msg":"trace[231968395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5206; }","duration":"223.861186ms","start":"2026-08-16T13:03:31.791702Z","end":"2026-08-16T13:03:32.015563Z","steps":["trace[231968395] 'agreement among raft nodes before linearized reading' (duration: 223.770413ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:32.015875Z","caller":"traceutil/trace.go:171","msg":"trace[193245547] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"320.436625ms","start":"2026-08-16T13:03:31.695425Z","end":"2026-08-16T13:03:32.015862Z","steps":["trace[193245547] 'process raft request' (duration: 278.845579ms)","trace[193245547] 'compare' (duration: 40.981019ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:03:32.015961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:03:31.695374Z","time spent":"320.528128ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:03:40.163582Z","caller":"traceutil/trace.go:171","msg":"trace[2032062535] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"116.916792ms","start":"2026-08-16T13:03:40.046627Z","end":"2026-08-16T13:03:40.163544Z","steps":["trace[2032062535] 'process raft request' (duration: 116.624244ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:50.688283Z","caller":"traceutil/trace.go:171","msg":"trace[1533256622] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"129.722344ms","start":"2026-08-16T13:03:50.558512Z","end":"2026-08-16T13:03:50.688234Z","steps":["trace[1533256622] 'process raft request' (duration: 67.014958ms)","trace[1533256622] 'compare' (duration: 62.301195ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:03:50.688517Z","caller":"traceutil/trace.go:171","msg":"trace[1333986249] linearizableReadLoop","detail":"{readStateIndex:5721; appliedIndex:5718; }","duration":"125.525436ms","start":"2026-08-16T13:03:50.562958Z","end":"2026-08-16T13:03:50.688484Z","steps":["trace[1333986249] 'read index received' (duration: 62.654555ms)","trace[1333986249] 'applied index is now lower than readState.Index' (duration: 62.82862ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:03:50.688325Z","caller":"traceutil/trace.go:171","msg":"trace[930104229] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"129.567019ms","start":"2026-08-16T13:03:50.558723Z","end":"2026-08-16T13:03:50.68829Z","steps":["trace[930104229] 'process raft request' (duration: 129.382204ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:03:50.68864Z","caller":"traceutil/trace.go:171","msg":"trace[1939874444] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"129.082606ms","start":"2026-08-16T13:03:50.559512Z","end":"2026-08-16T13:03:50.688595Z","steps":["trace[1939874444] 'process raft request' (duration: 128.687225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:03:50.689553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.555925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qcxpx\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-16T13:03:50.689666Z","caller":"traceutil/trace.go:171","msg":"trace[911597883] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qcxpx; range_end:; response_count:1; response_revision:5516; }","duration":"126.72965ms","start":"2026-08-16T13:03:50.562911Z","end":"2026-08-16T13:03:50.689641Z","steps":["trace[911597883] 'agreement among raft nodes before linearized reading' (duration: 125.68194ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:05:06.094472Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-08-16T13:05:06.160908Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"64.508221ms","hash":297146006,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":34226176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-16T13:05:06.160993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":297146006,"revision":2986,"compact-revision":-1} {"level":"info","ts":"2026-08-16T13:09:48.327604Z","caller":"traceutil/trace.go:171","msg":"trace[1897734916] transaction","detail":"{read_only:false; response_revision:8201; number_of_response:1; }","duration":"271.776988ms","start":"2026-08-16T13:09:48.055788Z","end":"2026-08-16T13:09:48.327565Z","steps":["trace[1897734916] 'process raft request' (duration: 271.532771ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:09:48.436349Z","caller":"traceutil/trace.go:171","msg":"trace[358878747] transaction","detail":"{read_only:false; response_revision:8202; number_of_response:1; }","duration":"378.020715ms","start":"2026-08-16T13:09:48.058294Z","end":"2026-08-16T13:09:48.436315Z","steps":["trace[358878747] 'process raft request' (duration: 342.562714ms)","trace[358878747] 'compare' (duration: 35.258185ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:09:48.436524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:09:48.05828Z","time spent":"378.160588ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:09:48.436562Z","caller":"traceutil/trace.go:171","msg":"trace[401004120] linearizableReadLoop","detail":"{readStateIndex:8492; appliedIndex:8490; }","duration":"233.332123ms","start":"2026-08-16T13:09:48.2032Z","end":"2026-08-16T13:09:48.436532Z","steps":["trace[401004120] 'read index received' (duration: 124.101641ms)","trace[401004120] 'applied index is now lower than readState.Index' (duration: 109.228002ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:09:48.436711Z","caller":"traceutil/trace.go:171","msg":"trace[421425035] transaction","detail":"{read_only:false; response_revision:8203; number_of_response:1; }","duration":"129.163024ms","start":"2026-08-16T13:09:48.307522Z","end":"2026-08-16T13:09:48.436685Z","steps":["trace[421425035] 'process raft request' (duration: 128.733292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:09:48.436821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.61278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-16T13:09:48.436872Z","caller":"traceutil/trace.go:171","msg":"trace[776371023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8203; }","duration":"233.715414ms","start":"2026-08-16T13:09:48.203136Z","end":"2026-08-16T13:09:48.436851Z","steps":["trace[776371023] 'agreement among raft nodes before linearized reading' (duration: 233.507728ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:10:06.100468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6130} {"level":"info","ts":"2026-08-16T13:10:06.202626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6130,"took":"100.046223ms","hash":3430166959,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":23363584,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-16T13:10:06.202732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3430166959,"revision":6130,"compact-revision":2986} {"level":"info","ts":"2026-08-16T13:10:29.453912Z","caller":"traceutil/trace.go:171","msg":"trace[450452884] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"292.475411ms","start":"2026-08-16T13:10:29.161393Z","end":"2026-08-16T13:10:29.453869Z","steps":["trace[450452884] 'process raft request' (duration: 292.232114ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:10:29.472094Z","caller":"traceutil/trace.go:171","msg":"trace[801039479] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"284.401573ms","start":"2026-08-16T13:10:29.187664Z","end":"2026-08-16T13:10:29.472065Z","steps":["trace[801039479] 'process raft request' (duration: 284.353862ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:10:29.472155Z","caller":"traceutil/trace.go:171","msg":"trace[553031074] transaction","detail":"{read_only:false; response_revision:8481; number_of_response:1; }","duration":"291.536254ms","start":"2026-08-16T13:10:29.180574Z","end":"2026-08-16T13:10:29.47211Z","steps":["trace[553031074] 'process raft request' (duration: 291.290728ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:10:42.845161Z","caller":"traceutil/trace.go:171","msg":"trace[446307757] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8898; }","duration":"167.909967ms","start":"2026-08-16T13:10:42.677215Z","end":"2026-08-16T13:10:42.845125Z","steps":["trace[446307757] 'read index received' (duration: 167.67216ms)","trace[446307757] 'applied index is now lower than readState.Index' (duration: 236.317µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:10:42.845599Z","caller":"traceutil/trace.go:171","msg":"trace[1462820069] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"185.144673ms","start":"2026-08-16T13:10:42.660425Z","end":"2026-08-16T13:10:42.845569Z","steps":["trace[1462820069] 'process raft request' (duration: 184.484385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:10:42.845977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.74179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-16T13:10:42.846029Z","caller":"traceutil/trace.go:171","msg":"trace[1651566765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8590; }","duration":"168.804003ms","start":"2026-08-16T13:10:42.677208Z","end":"2026-08-16T13:10:42.846012Z","steps":["trace[1651566765] 'agreement among raft nodes before linearized reading' (duration: 168.589566ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:10:42.930907Z","caller":"traceutil/trace.go:171","msg":"trace[1674832469] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"139.925558ms","start":"2026-08-16T13:10:42.790945Z","end":"2026-08-16T13:10:42.930871Z","steps":["trace[1674832469] 'process raft request' (duration: 122.848056ms)","trace[1674832469] 'compare' (duration: 16.871096ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:10:42.931087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.408855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-16T13:10:42.931129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.562579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:10:42.931173Z","caller":"traceutil/trace.go:171","msg":"trace[1218882227] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8591; }","duration":"244.651442ms","start":"2026-08-16T13:10:42.686507Z","end":"2026-08-16T13:10:42.931159Z","steps":["trace[1218882227] 'agreement among raft nodes before linearized reading' (duration: 244.358984ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:10:42.931179Z","caller":"traceutil/trace.go:171","msg":"trace[430505954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8591; }","duration":"177.561389ms","start":"2026-08-16T13:10:42.753596Z","end":"2026-08-16T13:10:42.931157Z","steps":["trace[430505954] 'agreement among raft nodes before linearized reading' (duration: 177.290541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:10:42.931294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.516896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-16T13:10:42.931354Z","caller":"traceutil/trace.go:171","msg":"trace[460880061] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8591; }","duration":"223.610309ms","start":"2026-08-16T13:10:42.707725Z","end":"2026-08-16T13:10:42.931336Z","steps":["trace[460880061] 'agreement among raft nodes before linearized reading' (duration: 223.175537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:10:42.931398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.869309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-16T13:10:42.931438Z","caller":"traceutil/trace.go:171","msg":"trace[963746529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8592; }","duration":"126.945871ms","start":"2026-08-16T13:10:42.80448Z","end":"2026-08-16T13:10:42.931426Z","steps":["trace[963746529] 'agreement among raft nodes before linearized reading' (duration: 126.830638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:10:42.931089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.347456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-16T13:10:42.931528Z","caller":"traceutil/trace.go:171","msg":"trace[1840683012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8591; }","duration":"187.827849ms","start":"2026-08-16T13:10:42.743689Z","end":"2026-08-16T13:10:42.931517Z","steps":["trace[1840683012] 'agreement among raft nodes before linearized reading' (duration: 187.200811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:10:43.899991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.255406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:10:43.900098Z","caller":"traceutil/trace.go:171","msg":"trace[1220488862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8602; }","duration":"107.413011ms","start":"2026-08-16T13:10:43.792654Z","end":"2026-08-16T13:10:43.900067Z","steps":["trace[1220488862] 'range keys from in-memory index tree' (duration: 107.140454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:10:43.900246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.67404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111947"} {"level":"info","ts":"2026-08-16T13:10:43.900319Z","caller":"traceutil/trace.go:171","msg":"trace[712984664] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8602; }","duration":"173.784953ms","start":"2026-08-16T13:10:43.726519Z","end":"2026-08-16T13:10:43.900304Z","steps":["trace[712984664] 'range keys from in-memory index tree' (duration: 173.420732ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:10:59.897958Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000237c.wal"} {"level":"info","ts":"2026-08-16T13:12:43.066033Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:12:43.071374Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-16T13:12:43.071448Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-16T13:15:06.106988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8304} {"level":"info","ts":"2026-08-16T13:15:06.189356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8304,"took":"80.010929ms","hash":1397313151,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":24006656,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-16T13:15:06.189434Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1397313151,"revision":8304,"compact-revision":6130} {"level":"info","ts":"2026-08-16T13:20:06.113477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10519} {"level":"info","ts":"2026-08-16T13:20:06.188184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10519,"took":"71.732472ms","hash":2581382754,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":26157056,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-16T13:20:06.188248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2581382754,"revision":10519,"compact-revision":8304} {"level":"info","ts":"2026-08-16T13:24:11.382589Z","caller":"traceutil/trace.go:171","msg":"trace[1576300376] linearizableReadLoop","detail":"{readStateIndex:14896; appliedIndex:14895; }","duration":"162.387179ms","start":"2026-08-16T13:24:11.220169Z","end":"2026-08-16T13:24:11.382557Z","steps":["trace[1576300376] 'read index received' (duration: 162.189774ms)","trace[1576300376] 'applied index is now lower than readState.Index' (duration: 196.405µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:24:11.382715Z","caller":"traceutil/trace.go:171","msg":"trace[7688476] transaction","detail":"{read_only:false; response_revision:14394; number_of_response:1; }","duration":"181.361294ms","start":"2026-08-16T13:24:11.201312Z","end":"2026-08-16T13:24:11.382673Z","steps":["trace[7688476] 'process raft request' (duration: 181.054835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:24:11.382886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.698769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-16T13:24:11.38298Z","caller":"traceutil/trace.go:171","msg":"trace[604983588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14394; }","duration":"162.868404ms","start":"2026-08-16T13:24:11.22009Z","end":"2026-08-16T13:24:11.382959Z","steps":["trace[604983588] 'agreement among raft nodes before linearized reading' (duration: 162.586666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:24:14.96615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.216563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:24:14.966324Z","caller":"traceutil/trace.go:171","msg":"trace[847985391] transaction","detail":"{read_only:false; response_revision:14430; number_of_response:1; }","duration":"201.625056ms","start":"2026-08-16T13:24:14.764688Z","end":"2026-08-16T13:24:14.966313Z","steps":["trace[847985391] 'process raft request' (duration: 201.555204ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:24:14.96641Z","caller":"traceutil/trace.go:171","msg":"trace[1467675389] transaction","detail":"{read_only:false; response_revision:14429; number_of_response:1; }","duration":"217.490933ms","start":"2026-08-16T13:24:14.748912Z","end":"2026-08-16T13:24:14.966402Z","steps":["trace[1467675389] 'process raft request' (duration: 25.948091ms)","trace[1467675389] 'compare' (duration: 190.884644ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:24:15.045406Z","caller":"traceutil/trace.go:171","msg":"trace[979796089] transaction","detail":"{read_only:false; response_revision:14431; number_of_response:1; }","duration":"158.50022ms","start":"2026-08-16T13:24:14.886872Z","end":"2026-08-16T13:24:15.045373Z","steps":["trace[979796089] 'process raft request' (duration: 158.340796ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:24:25.617031Z","caller":"traceutil/trace.go:171","msg":"trace[507127973] linearizableReadLoop","detail":"{readStateIndex:15109; appliedIndex:15108; }","duration":"105.75488ms","start":"2026-08-16T13:24:25.511258Z","end":"2026-08-16T13:24:25.617013Z","steps":["trace[507127973] 'read index received' (duration: 105.650977ms)","trace[507127973] 'applied index is now lower than readState.Index' (duration: 102.993µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:24:25.617146Z","caller":"traceutil/trace.go:171","msg":"trace[399285436] transaction","detail":"{read_only:false; response_revision:14602; number_of_response:1; }","duration":"130.048885ms","start":"2026-08-16T13:24:25.487059Z","end":"2026-08-16T13:24:25.617108Z","steps":["trace[399285436] 'process raft request' (duration: 129.836209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:24:25.617235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.965517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-16T13:24:25.617326Z","caller":"traceutil/trace.go:171","msg":"trace[1032447435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14602; }","duration":"106.106431ms","start":"2026-08-16T13:24:25.511205Z","end":"2026-08-16T13:24:25.617312Z","steps":["trace[1032447435] 'agreement among raft nodes before linearized reading' (duration: 105.909375ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:25:06.121001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12779} {"level":"info","ts":"2026-08-16T13:25:06.207761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12779,"took":"81.040824ms","hash":872021422,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":29237248,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-16T13:25:06.207875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":872021422,"revision":12779,"compact-revision":10519} {"level":"warn","ts":"2026-08-16T13:26:07.914755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.229534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T13:26:07.914935Z","caller":"traceutil/trace.go:171","msg":"trace[1133305184] linearizableReadLoop","detail":"{readStateIndex:16246; appliedIndex:16245; }","duration":"290.545708ms","start":"2026-08-16T13:26:07.624356Z","end":"2026-08-16T13:26:07.914902Z","steps":["trace[1133305184] 'read index received' (duration: 31.088886ms)","trace[1133305184] 'applied index is now lower than readState.Index' (duration: 259.454841ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:26:07.915082Z","caller":"traceutil/trace.go:171","msg":"trace[1412785339] transaction","detail":"{read_only:false; response_revision:15703; number_of_response:1; }","duration":"358.544943ms","start":"2026-08-16T13:26:07.556518Z","end":"2026-08-16T13:26:07.915063Z","steps":["trace[1412785339] 'process raft request' (duration: 98.880676ms)","trace[1412785339] 'compare' (duration: 259.028358ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:26:07.91516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:26:07.556485Z","time spent":"358.633916ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:26:07.915468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.107303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:26:07.915515Z","caller":"traceutil/trace.go:171","msg":"trace[945651389] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:15703; }","duration":"291.155964ms","start":"2026-08-16T13:26:07.624346Z","end":"2026-08-16T13:26:07.915502Z","steps":["trace[945651389] 'agreement among raft nodes before linearized reading' (duration: 291.054791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:26:07.915635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.643656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T13:26:07.915774Z","caller":"traceutil/trace.go:171","msg":"trace[2113777338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15703; }","duration":"122.823851ms","start":"2026-08-16T13:26:07.792899Z","end":"2026-08-16T13:26:07.915723Z","steps":["trace[2113777338] 'agreement among raft nodes before linearized reading' (duration: 122.619006ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:30:06.130514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15061} {"level":"info","ts":"2026-08-16T13:30:06.225678Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15061,"took":"92.063437ms","hash":2845181871,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":31129600,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-16T13:30:06.225807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2845181871,"revision":15061,"compact-revision":12779} {"level":"info","ts":"2026-08-16T13:31:45.068088Z","caller":"traceutil/trace.go:171","msg":"trace[188452272] transaction","detail":"{read_only:false; response_revision:18272; number_of_response:1; }","duration":"131.714666ms","start":"2026-08-16T13:31:44.93634Z","end":"2026-08-16T13:31:45.068055Z","steps":["trace[188452272] 'process raft request' (duration: 106.970228ms)","trace[188452272] 'compare' (duration: 24.619445ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:31:49.539495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.213794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:31:49.539871Z","caller":"traceutil/trace.go:171","msg":"trace[2130979504] transaction","detail":"{read_only:false; response_revision:18312; number_of_response:1; }","duration":"291.649628ms","start":"2026-08-16T13:31:49.24819Z","end":"2026-08-16T13:31:49.53984Z","steps":["trace[2130979504] 'process raft request' (duration: 291.415622ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:31:49.539903Z","caller":"traceutil/trace.go:171","msg":"trace[2004478131] transaction","detail":"{read_only:false; response_revision:18311; number_of_response:1; }","duration":"378.657983ms","start":"2026-08-16T13:31:49.161197Z","end":"2026-08-16T13:31:49.539855Z","steps":["trace[2004478131] 'process raft request' (duration: 202.990887ms)","trace[2004478131] 'compare' (duration: 174.874884ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:31:49.54018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:31:49.16117Z","time spent":"378.89617ms","remote":"127.0.0.1:34410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:31:49.540244Z","caller":"traceutil/trace.go:171","msg":"trace[11541900] linearizableReadLoop","detail":"{readStateIndex:18939; appliedIndex:18937; }","duration":"207.030231ms","start":"2026-08-16T13:31:49.33264Z","end":"2026-08-16T13:31:49.539671Z","steps":["trace[11541900] 'read index received' (duration: 31.686214ms)","trace[11541900] 'applied index is now lower than readState.Index' (duration: 175.342667ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:31:49.540299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.612077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-tzgvj\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-08-16T13:31:49.540425Z","caller":"traceutil/trace.go:171","msg":"trace[485139116] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-tzgvj; range_end:; response_count:1; response_revision:18312; }","duration":"207.800392ms","start":"2026-08-16T13:31:49.332591Z","end":"2026-08-16T13:31:49.540391Z","steps":["trace[485139116] 'agreement among raft nodes before linearized reading' (duration: 207.429842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:49.540479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.596835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-n5hgx\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-08-16T13:31:49.540519Z","caller":"traceutil/trace.go:171","msg":"trace[232509623] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-n5hgx; range_end:; response_count:1; response_revision:18312; }","duration":"197.639086ms","start":"2026-08-16T13:31:49.34287Z","end":"2026-08-16T13:31:49.540509Z","steps":["trace[232509623] 'agreement among raft nodes before linearized reading' (duration: 197.475541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:49.540668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.710496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:31:49.540702Z","caller":"traceutil/trace.go:171","msg":"trace[1120414743] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:18312; }","duration":"131.764988ms","start":"2026-08-16T13:31:49.408927Z","end":"2026-08-16T13:31:49.540692Z","steps":["trace[1120414743] 'agreement among raft nodes before linearized reading' (duration: 131.711706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:31:49.540845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.666981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-28rcs\" ","response":"range_response_count:1 size:20756"} {"level":"info","ts":"2026-08-16T13:31:49.540939Z","caller":"traceutil/trace.go:171","msg":"trace[495458082] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-28rcs; range_end:; response_count:1; response_revision:18312; }","duration":"133.807355ms","start":"2026-08-16T13:31:49.407109Z","end":"2026-08-16T13:31:49.540916Z","steps":["trace[495458082] 'agreement among raft nodes before linearized reading' (duration: 133.598149ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:31:49.727843Z","caller":"traceutil/trace.go:171","msg":"trace[1020956567] transaction","detail":"{read_only:false; response_revision:18313; number_of_response:1; }","duration":"170.513671ms","start":"2026-08-16T13:31:49.557294Z","end":"2026-08-16T13:31:49.727807Z","steps":["trace[1020956567] 'process raft request' (duration: 170.316326ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:33:40.881573Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:33:40.887382Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-16T13:33:40.887558Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-16T13:35:06.139678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17517} {"level":"info","ts":"2026-08-16T13:35:06.237907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17517,"took":"94.379603ms","hash":2864338231,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":34471936,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-16T13:35:06.237968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2864338231,"revision":17517,"compact-revision":15061} {"level":"info","ts":"2026-08-16T13:37:29.169427Z","caller":"traceutil/trace.go:171","msg":"trace[570388896] transaction","detail":"{read_only:false; response_revision:20885; number_of_response:1; }","duration":"102.691259ms","start":"2026-08-16T13:37:29.06671Z","end":"2026-08-16T13:37:29.169401Z","steps":["trace[570388896] 'process raft request' (duration: 102.489924ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:37:29.56702Z","caller":"traceutil/trace.go:171","msg":"trace[1497724975] transaction","detail":"{read_only:false; response_revision:20887; number_of_response:1; }","duration":"214.470012ms","start":"2026-08-16T13:37:29.352499Z","end":"2026-08-16T13:37:29.566969Z","steps":["trace[1497724975] 'process raft request' (duration: 144.903805ms)","trace[1497724975] 'compare' (duration: 69.132155ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:37:29.817889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.417426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:37:29.818121Z","caller":"traceutil/trace.go:171","msg":"trace[875263489] transaction","detail":"{read_only:false; response_revision:20888; number_of_response:1; }","duration":"183.626976ms","start":"2026-08-16T13:37:29.634462Z","end":"2026-08-16T13:37:29.818089Z","steps":["trace[875263489] 'process raft request' (duration: 77.860121ms)","trace[875263489] 'compare' (duration: 105.208599ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:37:30.142035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.262557ms","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-08-16T13:37:30.142101Z","caller":"traceutil/trace.go:171","msg":"trace[2096538435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20888; }","duration":"107.37446ms","start":"2026-08-16T13:37:30.034712Z","end":"2026-08-16T13:37:30.142086Z","steps":["trace[2096538435] 'range keys from in-memory index tree' (duration: 107.056372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:37:30.142168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.369517ms","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-08-16T13:37:30.142243Z","caller":"traceutil/trace.go:171","msg":"trace[2083876907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20888; }","duration":"114.461779ms","start":"2026-08-16T13:37:30.027763Z","end":"2026-08-16T13:37:30.142225Z","steps":["trace[2083876907] 'range keys from in-memory index tree' (duration: 114.190841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:37:30.142286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.997909ms","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-08-16T13:37:30.142312Z","caller":"traceutil/trace.go:171","msg":"trace[959242266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20888; }","duration":"113.02582ms","start":"2026-08-16T13:37:30.02928Z","end":"2026-08-16T13:37:30.142306Z","steps":["trace[959242266] 'range keys from in-memory index tree' (duration: 112.873625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:37:30.142383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.543546ms","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-08-16T13:37:30.142358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.73347ms","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-08-16T13:37:30.1424Z","caller":"traceutil/trace.go:171","msg":"trace[145013407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20888; }","duration":"171.603787ms","start":"2026-08-16T13:37:29.970792Z","end":"2026-08-16T13:37:30.142396Z","steps":["trace[145013407] 'range keys from in-memory index tree' (duration: 171.379981ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:37:30.142418Z","caller":"traceutil/trace.go:171","msg":"trace[249834303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20888; }","duration":"107.843943ms","start":"2026-08-16T13:37:30.034559Z","end":"2026-08-16T13:37:30.142403Z","steps":["trace[249834303] 'range keys from in-memory index tree' (duration: 107.579056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:37:30.448015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.941676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:37:30.448154Z","caller":"traceutil/trace.go:171","msg":"trace[1875134103] transaction","detail":"{read_only:false; response_revision:20890; number_of_response:1; }","duration":"302.772807ms","start":"2026-08-16T13:37:30.145355Z","end":"2026-08-16T13:37:30.448128Z","steps":["trace[1875134103] 'process raft request' (duration: 135.609974ms)","trace[1875134103] 'compare' (duration: 166.628097ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:37:30.448218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:37:30.145336Z","time spent":"302.851618ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:37:30.522972Z","caller":"traceutil/trace.go:171","msg":"trace[1008291585] transaction","detail":"{read_only:false; response_revision:20892; number_of_response:1; }","duration":"377.351584ms","start":"2026-08-16T13:37:30.145606Z","end":"2026-08-16T13:37:30.522958Z","steps":["trace[1008291585] 'process raft request' (duration: 377.081447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:37:30.523072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:37:30.145598Z","time spent":"377.435856ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:37:30.523052Z","caller":"traceutil/trace.go:171","msg":"trace[120439418] transaction","detail":"{read_only:false; response_revision:20891; number_of_response:1; }","duration":"377.580191ms","start":"2026-08-16T13:37:30.145428Z","end":"2026-08-16T13:37:30.523008Z","steps":["trace[120439418] 'process raft request' (duration: 377.032645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:37:30.52336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:37:30.145411Z","time spent":"377.836507ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:37:30.523512Z","caller":"traceutil/trace.go:171","msg":"trace[1040191887] transaction","detail":"{read_only:false; response_revision:20894; number_of_response:1; }","duration":"377.800557ms","start":"2026-08-16T13:37:30.145692Z","end":"2026-08-16T13:37:30.523493Z","steps":["trace[1040191887] 'process raft request' (duration: 377.727934ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:37:30.523564Z","caller":"traceutil/trace.go:171","msg":"trace[1217614277] transaction","detail":"{read_only:false; response_revision:20893; number_of_response:1; }","duration":"377.918681ms","start":"2026-08-16T13:37:30.145632Z","end":"2026-08-16T13:37:30.523551Z","steps":["trace[1217614277] 'process raft request' (duration: 377.276442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:37:30.523617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:37:30.145674Z","time spent":"377.89587ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:37:30.523647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:37:30.145624Z","time spent":"377.993172ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:37:30.531273Z","caller":"traceutil/trace.go:171","msg":"trace[1936334210] transaction","detail":"{read_only:false; response_revision:20895; number_of_response:1; }","duration":"166.612146ms","start":"2026-08-16T13:37:30.364628Z","end":"2026-08-16T13:37:30.53124Z","steps":["trace[1936334210] 'process raft request' (duration: 166.468882ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:40:06.146112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19868} {"level":"info","ts":"2026-08-16T13:40:06.259601Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19868,"took":"109.935602ms","hash":2985147832,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":30310400,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-16T13:40:06.259754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2985147832,"revision":19868,"compact-revision":17517} {"level":"info","ts":"2026-08-16T13:41:33.794993Z","caller":"traceutil/trace.go:171","msg":"trace[1105328658] transaction","detail":"{read_only:false; response_revision:23398; number_of_response:1; }","duration":"136.829669ms","start":"2026-08-16T13:41:33.658121Z","end":"2026-08-16T13:41:33.794951Z","steps":["trace[1105328658] 'process raft request' (duration: 113.345039ms)","trace[1105328658] 'compare' (duration: 23.035798ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:41:33.794996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.885613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-16T13:41:33.795288Z","caller":"traceutil/trace.go:171","msg":"trace[726475231] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:23397; }","duration":"157.289084ms","start":"2026-08-16T13:41:33.637985Z","end":"2026-08-16T13:41:33.795274Z","steps":["trace[726475231] 'count revisions from in-memory index tree' (duration: 156.753029ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:01.018301Z","caller":"traceutil/trace.go:171","msg":"trace[1232276545] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"146.609395ms","start":"2026-08-16T13:45:00.87167Z","end":"2026-08-16T13:45:01.018279Z","steps":["trace[1232276545] 'process raft request' (duration: 146.464381ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:03.908401Z","caller":"traceutil/trace.go:171","msg":"trace[873731272] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"142.453406ms","start":"2026-08-16T13:45:03.765912Z","end":"2026-08-16T13:45:03.908366Z","steps":["trace[873731272] 'process raft request' (duration: 142.24441ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:03.909289Z","caller":"traceutil/trace.go:171","msg":"trace[437741284] linearizableReadLoop","detail":"{readStateIndex:26346; appliedIndex:26346; }","duration":"117.870546ms","start":"2026-08-16T13:45:03.791397Z","end":"2026-08-16T13:45:03.909267Z","steps":["trace[437741284] 'read index received' (duration: 117.863006ms)","trace[437741284] 'applied index is now lower than readState.Index' (duration: 6.06µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:03.909588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.159873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:03.909627Z","caller":"traceutil/trace.go:171","msg":"trace[1950513419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25497; }","duration":"118.272176ms","start":"2026-08-16T13:45:03.791343Z","end":"2026-08-16T13:45:03.909615Z","steps":["trace[1950513419] 'agreement among raft nodes before linearized reading' (duration: 118.156673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.323131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.618979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:45:04.32357Z","caller":"traceutil/trace.go:171","msg":"trace[1520260571] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"415.774966ms","start":"2026-08-16T13:45:03.907755Z","end":"2026-08-16T13:45:04.32353Z","steps":["trace[1520260571] 'process raft request' (duration: 126.610471ms)","trace[1520260571] 'compare' (duration: 288.357202ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:04.323792Z","caller":"traceutil/trace.go:171","msg":"trace[257308533] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"409.839879ms","start":"2026-08-16T13:45:03.913916Z","end":"2026-08-16T13:45:04.323756Z","steps":["trace[257308533] 'process raft request' (duration: 409.646504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.323849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:03.907721Z","time spent":"415.986692ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:04.323931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:03.913899Z","time spent":"409.965693ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:04.324139Z","caller":"traceutil/trace.go:171","msg":"trace[1193264207] linearizableReadLoop","detail":"{readStateIndex:26347; appliedIndex:26346; }","duration":"413.247795ms","start":"2026-08-16T13:45:03.910875Z","end":"2026-08-16T13:45:04.324123Z","steps":["trace[1193264207] 'read index received' (duration: 123.67453ms)","trace[1193264207] 'applied index is now lower than readState.Index' (duration: 289.571785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:04.324388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.573835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:04.324436Z","caller":"traceutil/trace.go:171","msg":"trace[1801053928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25499; }","duration":"413.654016ms","start":"2026-08-16T13:45:03.910769Z","end":"2026-08-16T13:45:04.324423Z","steps":["trace[1801053928] 'agreement among raft nodes before linearized reading' (duration: 413.571313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.324484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:03.910747Z","time spent":"413.731608ms","remote":"127.0.0.1:34374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T13:45:04.625449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.55433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:45:04.625907Z","caller":"traceutil/trace.go:171","msg":"trace[1365677] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"329.359025ms","start":"2026-08-16T13:45:04.296527Z","end":"2026-08-16T13:45:04.625886Z","steps":["trace[1365677] 'process raft request' (duration: 149.250208ms)","trace[1365677] 'compare' (duration: 179.298073ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:04.62597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.296507Z","time spent":"329.432927ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:04.634179Z","caller":"traceutil/trace.go:171","msg":"trace[1949841689] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"328.960034ms","start":"2026-08-16T13:45:04.305196Z","end":"2026-08-16T13:45:04.634156Z","steps":["trace[1949841689] 'process raft request' (duration: 328.783198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.634343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.305176Z","time spent":"329.071057ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:04.634344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.010195ms","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":"info","ts":"2026-08-16T13:45:04.63443Z","caller":"traceutil/trace.go:171","msg":"trace[1661317542] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25501; }","duration":"548.15547ms","start":"2026-08-16T13:45:04.086257Z","end":"2026-08-16T13:45:04.634413Z","steps":["trace[1661317542] 'agreement among raft nodes before linearized reading' (duration: 547.932744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.634479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.086233Z","time spent":"548.235611ms","remote":"127.0.0.1:40072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-16T13:45:04.634656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.481263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:04.634713Z","caller":"traceutil/trace.go:171","msg":"trace[1787227208] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25501; }","duration":"556.546905ms","start":"2026-08-16T13:45:04.07814Z","end":"2026-08-16T13:45:04.634687Z","steps":["trace[1787227208] 'agreement among raft nodes before linearized reading' (duration: 556.479104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.634745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.078122Z","time spent":"556.618348ms","remote":"127.0.0.1:34580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-08-16T13:45:04.634887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.237006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1021"} {"level":"info","ts":"2026-08-16T13:45:04.634892Z","caller":"traceutil/trace.go:171","msg":"trace[1046924478] linearizableReadLoop","detail":"{readStateIndex:26350; appliedIndex:26348; }","duration":"309.931468ms","start":"2026-08-16T13:45:04.324176Z","end":"2026-08-16T13:45:04.634108Z","steps":["trace[1046924478] 'read index received' (duration: 121.548588ms)","trace[1046924478] 'applied index is now lower than readState.Index' (duration: 188.38219ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:04.635019Z","caller":"traceutil/trace.go:171","msg":"trace[664851543] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:25501; }","duration":"711.410991ms","start":"2026-08-16T13:45:03.923591Z","end":"2026-08-16T13:45:04.635002Z","steps":["trace[664851543] 'agreement among raft nodes before linearized reading' (duration: 711.221976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.635062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:03.923571Z","time spent":"711.479214ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1044,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" "} {"level":"warn","ts":"2026-08-16T13:45:04.910441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.134881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:45:04.910634Z","caller":"traceutil/trace.go:171","msg":"trace[1560479360] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"435.595442ms","start":"2026-08-16T13:45:04.474999Z","end":"2026-08-16T13:45:04.910595Z","steps":["trace[1560479360] 'process raft request' (duration: 295.218554ms)","trace[1560479360] 'compare' (duration: 139.214226ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:04.911047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.474968Z","time spent":"435.985973ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:04.949363Z","caller":"traceutil/trace.go:171","msg":"trace[541693555] linearizableReadLoop","detail":"{readStateIndex:26352; appliedIndex:26350; }","duration":"314.294801ms","start":"2026-08-16T13:45:04.63504Z","end":"2026-08-16T13:45:04.949335Z","steps":["trace[541693555] 'read index received' (duration: 135.099021ms)","trace[541693555] 'applied index is now lower than readState.Index' (duration: 179.1729ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:04.949558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.12646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:04.949617Z","caller":"traceutil/trace.go:171","msg":"trace[248394895] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25502; }","duration":"585.251443ms","start":"2026-08-16T13:45:04.364352Z","end":"2026-08-16T13:45:04.949603Z","steps":["trace[248394895] 'agreement among raft nodes before linearized reading' (duration: 585.13606ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:04.949647Z","caller":"traceutil/trace.go:171","msg":"trace[1186166116] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"171.862114ms","start":"2026-08-16T13:45:04.777762Z","end":"2026-08-16T13:45:04.949624Z","steps":["trace[1186166116] 'process raft request' (duration: 171.443693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.949732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.801024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:04.94977Z","caller":"traceutil/trace.go:171","msg":"trace[946870864] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25503; }","duration":"619.837775ms","start":"2026-08-16T13:45:04.329923Z","end":"2026-08-16T13:45:04.949761Z","steps":["trace[946870864] 'agreement among raft nodes before linearized reading' (duration: 619.766663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.949662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.364329Z","time spent":"585.322135ms","remote":"127.0.0.1:39924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-08-16T13:45:04.949798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.329875Z","time spent":"619.915668ms","remote":"127.0.0.1:33338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true "} {"level":"warn","ts":"2026-08-16T13:45:04.950233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.466597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:04.950288Z","caller":"traceutil/trace.go:171","msg":"trace[1667716637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25503; }","duration":"158.563229ms","start":"2026-08-16T13:45:04.791711Z","end":"2026-08-16T13:45:04.950274Z","steps":["trace[1667716637] 'agreement among raft nodes before linearized reading' (duration: 158.476487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.950308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.361002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-16T13:45:04.950301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.972832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-wwv2q\" ","response":"range_response_count:1 size:7125"} {"level":"info","ts":"2026-08-16T13:45:04.95036Z","caller":"traceutil/trace.go:171","msg":"trace[1376906087] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25503; }","duration":"309.427333ms","start":"2026-08-16T13:45:04.640923Z","end":"2026-08-16T13:45:04.95035Z","steps":["trace[1376906087] 'agreement among raft nodes before linearized reading' (duration: 309.349871ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:04.950374Z","caller":"traceutil/trace.go:171","msg":"trace[266514151] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-wwv2q; range_end:; response_count:1; response_revision:25503; }","duration":"314.074975ms","start":"2026-08-16T13:45:04.636284Z","end":"2026-08-16T13:45:04.950359Z","steps":["trace[266514151] 'agreement among raft nodes before linearized reading' (duration: 313.855529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:04.950401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.640915Z","time spent":"309.477836ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-08-16T13:45:04.950431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:04.636261Z","time spent":"314.161738ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":7148,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-wwv2q\" "} {"level":"info","ts":"2026-08-16T13:45:05.194794Z","caller":"traceutil/trace.go:171","msg":"trace[915623234] linearizableReadLoop","detail":"{readStateIndex:26354; appliedIndex:26353; }","duration":"161.200645ms","start":"2026-08-16T13:45:05.033557Z","end":"2026-08-16T13:45:05.194758Z","steps":["trace[915623234] 'read index received' (duration: 158.761846ms)","trace[915623234] 'applied index is now lower than readState.Index' (duration: 2.437339ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:05.195031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.455992ms","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-08-16T13:45:05.19497Z","caller":"traceutil/trace.go:171","msg":"trace[351921279] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"163.505339ms","start":"2026-08-16T13:45:05.031414Z","end":"2026-08-16T13:45:05.194919Z","steps":["trace[351921279] 'process raft request' (duration: 161.215615ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:05.1951Z","caller":"traceutil/trace.go:171","msg":"trace[856608271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25504; }","duration":"161.561165ms","start":"2026-08-16T13:45:05.033527Z","end":"2026-08-16T13:45:05.195088Z","steps":["trace[856608271] 'agreement among raft nodes before linearized reading' (duration: 161.37759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:05.195235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.014075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-16T13:45:05.195392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.032364ms","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-08-16T13:45:05.195466Z","caller":"traceutil/trace.go:171","msg":"trace[1115027263] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25504; }","duration":"148.097726ms","start":"2026-08-16T13:45:05.047336Z","end":"2026-08-16T13:45:05.195433Z","steps":["trace[1115027263] 'agreement among raft nodes before linearized reading' (duration: 147.968502ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:05.195454Z","caller":"traceutil/trace.go:171","msg":"trace[990668972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25504; }","duration":"153.279972ms","start":"2026-08-16T13:45:05.042136Z","end":"2026-08-16T13:45:05.195416Z","steps":["trace[990668972] 'agreement among raft nodes before linearized reading' (duration: 152.820609ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:06.011354Z","caller":"traceutil/trace.go:171","msg":"trace[2147311060] linearizableReadLoop","detail":"{readStateIndex:26360; appliedIndex:26359; }","duration":"238.391756ms","start":"2026-08-16T13:45:05.772936Z","end":"2026-08-16T13:45:06.011328Z","steps":["trace[2147311060] 'read index received' (duration: 238.271233ms)","trace[2147311060] 'applied index is now lower than readState.Index' (duration: 119.503µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:06.011469Z","caller":"traceutil/trace.go:171","msg":"trace[245884838] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"262.261847ms","start":"2026-08-16T13:45:05.749158Z","end":"2026-08-16T13:45:06.01142Z","steps":["trace[245884838] 'process raft request' (duration: 262.02095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.011596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.606232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:06.011671Z","caller":"traceutil/trace.go:171","msg":"trace[2042774677] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25510; }","duration":"238.781456ms","start":"2026-08-16T13:45:05.772876Z","end":"2026-08-16T13:45:06.011657Z","steps":["trace[2042774677] 'agreement among raft nodes before linearized reading' (duration: 238.614002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.262846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.112739ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:06.262973Z","caller":"traceutil/trace.go:171","msg":"trace[1326904071] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25510; }","duration":"195.263393ms","start":"2026-08-16T13:45:06.06769Z","end":"2026-08-16T13:45:06.262953Z","steps":["trace[1326904071] 'range keys from in-memory index tree' (duration: 195.086158ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:06.263841Z","caller":"traceutil/trace.go:171","msg":"trace[893242632] linearizableReadLoop","detail":"{readStateIndex:26361; appliedIndex:26360; }","duration":"252.353269ms","start":"2026-08-16T13:45:06.01144Z","end":"2026-08-16T13:45:06.263793Z","steps":["trace[893242632] 'read index received' (duration: 232.886181ms)","trace[893242632] 'applied index is now lower than readState.Index' (duration: 19.464498ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:06.263941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.076721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:06.263978Z","caller":"traceutil/trace.go:171","msg":"trace[913768820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25511; }","duration":"471.148093ms","start":"2026-08-16T13:45:05.792819Z","end":"2026-08-16T13:45:06.263967Z","steps":["trace[913768820] 'agreement among raft nodes before linearized reading' (duration: 471.075921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.264007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:05.792799Z","time spent":"471.203235ms","remote":"127.0.0.1:34380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T13:45:06.263862Z","caller":"traceutil/trace.go:171","msg":"trace[2017044953] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"350.495749ms","start":"2026-08-16T13:45:05.913342Z","end":"2026-08-16T13:45:06.263837Z","steps":["trace[2017044953] 'process raft request' (duration: 330.922289ms)","trace[2017044953] 'compare' (duration: 18.732757ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:06.264421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:05.913312Z","time spent":"351.029284ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:06.608191Z","caller":"traceutil/trace.go:171","msg":"trace[2144195721] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"589.689977ms","start":"2026-08-16T13:45:06.018454Z","end":"2026-08-16T13:45:06.608144Z","steps":["trace[2144195721] 'process raft request' (duration: 547.392998ms)","trace[2144195721] 'compare' (duration: 41.986831ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:06.608375Z","caller":"traceutil/trace.go:171","msg":"trace[816923869] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"583.144634ms","start":"2026-08-16T13:45:06.025192Z","end":"2026-08-16T13:45:06.608336Z","steps":["trace[816923869] 'process raft request' (duration: 582.931488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.608431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.018428Z","time spent":"589.884163ms","remote":"127.0.0.1:34550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":652,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:06.608493Z","caller":"traceutil/trace.go:171","msg":"trace[2066754793] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"460.36786ms","start":"2026-08-16T13:45:06.148117Z","end":"2026-08-16T13:45:06.608485Z","steps":["trace[2066754793] 'process raft request' (duration: 460.075162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.60859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.025162Z","time spent":"583.324188ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:06.608643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.148095Z","time spent":"460.449413ms","remote":"127.0.0.1:34390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:06.608403Z","caller":"traceutil/trace.go:171","msg":"trace[518847671] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"276.535168ms","start":"2026-08-16T13:45:06.331827Z","end":"2026-08-16T13:45:06.608362Z","steps":["trace[518847671] 'process raft request' (duration: 276.475387ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:06.608968Z","caller":"traceutil/trace.go:171","msg":"trace[2094962897] linearizableReadLoop","detail":"{readStateIndex:26365; appliedIndex:26361; }","duration":"345.009955ms","start":"2026-08-16T13:45:06.263943Z","end":"2026-08-16T13:45:06.608953Z","steps":["trace[2094962897] 'read index received' (duration: 301.919453ms)","trace[2094962897] 'applied index is now lower than readState.Index' (duration: 43.088092ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:06.609082Z","caller":"traceutil/trace.go:171","msg":"trace[1217434476] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"588.136884ms","start":"2026-08-16T13:45:06.020925Z","end":"2026-08-16T13:45:06.609062Z","steps":["trace[1217434476] 'process raft request' (duration: 587.114415ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:06.609139Z","caller":"traceutil/trace.go:171","msg":"trace[245265185] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"278.367561ms","start":"2026-08-16T13:45:06.330751Z","end":"2026-08-16T13:45:06.609118Z","steps":["trace[245265185] 'process raft request' (duration: 277.492906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.609179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.02091Z","time spent":"588.215567ms","remote":"127.0.0.1:34652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1167,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:06.609239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.730212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:06.609281Z","caller":"traceutil/trace.go:171","msg":"trace[100894200] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25517; }","duration":"593.827685ms","start":"2026-08-16T13:45:06.01544Z","end":"2026-08-16T13:45:06.609268Z","steps":["trace[100894200] 'agreement among raft nodes before linearized reading' (duration: 593.756313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.609312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.015425Z","time spent":"593.880806ms","remote":"127.0.0.1:34580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-16T13:45:06.609281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.106605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-08-16T13:45:06.609365Z","caller":"traceutil/trace.go:171","msg":"trace[357309448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:25517; }","duration":"587.219349ms","start":"2026-08-16T13:45:06.022124Z","end":"2026-08-16T13:45:06.609344Z","steps":["trace[357309448] 'agreement among raft nodes before linearized reading' (duration: 586.978632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.609414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.022116Z","time spent":"587.28887ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-08-16T13:45:06.609503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.575303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:06.609532Z","caller":"traceutil/trace.go:171","msg":"trace[574151133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25517; }","duration":"344.643415ms","start":"2026-08-16T13:45:06.26488Z","end":"2026-08-16T13:45:06.609523Z","steps":["trace[574151133] 'agreement among raft nodes before linearized reading' (duration: 344.591843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.609557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.264864Z","time spent":"344.687926ms","remote":"127.0.0.1:34374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T13:45:06.610312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.662976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-16T13:45:06.610401Z","caller":"traceutil/trace.go:171","msg":"trace[110460049] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25517; }","duration":"253.733358ms","start":"2026-08-16T13:45:06.356625Z","end":"2026-08-16T13:45:06.610358Z","steps":["trace[110460049] 'agreement among raft nodes before linearized reading' (duration: 253.4418ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:06.886701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22117} {"level":"warn","ts":"2026-08-16T13:45:06.887958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.318304ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-08-16T13:45:06.888071Z","caller":"traceutil/trace.go:171","msg":"trace[1235651000] linearizableReadLoop","detail":"{readStateIndex:26368; appliedIndex:26367; }","duration":"273.827073ms","start":"2026-08-16T13:45:06.614224Z","end":"2026-08-16T13:45:06.888051Z","steps":["trace[1235651000] 'read index received' (duration: 16.423012ms)","trace[1235651000] 'applied index is now lower than readState.Index' (duration: 257.402211ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:06.888195Z","caller":"traceutil/trace.go:171","msg":"trace[1943793338] compact","detail":"{revision:22117; response_revision:25517; }","duration":"277.221578ms","start":"2026-08-16T13:45:06.610965Z","end":"2026-08-16T13:45:06.888186Z","steps":["trace[1943793338] 'check and update compact revision' (duration: 254.077927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:06.889191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.947774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-08-16T13:45:06.889255Z","caller":"traceutil/trace.go:171","msg":"trace[1267467933] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:25517; }","duration":"275.058788ms","start":"2026-08-16T13:45:06.614174Z","end":"2026-08-16T13:45:06.889233Z","steps":["trace[1267467933] 'agreement among raft nodes before linearized reading' (duration: 274.380968ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:07.139662Z","caller":"traceutil/trace.go:171","msg":"trace[1378840812] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"519.395801ms","start":"2026-08-16T13:45:06.62023Z","end":"2026-08-16T13:45:07.139626Z","steps":["trace[1378840812] 'process raft request' (duration: 465.490325ms)","trace[1378840812] 'compare' (duration: 53.727321ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:07.139851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.620191Z","time spent":"519.555215ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7836,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:07.161338Z","caller":"traceutil/trace.go:171","msg":"trace[370020463] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"530.285508ms","start":"2026-08-16T13:45:06.631006Z","end":"2026-08-16T13:45:07.161291Z","steps":["trace[370020463] 'process raft request' (duration: 522.360015ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:07.161484Z","caller":"traceutil/trace.go:171","msg":"trace[1316871584] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"522.778365ms","start":"2026-08-16T13:45:06.638651Z","end":"2026-08-16T13:45:07.161429Z","steps":["trace[1316871584] 'process raft request' (duration: 522.537039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:07.161582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.630995Z","time spent":"530.448802ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:07.161609Z","caller":"traceutil/trace.go:171","msg":"trace[758341574] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"244.401546ms","start":"2026-08-16T13:45:06.917194Z","end":"2026-08-16T13:45:07.161596Z","steps":["trace[758341574] 'process raft request' (duration: 244.123298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:07.161689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.77486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-16T13:45:07.161745Z","caller":"traceutil/trace.go:171","msg":"trace[993402249] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"259.569282ms","start":"2026-08-16T13:45:06.902165Z","end":"2026-08-16T13:45:07.161734Z","steps":["trace[993402249] 'process raft request' (duration: 259.083028ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:07.161753Z","caller":"traceutil/trace.go:171","msg":"trace[2009849957] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25523; }","duration":"546.871503ms","start":"2026-08-16T13:45:06.61487Z","end":"2026-08-16T13:45:07.161741Z","steps":["trace[2009849957] 'agreement among raft nodes before linearized reading' (duration: 546.683418ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:07.161729Z","caller":"traceutil/trace.go:171","msg":"trace[1260053884] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"205.44852ms","start":"2026-08-16T13:45:06.956225Z","end":"2026-08-16T13:45:07.161673Z","steps":["trace[1260053884] 'process raft request' (duration: 205.179733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:07.161803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.614822Z","time spent":"546.973756ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-16T13:45:07.162003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.293369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-08-16T13:45:07.161692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.638638Z","time spent":"522.939311ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:07.16206Z","caller":"traceutil/trace.go:171","msg":"trace[1676283677] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25523; }","duration":"358.355161ms","start":"2026-08-16T13:45:06.803693Z","end":"2026-08-16T13:45:07.162048Z","steps":["trace[1676283677] 'agreement among raft nodes before linearized reading' (duration: 358.163545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:07.162089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:06.80367Z","time spent":"358.412822ms","remote":"127.0.0.1:34544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-08-16T13:45:07.161503Z","caller":"traceutil/trace.go:171","msg":"trace[833287949] linearizableReadLoop","detail":"{readStateIndex:26371; appliedIndex:26368; }","duration":"273.335429ms","start":"2026-08-16T13:45:06.888141Z","end":"2026-08-16T13:45:07.161477Z","steps":["trace[833287949] 'read index received' (duration: 197.605509ms)","trace[833287949] 'applied index is now lower than readState.Index' (duration: 75.72848ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:07.252043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22117,"took":"359.501653ms","hash":2044643889,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":45256704,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-16T13:45:07.252136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2044643889,"revision":22117,"compact-revision":19868} {"level":"info","ts":"2026-08-16T13:45:12.541155Z","caller":"traceutil/trace.go:171","msg":"trace[228639035] linearizableReadLoop","detail":"{readStateIndex:26421; appliedIndex:26419; }","duration":"198.028891ms","start":"2026-08-16T13:45:12.343087Z","end":"2026-08-16T13:45:12.541116Z","steps":["trace[228639035] 'read index received' (duration: 24.294304ms)","trace[228639035] 'applied index is now lower than readState.Index' (duration: 173.732787ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:12.541195Z","caller":"traceutil/trace.go:171","msg":"trace[39773746] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"246.031121ms","start":"2026-08-16T13:45:12.29512Z","end":"2026-08-16T13:45:12.541151Z","steps":["trace[39773746] 'process raft request' (duration: 222.145629ms)","trace[39773746] 'compare' (duration: 23.383138ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:12.541405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.284438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:12.541448Z","caller":"traceutil/trace.go:171","msg":"trace[1021156415] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25569; }","duration":"198.393221ms","start":"2026-08-16T13:45:12.343042Z","end":"2026-08-16T13:45:12.541435Z","steps":["trace[1021156415] 'agreement among raft nodes before linearized reading' (duration: 198.270757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:12.882016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.767452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:45:12.882295Z","caller":"traceutil/trace.go:171","msg":"trace[1856364361] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"247.309026ms","start":"2026-08-16T13:45:12.634952Z","end":"2026-08-16T13:45:12.882261Z","steps":["trace[1856364361] 'process raft request' (duration: 85.449973ms)","trace[1856364361] 'compare' (duration: 160.474144ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:12.88316Z","caller":"traceutil/trace.go:171","msg":"trace[1576111239] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"247.467611ms","start":"2026-08-16T13:45:12.635675Z","end":"2026-08-16T13:45:12.883143Z","steps":["trace[1576111239] 'process raft request' (duration: 246.527435ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:13.578718Z","caller":"traceutil/trace.go:171","msg":"trace[1860985508] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"388.107276ms","start":"2026-08-16T13:45:13.190577Z","end":"2026-08-16T13:45:13.578684Z","steps":["trace[1860985508] 'process raft request' (duration: 289.579985ms)","trace[1860985508] 'compare' (duration: 98.133771ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:13.578891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.19054Z","time spent":"388.255431ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:13.579211Z","caller":"traceutil/trace.go:171","msg":"trace[369564874] linearizableReadLoop","detail":"{readStateIndex:26429; appliedIndex:26426; }","duration":"326.979668ms","start":"2026-08-16T13:45:13.252198Z","end":"2026-08-16T13:45:13.579177Z","steps":["trace[369564874] 'read index received' (duration: 228.065305ms)","trace[369564874] 'applied index is now lower than readState.Index' (duration: 98.912913ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:13.579387Z","caller":"traceutil/trace.go:171","msg":"trace[1702705756] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"388.469547ms","start":"2026-08-16T13:45:13.190897Z","end":"2026-08-16T13:45:13.579367Z","steps":["trace[1702705756] 'process raft request' (duration: 387.742846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:13.579476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.286216ms","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-08-16T13:45:13.579511Z","caller":"traceutil/trace.go:171","msg":"trace[812270969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25577; }","duration":"327.321797ms","start":"2026-08-16T13:45:13.25218Z","end":"2026-08-16T13:45:13.579501Z","steps":["trace[812270969] 'agreement among raft nodes before linearized reading' (duration: 327.190984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:13.579535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.252072Z","time spent":"327.457791ms","remote":"127.0.0.1:34640","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":"warn","ts":"2026-08-16T13:45:13.579545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.190871Z","time spent":"388.55571ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:13.579643Z","caller":"traceutil/trace.go:171","msg":"trace[1773834429] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"339.586952ms","start":"2026-08-16T13:45:13.240046Z","end":"2026-08-16T13:45:13.579633Z","steps":["trace[1773834429] 'process raft request' (duration: 338.989065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:13.579701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.240025Z","time spent":"339.642504ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:13.579834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.604952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-16T13:45:13.579853Z","caller":"traceutil/trace.go:171","msg":"trace[1254757217] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25577; }","duration":"291.679555ms","start":"2026-08-16T13:45:13.288166Z","end":"2026-08-16T13:45:13.579845Z","steps":["trace[1254757217] 'agreement among raft nodes before linearized reading' (duration: 291.636874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:13.580005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.717326ms","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":"info","ts":"2026-08-16T13:45:13.580028Z","caller":"traceutil/trace.go:171","msg":"trace[137633298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25577; }","duration":"275.761457ms","start":"2026-08-16T13:45:13.304256Z","end":"2026-08-16T13:45:13.580017Z","steps":["trace[137633298] 'agreement among raft nodes before linearized reading' (duration: 275.700176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:13.58013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.734263ms","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-08-16T13:45:13.580148Z","caller":"traceutil/trace.go:171","msg":"trace[1769559417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25577; }","duration":"282.786424ms","start":"2026-08-16T13:45:13.297355Z","end":"2026-08-16T13:45:13.580141Z","steps":["trace[1769559417] 'agreement among raft nodes before linearized reading' (duration: 282.728432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:13.580367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.471237ms","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-08-16T13:45:13.580412Z","caller":"traceutil/trace.go:171","msg":"trace[396840420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25577; }","duration":"287.540888ms","start":"2026-08-16T13:45:13.292859Z","end":"2026-08-16T13:45:13.5804Z","steps":["trace[396840420] 'agreement among raft nodes before linearized reading' (duration: 287.378724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:13.580462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.098753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-16T13:45:13.580488Z","caller":"traceutil/trace.go:171","msg":"trace[862607761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25577; }","duration":"283.144714ms","start":"2026-08-16T13:45:13.297335Z","end":"2026-08-16T13:45:13.58048Z","steps":["trace[862607761] 'agreement among raft nodes before linearized reading' (duration: 283.077242ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:13.746049Z","caller":"traceutil/trace.go:171","msg":"trace[1178593219] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"159.014973ms","start":"2026-08-16T13:45:13.586976Z","end":"2026-08-16T13:45:13.745991Z","steps":["trace[1178593219] 'process raft request' (duration: 147.075208ms)","trace[1178593219] 'compare' (duration: 11.577405ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:14.14397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.593485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:45:14.144226Z","caller":"traceutil/trace.go:171","msg":"trace[371057021] linearizableReadLoop","detail":"{readStateIndex:26434; appliedIndex:26429; }","duration":"414.543821ms","start":"2026-08-16T13:45:13.729668Z","end":"2026-08-16T13:45:14.144211Z","steps":["trace[371057021] 'read index received' (duration: 4.502617ms)","trace[371057021] 'applied index is now lower than readState.Index' (duration: 410.040424ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:14.1443Z","caller":"traceutil/trace.go:171","msg":"trace[2105228007] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"557.292797ms","start":"2026-08-16T13:45:13.586999Z","end":"2026-08-16T13:45:14.144292Z","steps":["trace[2105228007] 'process raft request' (duration: 261.306742ms)","trace[2105228007] 'compare' (duration: 295.384308ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:14.144347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.586955Z","time spent":"557.363429ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:14.144487Z","caller":"traceutil/trace.go:171","msg":"trace[173879225] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"557.440301ms","start":"2026-08-16T13:45:13.587024Z","end":"2026-08-16T13:45:14.144464Z","steps":["trace[173879225] 'process raft request' (duration: 557.05724ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:14.144489Z","caller":"traceutil/trace.go:171","msg":"trace[1988287335] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"554.047284ms","start":"2026-08-16T13:45:13.590406Z","end":"2026-08-16T13:45:14.144453Z","steps":["trace[1988287335] 'process raft request' (duration: 553.774567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:14.144599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.586988Z","time spent":"557.550804ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:14.144617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.954332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:14.144641Z","caller":"traceutil/trace.go:171","msg":"trace[1133726446] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25582; }","duration":"415.010123ms","start":"2026-08-16T13:45:13.729622Z","end":"2026-08-16T13:45:14.144632Z","steps":["trace[1133726446] 'agreement among raft nodes before linearized reading' (duration: 414.90321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:14.144648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.590395Z","time spent":"554.164757ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:14.144667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.729605Z","time spent":"415.056515ms","remote":"127.0.0.1:33252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-08-16T13:45:14.14475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.592652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-16T13:45:14.14468Z","caller":"traceutil/trace.go:171","msg":"trace[129456046] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"555.554337ms","start":"2026-08-16T13:45:13.588969Z","end":"2026-08-16T13:45:14.144523Z","steps":["trace[129456046] 'process raft request' (duration: 555.183467ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:14.144875Z","caller":"traceutil/trace.go:171","msg":"trace[428731014] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:25582; }","duration":"131.773907ms","start":"2026-08-16T13:45:14.013069Z","end":"2026-08-16T13:45:14.144843Z","steps":["trace[428731014] 'agreement among raft nodes before linearized reading' (duration: 131.54692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:14.144972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:13.588945Z","time spent":"555.945979ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:16.631378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.1871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:45:16.631596Z","caller":"traceutil/trace.go:171","msg":"trace[204219588] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"196.166049ms","start":"2026-08-16T13:45:16.435397Z","end":"2026-08-16T13:45:16.631563Z","steps":["trace[204219588] 'process raft request' (duration: 80.643179ms)","trace[204219588] 'compare' (duration: 114.999335ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:22.967311Z","caller":"traceutil/trace.go:171","msg":"trace[652002295] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"314.341472ms","start":"2026-08-16T13:45:22.652931Z","end":"2026-08-16T13:45:22.967272Z","steps":["trace[652002295] 'process raft request' (duration: 266.392653ms)","trace[652002295] 'compare' (duration: 47.093005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:22.967589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:22.652908Z","time spent":"314.495656ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:22.967944Z","caller":"traceutil/trace.go:171","msg":"trace[1120454749] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"102.927836ms","start":"2026-08-16T13:45:22.864982Z","end":"2026-08-16T13:45:22.96791Z","steps":["trace[1120454749] 'process raft request' (duration: 101.946869ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:22.968352Z","caller":"traceutil/trace.go:171","msg":"trace[1699680062] linearizableReadLoop","detail":"{readStateIndex:26506; appliedIndex:26505; }","duration":"298.219539ms","start":"2026-08-16T13:45:22.670117Z","end":"2026-08-16T13:45:22.968337Z","steps":["trace[1699680062] 'read index received' (duration: 249.18159ms)","trace[1699680062] 'applied index is now lower than readState.Index' (duration: 49.035789ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:22.96872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.578268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-16T13:45:22.968788Z","caller":"traceutil/trace.go:171","msg":"trace[888229892] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25653; }","duration":"298.690912ms","start":"2026-08-16T13:45:22.670083Z","end":"2026-08-16T13:45:22.968774Z","steps":["trace[888229892] 'agreement among raft nodes before linearized reading' (duration: 298.485496ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:23.329422Z","caller":"traceutil/trace.go:171","msg":"trace[1089365488] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"406.139535ms","start":"2026-08-16T13:45:22.923243Z","end":"2026-08-16T13:45:23.329382Z","steps":["trace[1089365488] 'process raft request' (duration: 389.9897ms)","trace[1089365488] 'compare' (duration: 15.475076ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:23.32947Z","caller":"traceutil/trace.go:171","msg":"trace[79930088] linearizableReadLoop","detail":"{readStateIndex:26509; appliedIndex:26507; }","duration":"361.045706ms","start":"2026-08-16T13:45:22.968389Z","end":"2026-08-16T13:45:23.329435Z","steps":["trace[79930088] 'read index received' (duration: 344.82016ms)","trace[79930088] 'applied index is now lower than readState.Index' (duration: 16.224056ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:23.329522Z","caller":"traceutil/trace.go:171","msg":"trace[1840163901] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"406.111554ms","start":"2026-08-16T13:45:22.92335Z","end":"2026-08-16T13:45:23.329461Z","steps":["trace[1840163901] 'process raft request' (duration: 405.96073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.329591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:22.923214Z","time spent":"406.285499ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:23.329749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:22.923318Z","time spent":"406.30922ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:23.329856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.604463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-16T13:45:23.329918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.192699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-08-16T13:45:23.330695Z","caller":"traceutil/trace.go:171","msg":"trace[1202553889] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25655; }","duration":"522.955981ms","start":"2026-08-16T13:45:22.807679Z","end":"2026-08-16T13:45:23.330635Z","steps":["trace[1202553889] 'agreement among raft nodes before linearized reading' (duration: 521.90002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.330775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:22.807669Z","time spent":"523.085664ms","remote":"127.0.0.1:44274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418327,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-08-16T13:45:23.329985Z","caller":"traceutil/trace.go:171","msg":"trace[524218178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25655; }","duration":"537.812939ms","start":"2026-08-16T13:45:22.792141Z","end":"2026-08-16T13:45:23.329954Z","steps":["trace[524218178] 'agreement among raft nodes before linearized reading' (duration: 537.578262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.331156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:22.792122Z","time spent":"538.998912ms","remote":"127.0.0.1:34380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T13:45:23.802893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.378661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:45:23.803236Z","caller":"traceutil/trace.go:171","msg":"trace[419747045] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26509; }","duration":"473.670494ms","start":"2026-08-16T13:45:23.329542Z","end":"2026-08-16T13:45:23.803213Z","steps":["trace[419747045] 'read index received' (duration: 328.948504ms)","trace[419747045] 'applied index is now lower than readState.Index' (duration: 144.72044ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:23.803371Z","caller":"traceutil/trace.go:171","msg":"trace[1432903916] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"789.24017ms","start":"2026-08-16T13:45:23.014112Z","end":"2026-08-16T13:45:23.803352Z","steps":["trace[1432903916] 'process raft request' (duration: 644.284883ms)","trace[1432903916] 'compare' (duration: 144.167315ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:23.803474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.014083Z","time spent":"789.335493ms","remote":"127.0.0.1:46304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7897,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:23.803541Z","caller":"traceutil/trace.go:171","msg":"trace[1671250850] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"567.884764ms","start":"2026-08-16T13:45:23.235643Z","end":"2026-08-16T13:45:23.803528Z","steps":["trace[1671250850] 'process raft request' (duration: 567.405531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.803601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.112622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-16T13:45:23.803656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.235604Z","time spent":"567.988317ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:23.803703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.148729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-16T13:45:23.803796Z","caller":"traceutil/trace.go:171","msg":"trace[589215972] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25658; }","duration":"831.278763ms","start":"2026-08-16T13:45:22.972498Z","end":"2026-08-16T13:45:23.803777Z","steps":["trace[589215972] 'agreement among raft nodes before linearized reading' (duration: 830.973274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.80385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:22.972481Z","time spent":"831.359315ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-16T13:45:23.803736Z","caller":"traceutil/trace.go:171","msg":"trace[20959611] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25658; }","duration":"626.273576ms","start":"2026-08-16T13:45:23.177443Z","end":"2026-08-16T13:45:23.803717Z","steps":["trace[20959611] 'agreement among raft nodes before linearized reading' (duration: 626.004779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.804076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.17738Z","time spent":"626.672088ms","remote":"127.0.0.1:48366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"info","ts":"2026-08-16T13:45:23.803477Z","caller":"traceutil/trace.go:171","msg":"trace[32226496] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"565.746135ms","start":"2026-08-16T13:45:23.237707Z","end":"2026-08-16T13:45:23.803453Z","steps":["trace[32226496] 'process raft request' (duration: 565.441996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.804603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.237669Z","time spent":"566.787195ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:23.970551Z","caller":"traceutil/trace.go:171","msg":"trace[574580124] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"353.073712ms","start":"2026-08-16T13:45:23.617442Z","end":"2026-08-16T13:45:23.970516Z","steps":["trace[574580124] 'process raft request' (duration: 352.165747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.97075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.617417Z","time spent":"353.216366ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:23.971851Z","caller":"traceutil/trace.go:171","msg":"trace[24219155] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"350.906691ms","start":"2026-08-16T13:45:23.620935Z","end":"2026-08-16T13:45:23.971842Z","steps":["trace[24219155] 'process raft request' (duration: 350.861719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.971906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.620915Z","time spent":"350.966753ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:23.972101Z","caller":"traceutil/trace.go:171","msg":"trace[810742255] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"351.53066ms","start":"2026-08-16T13:45:23.620564Z","end":"2026-08-16T13:45:23.972094Z","steps":["trace[810742255] 'process raft request' (duration: 349.872153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:23.97214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.620529Z","time spent":"351.592061ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:45:24.724706Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006795.wal"} {"level":"info","ts":"2026-08-16T13:45:24.726466Z","caller":"traceutil/trace.go:171","msg":"trace[1045074400] linearizableReadLoop","detail":"{readStateIndex:26516; appliedIndex:26512; }","duration":"923.144949ms","start":"2026-08-16T13:45:23.80329Z","end":"2026-08-16T13:45:24.726435Z","steps":["trace[1045074400] 'read index received' (duration: 166.389312ms)","trace[1045074400] 'applied index is now lower than readState.Index' (duration: 756.754516ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:24.726663Z","caller":"traceutil/trace.go:171","msg":"trace[1459714673] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"1.008007035s","start":"2026-08-16T13:45:23.718617Z","end":"2026-08-16T13:45:24.726625Z","steps":["trace[1459714673] 'process raft request' (duration: 1.006654086s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.726825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.718588Z","time spent":"1.008154689s","remote":"127.0.0.1:44274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:45:24.726894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392827989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:24.72695Z","caller":"traceutil/trace.go:171","msg":"trace[1195227539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25662; }","duration":"1.392901761s","start":"2026-08-16T13:45:23.334033Z","end":"2026-08-16T13:45:24.726934Z","steps":["trace[1195227539] 'agreement among raft nodes before linearized reading' (duration: 1.392670085s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.726999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.333947Z","time spent":"1.393043135s","remote":"127.0.0.1:34374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T13:45:24.727286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.642485ms","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-08-16T13:45:24.727421Z","caller":"traceutil/trace.go:171","msg":"trace[818715641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25662; }","duration":"932.82363ms","start":"2026-08-16T13:45:23.794589Z","end":"2026-08-16T13:45:24.727412Z","steps":["trace[818715641] 'agreement among raft nodes before linearized reading' (duration: 932.095439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.727475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.794561Z","time spent":"932.879241ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-16T13:45:24.733203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.48615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18435"} {"level":"info","ts":"2026-08-16T13:45:24.733317Z","caller":"traceutil/trace.go:171","msg":"trace[1820798282] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25662; }","duration":"927.662535ms","start":"2026-08-16T13:45:23.80563Z","end":"2026-08-16T13:45:24.733293Z","steps":["trace[1820798282] 'agreement among raft nodes before linearized reading' (duration: 927.342316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.733448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.805602Z","time spent":"927.82682ms","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18458,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-16T13:45:24.733656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.410278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-16T13:45:24.73367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.38879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18435"} {"level":"info","ts":"2026-08-16T13:45:24.733715Z","caller":"traceutil/trace.go:171","msg":"trace[1881714466] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25662; }","duration":"920.453671ms","start":"2026-08-16T13:45:23.813249Z","end":"2026-08-16T13:45:24.733703Z","steps":["trace[1881714466] 'agreement among raft nodes before linearized reading' (duration: 920.294307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.733711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.26295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-16T13:45:24.733751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:23.813234Z","time spent":"920.509204ms","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18458,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"info","ts":"2026-08-16T13:45:24.73371Z","caller":"traceutil/trace.go:171","msg":"trace[298958802] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25662; }","duration":"633.51042ms","start":"2026-08-16T13:45:24.100179Z","end":"2026-08-16T13:45:24.733689Z","steps":["trace[298958802] 'agreement among raft nodes before linearized reading' (duration: 633.332905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.733177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.793105ms","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-08-16T13:45:24.733801Z","caller":"traceutil/trace.go:171","msg":"trace[2022170284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25662; }","duration":"528.419604ms","start":"2026-08-16T13:45:24.205361Z","end":"2026-08-16T13:45:24.73378Z","steps":["trace[2022170284] 'agreement among raft nodes before linearized reading' (duration: 528.134286ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:24.733931Z","caller":"traceutil/trace.go:171","msg":"trace[1133230069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25662; }","duration":"486.571036ms","start":"2026-08-16T13:45:24.247343Z","end":"2026-08-16T13:45:24.733914Z","steps":["trace[1133230069] 'agreement among raft nodes before linearized reading' (duration: 485.63228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.733967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:24.205331Z","time spent":"528.624599ms","remote":"127.0.0.1:34640","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-08-16T13:45:24.73395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:24.100149Z","time spent":"633.742037ms","remote":"127.0.0.1:34902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":60,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-16T13:45:24.734099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.082282ms","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-08-16T13:45:24.734139Z","caller":"traceutil/trace.go:171","msg":"trace[613562149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25662; }","duration":"492.154474ms","start":"2026-08-16T13:45:24.241975Z","end":"2026-08-16T13:45:24.734129Z","steps":["trace[613562149] 'agreement among raft nodes before linearized reading' (duration: 492.056641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.734162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:24.241963Z","time spent":"492.193786ms","remote":"127.0.0.1:34640","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":"warn","ts":"2026-08-16T13:45:24.733978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:24.247335Z","time spent":"486.632759ms","remote":"127.0.0.1:34640","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":"warn","ts":"2026-08-16T13:45:24.734385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.611322ms","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-08-16T13:45:24.734438Z","caller":"traceutil/trace.go:171","msg":"trace[1966932047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25662; }","duration":"490.701914ms","start":"2026-08-16T13:45:24.243722Z","end":"2026-08-16T13:45:24.734424Z","steps":["trace[1966932047] 'agreement among raft nodes before linearized reading' (duration: 490.55974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:24.73448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:45:24.2437Z","time spent":"490.772736ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-16T13:45:29.105557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.694988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7929f6e4-42d6-4e32-89a5-71f5bfa70e5f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-16T13:45:29.105631Z","caller":"traceutil/trace.go:171","msg":"trace[1818527382] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7929f6e4-42d6-4e32-89a5-71f5bfa70e5f; range_end:; response_count:1; response_revision:25755; }","duration":"252.833202ms","start":"2026-08-16T13:45:28.852784Z","end":"2026-08-16T13:45:29.105617Z","steps":["trace[1818527382] 'range keys from in-memory index tree' (duration: 252.499983ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:29.106101Z","caller":"traceutil/trace.go:171","msg":"trace[1300614853] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"115.422597ms","start":"2026-08-16T13:45:28.990623Z","end":"2026-08-16T13:45:29.106046Z","steps":["trace[1300614853] 'process raft request' (duration: 35.545751ms)","trace[1300614853] 'compare' (duration: 79.266139ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:29.354158Z","caller":"traceutil/trace.go:171","msg":"trace[632372572] linearizableReadLoop","detail":"{readStateIndex:26613; appliedIndex:26612; }","duration":"161.540115ms","start":"2026-08-16T13:45:29.192585Z","end":"2026-08-16T13:45:29.354125Z","steps":["trace[632372572] 'read index received' (duration: 82.286605ms)","trace[632372572] 'applied index is now lower than readState.Index' (duration: 79.25231ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:29.354396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.799011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-16T13:45:29.354363Z","caller":"traceutil/trace.go:171","msg":"trace[1170865600] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"240.575597ms","start":"2026-08-16T13:45:29.113745Z","end":"2026-08-16T13:45:29.354321Z","steps":["trace[1170865600] 'process raft request' (duration: 161.103261ms)","trace[1170865600] 'compare' (duration: 79.011553ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:29.354441Z","caller":"traceutil/trace.go:171","msg":"trace[681467805] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:25757; }","duration":"161.906204ms","start":"2026-08-16T13:45:29.192519Z","end":"2026-08-16T13:45:29.354426Z","steps":["trace[681467805] 'agreement among raft nodes before linearized reading' (duration: 161.713529ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:29.633916Z","caller":"traceutil/trace.go:171","msg":"trace[242984917] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"281.825678ms","start":"2026-08-16T13:45:29.352017Z","end":"2026-08-16T13:45:29.633842Z","steps":["trace[242984917] 'process raft request' (duration: 281.444217ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:29.633986Z","caller":"traceutil/trace.go:171","msg":"trace[447586256] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"279.694678ms","start":"2026-08-16T13:45:29.354258Z","end":"2026-08-16T13:45:29.633952Z","steps":["trace[447586256] 'process raft request' (duration: 279.437261ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:29.782879Z","caller":"traceutil/trace.go:171","msg":"trace[640150166] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"147.057547ms","start":"2026-08-16T13:45:29.635779Z","end":"2026-08-16T13:45:29.782837Z","steps":["trace[640150166] 'process raft request' (duration: 51.916251ms)","trace[640150166] 'compare' (duration: 94.862668ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:31.461612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.401644ms","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-08-16T13:45:31.461694Z","caller":"traceutil/trace.go:171","msg":"trace[637585319] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25775; }","duration":"245.560288ms","start":"2026-08-16T13:45:31.216106Z","end":"2026-08-16T13:45:31.461667Z","steps":["trace[637585319] 'count revisions from in-memory index tree' (duration: 245.322591ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:36.990306Z","caller":"traceutil/trace.go:171","msg":"trace[352212997] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"193.953847ms","start":"2026-08-16T13:45:36.796324Z","end":"2026-08-16T13:45:36.990277Z","steps":["trace[352212997] 'process raft request' (duration: 193.786022ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:37.0715Z","caller":"traceutil/trace.go:171","msg":"trace[329194575] linearizableReadLoop","detail":"{readStateIndex:26692; appliedIndex:26690; }","duration":"252.586116ms","start":"2026-08-16T13:45:36.818893Z","end":"2026-08-16T13:45:37.071479Z","steps":["trace[329194575] 'read index received' (duration: 171.180895ms)","trace[329194575] 'applied index is now lower than readState.Index' (duration: 81.404261ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:37.071609Z","caller":"traceutil/trace.go:171","msg":"trace[1982520848] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"275.164601ms","start":"2026-08-16T13:45:36.796435Z","end":"2026-08-16T13:45:37.0716Z","steps":["trace[1982520848] 'process raft request' (duration: 274.843031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:37.071818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.893885ms","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":"info","ts":"2026-08-16T13:45:37.071869Z","caller":"traceutil/trace.go:171","msg":"trace[563613248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25833; }","duration":"252.990787ms","start":"2026-08-16T13:45:36.818842Z","end":"2026-08-16T13:45:37.071833Z","steps":["trace[563613248] 'agreement among raft nodes before linearized reading' (duration: 252.860653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:37.071972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.506016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-16T13:45:37.071989Z","caller":"traceutil/trace.go:171","msg":"trace[348723140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25833; }","duration":"251.537046ms","start":"2026-08-16T13:45:36.820446Z","end":"2026-08-16T13:45:37.071983Z","steps":["trace[348723140] 'agreement among raft nodes before linearized reading' (duration: 251.482995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:37.072162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.244628ms","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-08-16T13:45:37.072181Z","caller":"traceutil/trace.go:171","msg":"trace[678141994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25833; }","duration":"251.277288ms","start":"2026-08-16T13:45:36.820899Z","end":"2026-08-16T13:45:37.072176Z","steps":["trace[678141994] 'agreement among raft nodes before linearized reading' (duration: 251.222307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:37.072239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.72948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-08-16T13:45:37.072333Z","caller":"traceutil/trace.go:171","msg":"trace[1735050634] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:25833; }","duration":"182.881254ms","start":"2026-08-16T13:45:36.889428Z","end":"2026-08-16T13:45:37.07231Z","steps":["trace[1735050634] 'agreement among raft nodes before linearized reading' (duration: 182.622217ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:41.121972Z","caller":"traceutil/trace.go:171","msg":"trace[608518574] transaction","detail":"{read_only:false; response_revision:25868; number_of_response:1; }","duration":"112.586196ms","start":"2026-08-16T13:45:41.009362Z","end":"2026-08-16T13:45:41.121948Z","steps":["trace[608518574] 'process raft request' (duration: 112.075332ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:41.335156Z","caller":"traceutil/trace.go:171","msg":"trace[1833471923] transaction","detail":"{read_only:false; response_revision:25869; number_of_response:1; }","duration":"209.269337ms","start":"2026-08-16T13:45:41.125858Z","end":"2026-08-16T13:45:41.335128Z","steps":["trace[1833471923] 'process raft request' (duration: 160.189426ms)","trace[1833471923] 'compare' (duration: 48.639618ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:41.335188Z","caller":"traceutil/trace.go:171","msg":"trace[988408740] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"157.077469ms","start":"2026-08-16T13:45:41.178072Z","end":"2026-08-16T13:45:41.335149Z","steps":["trace[988408740] 'process raft request' (duration: 156.942365ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:41.335398Z","caller":"traceutil/trace.go:171","msg":"trace[59684107] linearizableReadLoop","detail":"{readStateIndex:26729; appliedIndex:26728; }","duration":"159.465846ms","start":"2026-08-16T13:45:41.175924Z","end":"2026-08-16T13:45:41.33539Z","steps":["trace[59684107] 'read index received' (duration: 110.169429ms)","trace[59684107] 'applied index is now lower than readState.Index' (duration: 49.295397ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:41.335557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.62837ms","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-08-16T13:45:41.335586Z","caller":"traceutil/trace.go:171","msg":"trace[1815486532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25870; }","duration":"159.700372ms","start":"2026-08-16T13:45:41.175877Z","end":"2026-08-16T13:45:41.335577Z","steps":["trace[1815486532] 'agreement among raft nodes before linearized reading' (duration: 159.570039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:41.33582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.647346ms","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-08-16T13:45:41.335888Z","caller":"traceutil/trace.go:171","msg":"trace[506140669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25870; }","duration":"156.77673ms","start":"2026-08-16T13:45:41.179095Z","end":"2026-08-16T13:45:41.335871Z","steps":["trace[506140669] 'agreement among raft nodes before linearized reading' (duration: 156.605435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:41.335821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.592132ms","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-08-16T13:45:41.335992Z","caller":"traceutil/trace.go:171","msg":"trace[64426609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25870; }","duration":"157.819799ms","start":"2026-08-16T13:45:41.178158Z","end":"2026-08-16T13:45:41.335978Z","steps":["trace[64426609] 'agreement among raft nodes before linearized reading' (duration: 157.49586ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:41.51854Z","caller":"traceutil/trace.go:171","msg":"trace[1149481853] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"177.603226ms","start":"2026-08-16T13:45:41.340902Z","end":"2026-08-16T13:45:41.518505Z","steps":["trace[1149481853] 'process raft request' (duration: 173.48027ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:41.518714Z","caller":"traceutil/trace.go:171","msg":"trace[1296991029] linearizableReadLoop","detail":"{readStateIndex:26733; appliedIndex:26730; }","duration":"177.018009ms","start":"2026-08-16T13:45:41.341648Z","end":"2026-08-16T13:45:41.518666Z","steps":["trace[1296991029] 'read index received' (duration: 43.903725ms)","trace[1296991029] 'applied index is now lower than readState.Index' (duration: 133.111824ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:41.518783Z","caller":"traceutil/trace.go:171","msg":"trace[1274938505] transaction","detail":"{read_only:false; response_revision:25873; number_of_response:1; }","duration":"177.345469ms","start":"2026-08-16T13:45:41.341409Z","end":"2026-08-16T13:45:41.518754Z","steps":["trace[1274938505] 'process raft request' (duration: 176.975628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:41.518934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.247305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:41.518973Z","caller":"traceutil/trace.go:171","msg":"trace[2102272244] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25873; }","duration":"177.320787ms","start":"2026-08-16T13:45:41.341639Z","end":"2026-08-16T13:45:41.51896Z","steps":["trace[2102272244] 'agreement among raft nodes before linearized reading' (duration: 177.201454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:41.519086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.991418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-16T13:45:41.519125Z","caller":"traceutil/trace.go:171","msg":"trace[110323123] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25873; }","duration":"113.06345ms","start":"2026-08-16T13:45:41.406049Z","end":"2026-08-16T13:45:41.519112Z","steps":["trace[110323123] 'agreement among raft nodes before linearized reading' (duration: 112.915905ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:45:41.681431Z","caller":"traceutil/trace.go:171","msg":"trace[1686551245] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"157.019807ms","start":"2026-08-16T13:45:41.524376Z","end":"2026-08-16T13:45:41.681396Z","steps":["trace[1686551245] 'process raft request' (duration: 127.347063ms)","trace[1686551245] 'compare' (duration: 29.243282ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:43.086239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.964965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:45:43.086359Z","caller":"traceutil/trace.go:171","msg":"trace[1393780207] linearizableReadLoop","detail":"{readStateIndex:26745; appliedIndex:26744; }","duration":"184.014277ms","start":"2026-08-16T13:45:42.902327Z","end":"2026-08-16T13:45:43.086341Z","steps":["trace[1393780207] 'read index received' (duration: 15.776104ms)","trace[1393780207] 'applied index is now lower than readState.Index' (duration: 168.236682ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:45:43.086606Z","caller":"traceutil/trace.go:171","msg":"trace[786171865] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"218.451805ms","start":"2026-08-16T13:45:42.868138Z","end":"2026-08-16T13:45:43.08659Z","steps":["trace[786171865] 'process raft request' (duration: 50.008957ms)","trace[786171865] 'compare' (duration: 167.627845ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:45:43.086759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.459854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:45:43.086839Z","caller":"traceutil/trace.go:171","msg":"trace[773996700] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25885; }","duration":"128.610398ms","start":"2026-08-16T13:45:42.958211Z","end":"2026-08-16T13:45:43.086822Z","steps":["trace[773996700] 'agreement among raft nodes before linearized reading' (duration: 128.436953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:45:43.086924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.556721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-08-16T13:45:43.087023Z","caller":"traceutil/trace.go:171","msg":"trace[1604929333] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25885; }","duration":"184.692976ms","start":"2026-08-16T13:45:42.902306Z","end":"2026-08-16T13:45:43.086999Z","steps":["trace[1604929333] 'agreement among raft nodes before linearized reading' (duration: 184.350066ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:01.537215Z","caller":"traceutil/trace.go:171","msg":"trace[1179249936] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"274.116698ms","start":"2026-08-16T13:46:01.26306Z","end":"2026-08-16T13:46:01.537177Z","steps":["trace[1179249936] 'process raft request' (duration: 273.947343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:01.53746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.027563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:46:01.537512Z","caller":"traceutil/trace.go:171","msg":"trace[1938914282] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:26034; }","duration":"161.136116ms","start":"2026-08-16T13:46:01.376363Z","end":"2026-08-16T13:46:01.537499Z","steps":["trace[1938914282] 'agreement among raft nodes before linearized reading' (duration: 160.992112ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:01.537332Z","caller":"traceutil/trace.go:171","msg":"trace[1747838583] linearizableReadLoop","detail":"{readStateIndex:26898; appliedIndex:26898; }","duration":"160.876069ms","start":"2026-08-16T13:46:01.376401Z","end":"2026-08-16T13:46:01.537277Z","steps":["trace[1747838583] 'read index received' (duration: 160.865069ms)","trace[1747838583] 'applied index is now lower than readState.Index' (duration: 9.25µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:01.691556Z","caller":"traceutil/trace.go:171","msg":"trace[1779249304] linearizableReadLoop","detail":"{readStateIndex:26899; appliedIndex:26898; }","duration":"153.753979ms","start":"2026-08-16T13:46:01.537773Z","end":"2026-08-16T13:46:01.691527Z","steps":["trace[1779249304] 'read index received' (duration: 119.33418ms)","trace[1779249304] 'applied index is now lower than readState.Index' (duration: 34.418839ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:01.691692Z","caller":"traceutil/trace.go:171","msg":"trace[590571948] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"283.271085ms","start":"2026-08-16T13:46:01.408408Z","end":"2026-08-16T13:46:01.69168Z","steps":["trace[590571948] 'process raft request' (duration: 248.641571ms)","trace[590571948] 'compare' (duration: 34.335836ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:01.691904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.420933ms","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-08-16T13:46:01.691989Z","caller":"traceutil/trace.go:171","msg":"trace[23233386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26035; }","duration":"249.551696ms","start":"2026-08-16T13:46:01.442418Z","end":"2026-08-16T13:46:01.69197Z","steps":["trace[23233386] 'agreement among raft nodes before linearized reading' (duration: 249.350861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:01.692166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.199984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-16T13:46:01.692202Z","caller":"traceutil/trace.go:171","msg":"trace[803922269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26035; }","duration":"212.264766ms","start":"2026-08-16T13:46:01.479927Z","end":"2026-08-16T13:46:01.692192Z","steps":["trace[803922269] 'agreement among raft nodes before linearized reading' (duration: 212.167963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:01.69253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.015114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:46:01.692621Z","caller":"traceutil/trace.go:171","msg":"trace[1985879805] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26035; }","duration":"231.153578ms","start":"2026-08-16T13:46:01.461441Z","end":"2026-08-16T13:46:01.692594Z","steps":["trace[1985879805] 'agreement among raft nodes before linearized reading' (duration: 230.981913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:01.917571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.986389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:46:01.917658Z","caller":"traceutil/trace.go:171","msg":"trace[705138272] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"220.527909ms","start":"2026-08-16T13:46:01.697115Z","end":"2026-08-16T13:46:01.917643Z","steps":["trace[705138272] 'process raft request' (duration: 106.383385ms)","trace[705138272] 'compare' (duration: 113.786534ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:01.920704Z","caller":"traceutil/trace.go:171","msg":"trace[2045743956] transaction","detail":"{read_only:false; response_revision:26039; number_of_response:1; }","duration":"223.132723ms","start":"2026-08-16T13:46:01.697558Z","end":"2026-08-16T13:46:01.920691Z","steps":["trace[2045743956] 'process raft request' (duration: 223.082281ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:01.920713Z","caller":"traceutil/trace.go:171","msg":"trace[1478492597] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"223.281817ms","start":"2026-08-16T13:46:01.697396Z","end":"2026-08-16T13:46:01.920678Z","steps":["trace[1478492597] 'process raft request' (duration: 223.216595ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:01.920704Z","caller":"traceutil/trace.go:171","msg":"trace[1205178694] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"223.271396ms","start":"2026-08-16T13:46:01.6974Z","end":"2026-08-16T13:46:01.920671Z","steps":["trace[1205178694] 'process raft request' (duration: 223.118662ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:01.920909Z","caller":"traceutil/trace.go:171","msg":"trace[1464531514] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"125.354739ms","start":"2026-08-16T13:46:01.795545Z","end":"2026-08-16T13:46:01.9209Z","steps":["trace[1464531514] 'process raft request' (duration: 125.120333ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:01.92103Z","caller":"traceutil/trace.go:171","msg":"trace[695993531] linearizableReadLoop","detail":"{readStateIndex:26903; appliedIndex:26899; }","duration":"128.18211ms","start":"2026-08-16T13:46:01.792839Z","end":"2026-08-16T13:46:01.921021Z","steps":["trace[695993531] 'read index received' (duration: 10.665971ms)","trace[695993531] 'applied index is now lower than readState.Index' (duration: 117.515369ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:01.921086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.246591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:46:01.921087Z","caller":"traceutil/trace.go:171","msg":"trace[1731183458] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"121.626174ms","start":"2026-08-16T13:46:01.799452Z","end":"2026-08-16T13:46:01.921078Z","steps":["trace[1731183458] 'process raft request' (duration: 121.403118ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:01.921106Z","caller":"traceutil/trace.go:171","msg":"trace[523950203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26041; }","duration":"128.312443ms","start":"2026-08-16T13:46:01.792788Z","end":"2026-08-16T13:46:01.9211Z","steps":["trace[523950203] 'agreement among raft nodes before linearized reading' (duration: 128.261962ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:02.169914Z","caller":"traceutil/trace.go:171","msg":"trace[712303969] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"219.620454ms","start":"2026-08-16T13:46:01.950264Z","end":"2026-08-16T13:46:02.169885Z","steps":["trace[712303969] 'process raft request' (duration: 176.525129ms)","trace[712303969] 'compare' (duration: 42.533489ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:02.234012Z","caller":"traceutil/trace.go:171","msg":"trace[1453228517] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"104.621087ms","start":"2026-08-16T13:46:02.12937Z","end":"2026-08-16T13:46:02.233991Z","steps":["trace[1453228517] 'process raft request' (duration: 104.494983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:02.549358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.334166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:46:02.549549Z","caller":"traceutil/trace.go:171","msg":"trace[506254138] linearizableReadLoop","detail":"{readStateIndex:26910; appliedIndex:26908; }","duration":"295.435717ms","start":"2026-08-16T13:46:02.254095Z","end":"2026-08-16T13:46:02.549531Z","steps":["trace[506254138] 'read index received' (duration: 43.73341ms)","trace[506254138] 'applied index is now lower than readState.Index' (duration: 251.701427ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:02.549608Z","caller":"traceutil/trace.go:171","msg":"trace[1654216317] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"417.33313ms","start":"2026-08-16T13:46:02.132238Z","end":"2026-08-16T13:46:02.549571Z","steps":["trace[1654216317] 'process raft request' (duration: 165.703886ms)","trace[1654216317] 'compare' (duration: 250.740459ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:02.549639Z","caller":"traceutil/trace.go:171","msg":"trace[1824982263] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"415.559059ms","start":"2026-08-16T13:46:02.13406Z","end":"2026-08-16T13:46:02.549619Z","steps":["trace[1824982263] 'process raft request' (duration: 415.399275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:02.549744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:46:02.134049Z","time spent":"415.648753ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:46:02.549767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.665244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-16T13:46:02.5498Z","caller":"traceutil/trace.go:171","msg":"trace[73390521] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26046; }","duration":"295.761826ms","start":"2026-08-16T13:46:02.254027Z","end":"2026-08-16T13:46:02.549789Z","steps":["trace[73390521] 'agreement among raft nodes before linearized reading' (duration: 295.587011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:02.549956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.821043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:46:02.550019Z","caller":"traceutil/trace.go:171","msg":"trace[1389218692] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:26046; }","duration":"260.875016ms","start":"2026-08-16T13:46:02.289112Z","end":"2026-08-16T13:46:02.549987Z","steps":["trace[1389218692] 'agreement among raft nodes before linearized reading' (duration: 260.792632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:02.5504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:46:02.132217Z","time spent":"418.009259ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:46:02.925379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.196086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:46:02.925495Z","caller":"traceutil/trace.go:171","msg":"trace[237536501] linearizableReadLoop","detail":"{readStateIndex:26911; appliedIndex:26910; }","duration":"111.972542ms","start":"2026-08-16T13:46:02.813504Z","end":"2026-08-16T13:46:02.925477Z","steps":["trace[237536501] 'read index received' (duration: 44.591µs)","trace[237536501] 'applied index is now lower than readState.Index' (duration: 111.926221ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:02.925684Z","caller":"traceutil/trace.go:171","msg":"trace[1716445413] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"223.000609ms","start":"2026-08-16T13:46:02.702637Z","end":"2026-08-16T13:46:02.925638Z","steps":["trace[1716445413] 'process raft request' (duration: 65.427912ms)","trace[1716445413] 'compare' (duration: 156.754903ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:02.925951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.441675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277957"} {"level":"info","ts":"2026-08-16T13:46:02.925996Z","caller":"traceutil/trace.go:171","msg":"trace[344852080] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26047; }","duration":"112.512758ms","start":"2026-08-16T13:46:02.813472Z","end":"2026-08-16T13:46:02.925985Z","steps":["trace[344852080] 'agreement among raft nodes before linearized reading' (duration: 112.094446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:03.204215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.511217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:46:03.204313Z","caller":"traceutil/trace.go:171","msg":"trace[31287122] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26047; }","duration":"134.650971ms","start":"2026-08-16T13:46:03.069637Z","end":"2026-08-16T13:46:03.204288Z","steps":["trace[31287122] 'count revisions from in-memory index tree' (duration: 134.445465ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:04.086299Z","caller":"traceutil/trace.go:171","msg":"trace[714563925] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"157.193716ms","start":"2026-08-16T13:46:03.929078Z","end":"2026-08-16T13:46:04.086271Z","steps":["trace[714563925] 'process raft request' (duration: 150.716254ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:04.086615Z","caller":"traceutil/trace.go:171","msg":"trace[986426340] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"157.319969ms","start":"2026-08-16T13:46:03.929273Z","end":"2026-08-16T13:46:04.086593Z","steps":["trace[986426340] 'process raft request' (duration: 156.945579ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:04.086697Z","caller":"traceutil/trace.go:171","msg":"trace[1986739183] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"153.911514ms","start":"2026-08-16T13:46:03.932771Z","end":"2026-08-16T13:46:04.086683Z","steps":["trace[1986739183] 'process raft request' (duration: 153.636126ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:04.086747Z","caller":"traceutil/trace.go:171","msg":"trace[782432192] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"153.263575ms","start":"2026-08-16T13:46:03.93345Z","end":"2026-08-16T13:46:04.086714Z","steps":["trace[782432192] 'process raft request' (duration: 153.177663ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:04.086875Z","caller":"traceutil/trace.go:171","msg":"trace[1627954788] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"154.006976ms","start":"2026-08-16T13:46:03.932858Z","end":"2026-08-16T13:46:04.086865Z","steps":["trace[1627954788] 'process raft request' (duration: 153.705427ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:14.814225Z","caller":"traceutil/trace.go:171","msg":"trace[218774028] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"222.35487ms","start":"2026-08-16T13:46:14.591839Z","end":"2026-08-16T13:46:14.814194Z","steps":["trace[218774028] 'process raft request' (duration: 127.344024ms)","trace[218774028] 'compare' (duration: 94.825621ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:14.963086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.906913ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-16T13:46:14.963242Z","caller":"traceutil/trace.go:171","msg":"trace[1501292943] linearizableReadLoop","detail":"{readStateIndex:27000; appliedIndex:26998; }","duration":"170.718816ms","start":"2026-08-16T13:46:14.792496Z","end":"2026-08-16T13:46:14.963215Z","steps":["trace[1501292943] 'read index received' (duration: 20.137706ms)","trace[1501292943] 'applied index is now lower than readState.Index' (duration: 150.57913ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:14.963353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.84801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:46:14.963389Z","caller":"traceutil/trace.go:171","msg":"trace[1217011458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26133; }","duration":"170.921363ms","start":"2026-08-16T13:46:14.792457Z","end":"2026-08-16T13:46:14.963379Z","steps":["trace[1217011458] 'agreement among raft nodes before linearized reading' (duration: 170.82118ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:25.961093Z","caller":"traceutil/trace.go:171","msg":"trace[1983178373] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"115.265185ms","start":"2026-08-16T13:46:25.845798Z","end":"2026-08-16T13:46:25.961063Z","steps":["trace[1983178373] 'process raft request' (duration: 115.09804ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:26.1468Z","caller":"traceutil/trace.go:171","msg":"trace[287067425] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"269.035304ms","start":"2026-08-16T13:46:25.877735Z","end":"2026-08-16T13:46:26.146771Z","steps":["trace[287067425] 'process raft request' (duration: 192.800618ms)","trace[287067425] 'compare' (duration: 76.049391ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:26.147029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.960266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-16T13:46:26.147088Z","caller":"traceutil/trace.go:171","msg":"trace[1683886880] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26220; }","duration":"105.075709ms","start":"2026-08-16T13:46:26.041999Z","end":"2026-08-16T13:46:26.147075Z","steps":["trace[1683886880] 'agreement among raft nodes before linearized reading' (duration: 104.839442ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:26.146792Z","caller":"traceutil/trace.go:171","msg":"trace[895108768] linearizableReadLoop","detail":"{readStateIndex:27089; appliedIndex:27088; }","duration":"104.698979ms","start":"2026-08-16T13:46:26.042042Z","end":"2026-08-16T13:46:26.146741Z","steps":["trace[895108768] 'read index received' (duration: 28.427992ms)","trace[895108768] 'applied index is now lower than readState.Index' (duration: 76.269576ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:26.32497Z","caller":"traceutil/trace.go:171","msg":"trace[2054549116] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"143.066258ms","start":"2026-08-16T13:46:26.181877Z","end":"2026-08-16T13:46:26.324943Z","steps":["trace[2054549116] 'process raft request' (duration: 142.904974ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:26.326065Z","caller":"traceutil/trace.go:171","msg":"trace[54234150] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"142.77904ms","start":"2026-08-16T13:46:26.183264Z","end":"2026-08-16T13:46:26.326043Z","steps":["trace[54234150] 'process raft request' (duration: 142.607835ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:26.326317Z","caller":"traceutil/trace.go:171","msg":"trace[13246046] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"138.327376ms","start":"2026-08-16T13:46:26.187969Z","end":"2026-08-16T13:46:26.326296Z","steps":["trace[13246046] 'process raft request' (duration: 138.029848ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:26.326361Z","caller":"traceutil/trace.go:171","msg":"trace[2145665003] linearizableReadLoop","detail":"{readStateIndex:27093; appliedIndex:27090; }","duration":"134.817796ms","start":"2026-08-16T13:46:26.191523Z","end":"2026-08-16T13:46:26.326341Z","steps":["trace[2145665003] 'read index received' (duration: 133.350094ms)","trace[2145665003] 'applied index is now lower than readState.Index' (duration: 1.466652ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:26.326614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.344108ms","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-08-16T13:46:26.326669Z","caller":"traceutil/trace.go:171","msg":"trace[2129869890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26226; }","duration":"131.41327ms","start":"2026-08-16T13:46:26.195242Z","end":"2026-08-16T13:46:26.326656Z","steps":["trace[2129869890] 'agreement among raft nodes before linearized reading' (duration: 131.274676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:26.326617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.077773ms","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-08-16T13:46:26.326763Z","caller":"traceutil/trace.go:171","msg":"trace[613853758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26226; }","duration":"135.275788ms","start":"2026-08-16T13:46:26.191477Z","end":"2026-08-16T13:46:26.326753Z","steps":["trace[613853758] 'agreement among raft nodes before linearized reading' (duration: 135.002121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:26.326789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.880588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-16T13:46:26.326825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.132835ms","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-08-16T13:46:26.326894Z","caller":"traceutil/trace.go:171","msg":"trace[1221154418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26226; }","duration":"129.209328ms","start":"2026-08-16T13:46:26.197663Z","end":"2026-08-16T13:46:26.326872Z","steps":["trace[1221154418] 'agreement among raft nodes before linearized reading' (duration: 129.066314ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:26.326915Z","caller":"traceutil/trace.go:171","msg":"trace[708320885] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26226; }","duration":"113.086563ms","start":"2026-08-16T13:46:26.213758Z","end":"2026-08-16T13:46:26.326845Z","steps":["trace[708320885] 'agreement among raft nodes before linearized reading' (duration: 112.803495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:26.754741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.918038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:46:26.755082Z","caller":"traceutil/trace.go:171","msg":"trace[2099165769] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"424.261566ms","start":"2026-08-16T13:46:26.330785Z","end":"2026-08-16T13:46:26.755046Z","steps":["trace[2099165769] 'process raft request' (duration: 239.952527ms)","trace[2099165769] 'compare' (duration: 183.799755ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:26.755153Z","caller":"traceutil/trace.go:171","msg":"trace[1889437172] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"422.007982ms","start":"2026-08-16T13:46:26.333126Z","end":"2026-08-16T13:46:26.755134Z","steps":["trace[1889437172] 'process raft request' (duration: 421.765315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:26.755205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:46:26.330772Z","time spent":"424.366569ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:46:26.755239Z","caller":"traceutil/trace.go:171","msg":"trace[1854495069] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"423.581346ms","start":"2026-08-16T13:46:26.331625Z","end":"2026-08-16T13:46:26.755206Z","steps":["trace[1854495069] 'process raft request' (duration: 423.221376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:26.755307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:46:26.333117Z","time spent":"422.117966ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T13:46:26.755073Z","caller":"traceutil/trace.go:171","msg":"trace[1914245200] linearizableReadLoop","detail":"{readStateIndex:27096; appliedIndex:27095; }","duration":"424.190714ms","start":"2026-08-16T13:46:26.330838Z","end":"2026-08-16T13:46:26.755028Z","steps":["trace[1914245200] 'read index received' (duration: 239.923396ms)","trace[1914245200] 'applied index is now lower than readState.Index' (duration: 184.264028ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:26.755376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:46:26.331602Z","time spent":"423.701209ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T13:46:26.756069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.211892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-16T13:46:26.756134Z","caller":"traceutil/trace.go:171","msg":"trace[1094310811] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26229; }","duration":"425.284954ms","start":"2026-08-16T13:46:26.330831Z","end":"2026-08-16T13:46:26.756116Z","steps":["trace[1094310811] 'agreement among raft nodes before linearized reading' (duration: 424.298157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:26.756308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T13:46:26.330789Z","time spent":"425.377666ms","remote":"127.0.0.1:34564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-16T13:46:26.757766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.390744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-16T13:46:26.757829Z","caller":"traceutil/trace.go:171","msg":"trace[544151876] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26230; }","duration":"156.471306ms","start":"2026-08-16T13:46:26.601332Z","end":"2026-08-16T13:46:26.757804Z","steps":["trace[544151876] 'agreement among raft nodes before linearized reading' (duration: 156.235289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T13:46:27.049397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.350183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-16T13:46:27.04954Z","caller":"traceutil/trace.go:171","msg":"trace[496978501] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"190.146403ms","start":"2026-08-16T13:46:26.85938Z","end":"2026-08-16T13:46:27.049526Z","steps":["trace[496978501] 'process raft request' (duration: 190.101862ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:46:27.049578Z","caller":"traceutil/trace.go:171","msg":"trace[1579785280] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"284.251013ms","start":"2026-08-16T13:46:26.765294Z","end":"2026-08-16T13:46:27.049545Z","steps":["trace[1579785280] 'process raft request' (duration: 133.635322ms)","trace[1579785280] 'compare' (duration: 150.270201ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T13:46:27.049655Z","caller":"traceutil/trace.go:171","msg":"trace[1997663883] linearizableReadLoop","detail":"{readStateIndex:27100; appliedIndex:27099; }","duration":"233.155125ms","start":"2026-08-16T13:46:26.816492Z","end":"2026-08-16T13:46:27.049647Z","steps":["trace[1997663883] 'read index received' (duration: 82.578385ms)","trace[1997663883] 'applied index is now lower than readState.Index' (duration: 150.57605ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T13:46:27.049702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.235666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-16T13:46:27.049727Z","caller":"traceutil/trace.go:171","msg":"trace[46249148] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26232; }","duration":"233.296909ms","start":"2026-08-16T13:46:26.816421Z","end":"2026-08-16T13:46:27.049718Z","steps":["trace[46249148] 'agreement among raft nodes before linearized reading' (duration: 233.256377ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T13:50:06.895522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25515} {"level":"info","ts":"2026-08-16T13:50:07.056473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25515,"took":"155.847028ms","hash":1325199489,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":36315136,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-16T13:50:07.056541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1325199489,"revision":25515,"compact-revision":22117} {"level":"info","ts":"2026-08-16T13:51:21.504623Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T13:51:21.511114Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-16T13:51:21.511219Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-16T13:55:06.905634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28479} {"level":"info","ts":"2026-08-16T13:55:07.058995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28479,"took":"148.859926ms","hash":262753122,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":31481856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-16T13:55:07.059054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":262753122,"revision":28479,"compact-revision":25515} {"level":"info","ts":"2026-08-16T14:00:06.912035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30727} {"level":"info","ts":"2026-08-16T14:00:07.046427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30727,"took":"126.858548ms","hash":848208311,"current-db-size-bytes":58695680,"current-db-size":"59 MB","current-db-size-in-use-bytes":30646272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-16T14:00:07.04651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":848208311,"revision":30727,"compact-revision":28479} {"level":"info","ts":"2026-08-16T14:05:06.925605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33019} {"level":"info","ts":"2026-08-16T14:05:07.094488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33019,"took":"162.46701ms","hash":3791398581,"current-db-size-bytes":59740160,"current-db-size":"60 MB","current-db-size-in-use-bytes":31678464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-16T14:05:07.09475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3791398581,"revision":33019,"compact-revision":30727} {"level":"info","ts":"2026-08-16T14:10:06.936456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35299} {"level":"info","ts":"2026-08-16T14:10:07.088962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35299,"took":"147.621883ms","hash":2034390431,"current-db-size-bytes":60211200,"current-db-size":"60 MB","current-db-size-in-use-bytes":32391168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-16T14:10:07.089339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2034390431,"revision":35299,"compact-revision":33019} {"level":"info","ts":"2026-08-16T14:12:40.293695Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39582465ceaa2f67","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-16T14:12:40.299179Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-16T14:12:40.299268Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-16T14:15:06.94284Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37602} {"level":"info","ts":"2026-08-16T14:15:07.07079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37602,"took":"123.761666ms","hash":739394271,"current-db-size-bytes":61505536,"current-db-size":"62 MB","current-db-size-in-use-bytes":32178176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-16T14:15:07.070857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":739394271,"revision":37602,"compact-revision":35299}