{"level":"warn","ts":"2026-04-20T11:51:39.003331Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T11:51:39.003411Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-04-20T11:51:39.00347Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T11:51:39.003477Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-20T11:51:39.003492Z","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-04-20T11:51:39.004073Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-20T11:51:39.004138Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-04-20T11:51:39.009637Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.306728ms"} {"level":"info","ts":"2026-04-20T11:51:39.020396Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-20T11:51:39.020509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T11:51:39.020532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-20T11:51:39.020544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T11:51:39.02057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-20T11:51:39.02061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-20T11:51:39.033074Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T11:51:39.034763Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T11:51:39.036479Z","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-04-20T11:51:39.039355Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T11:51:39.039866Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T11:51:39.039922Z","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-04-20T11:51:39.04004Z","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-04-20T11:51:39.040075Z","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-04-20T11:51:39.041805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-20T11:51:39.041989Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-20T11:51:39.043634Z","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-04-20T11:51:39.043705Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-20T11:51:39.043776Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-20T11:51:39.043974Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T11:51:39.044033Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T11:51:39.420832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T11:51:39.420894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T11:51:39.42094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-20T11:51:39.42096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-20T11:51:39.420971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-20T11:51:39.420988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-20T11:51:39.421002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-20T11:51:39.422047Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:51:39.422838Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:51:39.422833Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T11:51:39.422905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:51:39.423181Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:51:39.423264Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:51:39.423301Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:51:39.423357Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T11:51:39.423401Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T11:51:39.426141Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-20T11:51:39.426341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-20T11:52:05.436623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.930396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T11:52:05.436762Z","caller":"traceutil/trace.go:171","msg":"trace[1345625058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"153.135442ms","start":"2026-04-20T11:52:05.283604Z","end":"2026-04-20T11:52:05.43674Z","steps":["trace[1345625058] 'range keys from in-memory index tree' (duration: 152.717211ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.951283Z","caller":"traceutil/trace.go:171","msg":"trace[2036607854] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"170.686275ms","start":"2026-04-20T11:52:08.780575Z","end":"2026-04-20T11:52:08.951261Z","steps":["trace[2036607854] 'process raft request' (duration: 170.575532ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.959384Z","caller":"traceutil/trace.go:171","msg":"trace[597653106] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"170.378037ms","start":"2026-04-20T11:52:08.788968Z","end":"2026-04-20T11:52:08.959346Z","steps":["trace[597653106] 'process raft request' (duration: 170.168311ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.959469Z","caller":"traceutil/trace.go:171","msg":"trace[1759543790] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"170.361716ms","start":"2026-04-20T11:52:08.789082Z","end":"2026-04-20T11:52:08.959444Z","steps":["trace[1759543790] 'process raft request' (duration: 170.192072ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.959532Z","caller":"traceutil/trace.go:171","msg":"trace[428758014] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"155.880206ms","start":"2026-04-20T11:52:08.803602Z","end":"2026-04-20T11:52:08.959482Z","steps":["trace[428758014] 'process raft request' (duration: 155.723492ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.959564Z","caller":"traceutil/trace.go:171","msg":"trace[1570468247] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"154.622393ms","start":"2026-04-20T11:52:08.804917Z","end":"2026-04-20T11:52:08.959539Z","steps":["trace[1570468247] 'process raft request' (duration: 154.50402ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.95965Z","caller":"traceutil/trace.go:171","msg":"trace[1032501836] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"152.562547ms","start":"2026-04-20T11:52:08.807069Z","end":"2026-04-20T11:52:08.959632Z","steps":["trace[1032501836] 'process raft request' (duration: 152.443024ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.959628Z","caller":"traceutil/trace.go:171","msg":"trace[1874534194] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"153.155163ms","start":"2026-04-20T11:52:08.806423Z","end":"2026-04-20T11:52:08.959578Z","steps":["trace[1874534194] 'process raft request' (duration: 153.04062ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.959739Z","caller":"traceutil/trace.go:171","msg":"trace[1944487847] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"147.600153ms","start":"2026-04-20T11:52:08.812132Z","end":"2026-04-20T11:52:08.959733Z","steps":["trace[1944487847] 'process raft request' (duration: 147.463449ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.959728Z","caller":"traceutil/trace.go:171","msg":"trace[2143338629] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"152.077953ms","start":"2026-04-20T11:52:08.807634Z","end":"2026-04-20T11:52:08.959711Z","steps":["trace[2143338629] 'process raft request' (duration: 151.917529ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.959803Z","caller":"traceutil/trace.go:171","msg":"trace[1165178132] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"143.228905ms","start":"2026-04-20T11:52:08.816561Z","end":"2026-04-20T11:52:08.95979Z","steps":["trace[1165178132] 'process raft request' (duration: 143.089782ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:08.95985Z","caller":"traceutil/trace.go:171","msg":"trace[1942968367] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"123.898305ms","start":"2026-04-20T11:52:08.835938Z","end":"2026-04-20T11:52:08.959836Z","steps":["trace[1942968367] 'process raft request' (duration: 123.768321ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:10.325255Z","caller":"traceutil/trace.go:171","msg":"trace[433542987] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"274.934802ms","start":"2026-04-20T11:52:10.050289Z","end":"2026-04-20T11:52:10.325224Z","steps":["trace[433542987] 'read index received' (duration: 274.692526ms)","trace[433542987] 'applied index is now lower than readState.Index' (duration: 241.216µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:52:10.325308Z","caller":"traceutil/trace.go:171","msg":"trace[839168488] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"294.330225ms","start":"2026-04-20T11:52:10.030943Z","end":"2026-04-20T11:52:10.325273Z","steps":["trace[839168488] 'process raft request' (duration: 294.009976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:10.325749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.426346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-20T11:52:10.325893Z","caller":"traceutil/trace.go:171","msg":"trace[1734830123] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:542; }","duration":"275.56379ms","start":"2026-04-20T11:52:10.050281Z","end":"2026-04-20T11:52:10.325844Z","steps":["trace[1734830123] 'agreement among raft nodes before linearized reading' (duration: 275.052486ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:10.332819Z","caller":"traceutil/trace.go:171","msg":"trace[1412290099] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"215.229126ms","start":"2026-04-20T11:52:10.117574Z","end":"2026-04-20T11:52:10.332803Z","steps":["trace[1412290099] 'process raft request' (duration: 214.63421ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:15.300255Z","caller":"traceutil/trace.go:171","msg":"trace[885515590] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"213.52682ms","start":"2026-04-20T11:52:15.086691Z","end":"2026-04-20T11:52:15.300218Z","steps":["trace[885515590] 'process raft request' (duration: 212.917683ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:15.830299Z","caller":"traceutil/trace.go:171","msg":"trace[984981847] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"476.320166ms","start":"2026-04-20T11:52:15.353954Z","end":"2026-04-20T11:52:15.830274Z","steps":["trace[984981847] 'process raft request' (duration: 476.195783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:15.830879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:52:15.353911Z","time spent":"476.541452ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:52:15.851095Z","caller":"traceutil/trace.go:171","msg":"trace[1401552352] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"336.348577ms","start":"2026-04-20T11:52:15.514722Z","end":"2026-04-20T11:52:15.851071Z","steps":["trace[1401552352] 'read index received' (duration: 315.474225ms)","trace[1401552352] 'applied index is now lower than readState.Index' (duration: 20.873112ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:52:15.851168Z","caller":"traceutil/trace.go:171","msg":"trace[491721494] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"351.217087ms","start":"2026-04-20T11:52:15.499931Z","end":"2026-04-20T11:52:15.851148Z","steps":["trace[491721494] 'process raft request' (duration: 350.873048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:15.851382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.529011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T11:52:15.851433Z","caller":"traceutil/trace.go:171","msg":"trace[334589398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"336.744407ms","start":"2026-04-20T11:52:15.514675Z","end":"2026-04-20T11:52:15.851419Z","steps":["trace[334589398] 'agreement among raft nodes before linearized reading' (duration: 336.525921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:15.851469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:52:15.514654Z","time spent":"336.805079ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T11:52:15.851504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:52:15.499901Z","time spent":"351.31441ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:52:15.851517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.954666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sldkp\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-20T11:52:15.851635Z","caller":"traceutil/trace.go:171","msg":"trace[546731740] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sldkp; range_end:; response_count:1; response_revision:576; }","duration":"180.12343ms","start":"2026-04-20T11:52:15.671479Z","end":"2026-04-20T11:52:15.851603Z","steps":["trace[546731740] 'agreement among raft nodes before linearized reading' (duration: 179.806762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:32.108325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.623663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:52:32.108448Z","caller":"traceutil/trace.go:171","msg":"trace[1868762005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"130.827008ms","start":"2026-04-20T11:52:31.977597Z","end":"2026-04-20T11:52:32.108424Z","steps":["trace[1868762005] 'range keys from in-memory index tree' (duration: 130.497569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:32.108693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.977257ms","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-04-20T11:52:32.108821Z","caller":"traceutil/trace.go:171","msg":"trace[1754073666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"152.11514ms","start":"2026-04-20T11:52:31.956677Z","end":"2026-04-20T11:52:32.108793Z","steps":["trace[1754073666] 'range keys from in-memory index tree' (duration: 151.846774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:32.108799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.165623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T11:52:32.108912Z","caller":"traceutil/trace.go:171","msg":"trace[1666838683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"159.308828ms","start":"2026-04-20T11:52:31.949573Z","end":"2026-04-20T11:52:32.108882Z","steps":["trace[1666838683] 'range keys from in-memory index tree' (duration: 158.940188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:32.108689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.87435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7xrxd\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-04-20T11:52:32.109056Z","caller":"traceutil/trace.go:171","msg":"trace[901085766] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7xrxd; range_end:; response_count:1; response_revision:733; }","duration":"331.323453ms","start":"2026-04-20T11:52:31.777706Z","end":"2026-04-20T11:52:32.10903Z","steps":["trace[901085766] 'range keys from in-memory index tree' (duration: 330.556292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:32.109107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:52:31.777679Z","time spent":"331.412355ms","remote":"127.0.0.1:41280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7xrxd\" "} {"level":"warn","ts":"2026-04-20T11:52:32.108689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.154462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T11:52:32.109236Z","caller":"traceutil/trace.go:171","msg":"trace[918660562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"167.758299ms","start":"2026-04-20T11:52:31.941468Z","end":"2026-04-20T11:52:32.109226Z","steps":["trace[918660562] 'range keys from in-memory index tree' (duration: 166.975027ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:19.459661Z","caller":"traceutil/trace.go:171","msg":"trace[989596013] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"302.094474ms","start":"2026-04-20T11:53:19.157547Z","end":"2026-04-20T11:53:19.459642Z","steps":["trace[989596013] 'process raft request' (duration: 301.94348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:19.459798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:19.157527Z","time spent":"302.200796ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:53:42.75437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.423849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T11:53:42.754432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.640181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T11:53:42.75437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.074141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T11:53:42.754499Z","caller":"traceutil/trace.go:171","msg":"trace[585795357] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1788; }","duration":"317.284837ms","start":"2026-04-20T11:53:42.437205Z","end":"2026-04-20T11:53:42.75449Z","steps":["trace[585795357] 'range keys from in-memory index tree' (duration: 316.809504ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:42.754497Z","caller":"traceutil/trace.go:171","msg":"trace[467558563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1788; }","duration":"207.738614ms","start":"2026-04-20T11:53:42.546741Z","end":"2026-04-20T11:53:42.75448Z","steps":["trace[467558563] 'range keys from in-memory index tree' (duration: 207.526998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:42.75453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:42.437171Z","time spent":"317.350429ms","remote":"127.0.0.1:49618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-20T11:53:42.754453Z","caller":"traceutil/trace.go:171","msg":"trace[1926684356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1788; }","duration":"233.564973ms","start":"2026-04-20T11:53:42.520872Z","end":"2026-04-20T11:53:42.754437Z","steps":["trace[1926684356] 'range keys from in-memory index tree' (duration: 233.173172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:42.754698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.846306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T11:53:42.754705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.161288ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:53:42.754773Z","caller":"traceutil/trace.go:171","msg":"trace[1687714741] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1788; }","duration":"204.23813ms","start":"2026-04-20T11:53:42.550525Z","end":"2026-04-20T11:53:42.754763Z","steps":["trace[1687714741] 'range keys from in-memory index tree' (duration: 204.148758ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:42.754772Z","caller":"traceutil/trace.go:171","msg":"trace[517381439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1788; }","duration":"153.950359ms","start":"2026-04-20T11:53:42.600802Z","end":"2026-04-20T11:53:42.754752Z","steps":["trace[517381439] 'range keys from in-memory index tree' (duration: 153.63739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:42.754793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.171481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb3a31d7-32c5-4ab0-af77-b56d6a458a0c\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-04-20T11:53:42.755018Z","caller":"traceutil/trace.go:171","msg":"trace[1988742373] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bb3a31d7-32c5-4ab0-af77-b56d6a458a0c; range_end:; response_count:1; response_revision:1788; }","duration":"366.322565ms","start":"2026-04-20T11:53:42.388527Z","end":"2026-04-20T11:53:42.754849Z","steps":["trace[1988742373] 'range keys from in-memory index tree' (duration: 365.688729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:42.75508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:42.388487Z","time spent":"366.557242ms","remote":"127.0.0.1:41280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb3a31d7-32c5-4ab0-af77-b56d6a458a0c\" "} {"level":"info","ts":"2026-04-20T11:54:27.798647Z","caller":"traceutil/trace.go:171","msg":"trace[101216296] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"115.259236ms","start":"2026-04-20T11:54:27.683357Z","end":"2026-04-20T11:54:27.798616Z","steps":["trace[101216296] 'process raft request' (duration: 114.956528ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:54:28.28497Z","caller":"traceutil/trace.go:171","msg":"trace[464259067] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"355.99742ms","start":"2026-04-20T11:54:27.92894Z","end":"2026-04-20T11:54:28.284937Z","steps":["trace[464259067] 'process raft request' (duration: 317.076711ms)","trace[464259067] 'compare' (duration: 38.784955ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:54:28.285146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:54:27.928923Z","time spent":"356.137274ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:54:28.289268Z","caller":"traceutil/trace.go:171","msg":"trace[1323176173] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"344.147314ms","start":"2026-04-20T11:54:27.945016Z","end":"2026-04-20T11:54:28.289164Z","steps":["trace[1323176173] 'process raft request' (duration: 343.913878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:54:28.289494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:54:27.944994Z","time spent":"344.384171ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:54:28.550504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.003974ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T11:54:28.550615Z","caller":"traceutil/trace.go:171","msg":"trace[797402919] linearizableReadLoop","detail":"{readStateIndex:2168; appliedIndex:2166; }","duration":"489.740718ms","start":"2026-04-20T11:54:28.060857Z","end":"2026-04-20T11:54:28.550598Z","steps":["trace[797402919] 'read index received' (duration: 185.155901ms)","trace[797402919] 'applied index is now lower than readState.Index' (duration: 304.583167ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:54:28.550822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.514998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T11:54:28.550866Z","caller":"traceutil/trace.go:171","msg":"trace[1675821417] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2087; }","duration":"249.590199ms","start":"2026-04-20T11:54:28.301263Z","end":"2026-04-20T11:54:28.550853Z","steps":["trace[1675821417] 'agreement among raft nodes before linearized reading' (duration: 249.428765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:54:28.550884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.008525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-20T11:54:28.550978Z","caller":"traceutil/trace.go:171","msg":"trace[1707915243] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2087; }","duration":"490.149629ms","start":"2026-04-20T11:54:28.060804Z","end":"2026-04-20T11:54:28.550954Z","steps":["trace[1707915243] 'agreement among raft nodes before linearized reading' (duration: 489.850711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:54:28.551024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:54:28.060784Z","time spent":"490.231902ms","remote":"127.0.0.1:49618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-20T11:55:20.388725Z","caller":"traceutil/trace.go:171","msg":"trace[554370532] linearizableReadLoop","detail":"{readStateIndex:2636; appliedIndex:2635; }","duration":"177.534978ms","start":"2026-04-20T11:55:20.211166Z","end":"2026-04-20T11:55:20.388701Z","steps":["trace[554370532] 'read index received' (duration: 172.026081ms)","trace[554370532] 'applied index is now lower than readState.Index' (duration: 5.507987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:20.388853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.679632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:55:20.388886Z","caller":"traceutil/trace.go:171","msg":"trace[848776200] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2536; }","duration":"177.781574ms","start":"2026-04-20T11:55:20.211094Z","end":"2026-04-20T11:55:20.388876Z","steps":["trace[848776200] 'agreement among raft nodes before linearized reading' (duration: 177.703872ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:20.389216Z","caller":"traceutil/trace.go:171","msg":"trace[1159175277] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"331.335232ms","start":"2026-04-20T11:55:20.05784Z","end":"2026-04-20T11:55:20.389175Z","steps":["trace[1159175277] 'process raft request' (duration: 325.365083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:20.389375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:55:20.057814Z","time spent":"331.475296ms","remote":"127.0.0.1:41358","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-04-20T11:55:56.208623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.622895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:55:56.20874Z","caller":"traceutil/trace.go:171","msg":"trace[388043443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2756; }","duration":"224.799009ms","start":"2026-04-20T11:55:55.98392Z","end":"2026-04-20T11:55:56.208719Z","steps":["trace[388043443] 'range keys from in-memory index tree' (duration: 224.425969ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:56.233697Z","caller":"traceutil/trace.go:171","msg":"trace[417571867] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"123.761436ms","start":"2026-04-20T11:55:56.109883Z","end":"2026-04-20T11:55:56.233644Z","steps":["trace[417571867] 'process raft request' (duration: 123.493359ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:32.018547Z","caller":"traceutil/trace.go:171","msg":"trace[2105623212] linearizableReadLoop","detail":"{readStateIndex:3922; appliedIndex:3921; }","duration":"129.182375ms","start":"2026-04-20T11:58:31.889341Z","end":"2026-04-20T11:58:32.018523Z","steps":["trace[2105623212] 'read index received' (duration: 128.99429ms)","trace[2105623212] 'applied index is now lower than readState.Index' (duration: 187.215µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:58:32.018906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.559106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:58:32.018959Z","caller":"traceutil/trace.go:171","msg":"trace[568011648] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3776; }","duration":"129.669479ms","start":"2026-04-20T11:58:31.889275Z","end":"2026-04-20T11:58:32.018945Z","steps":["trace[568011648] 'agreement among raft nodes before linearized reading' (duration: 129.582346ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:32.018952Z","caller":"traceutil/trace.go:171","msg":"trace[620085021] transaction","detail":"{read_only:false; response_revision:3776; number_of_response:1; }","duration":"177.958611ms","start":"2026-04-20T11:58:31.840957Z","end":"2026-04-20T11:58:32.018916Z","steps":["trace[620085021] 'process raft request' (duration: 177.438727ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:51.480681Z","caller":"traceutil/trace.go:171","msg":"trace[1043989633] linearizableReadLoop","detail":"{readStateIndex:4987; appliedIndex:4986; }","duration":"106.928052ms","start":"2026-04-20T11:59:51.373724Z","end":"2026-04-20T11:59:51.480652Z","steps":["trace[1043989633] 'read index received' (duration: 46.866885ms)","trace[1043989633] 'applied index is now lower than readState.Index' (duration: 60.060037ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:59:51.480748Z","caller":"traceutil/trace.go:171","msg":"trace[390727026] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"107.477207ms","start":"2026-04-20T11:59:51.373219Z","end":"2026-04-20T11:59:51.480696Z","steps":["trace[390727026] 'process raft request' (duration: 47.42164ms)","trace[390727026] 'compare' (duration: 59.853752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:59:51.48095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.200699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6eccc838-b684-4cd3-85fc-40e8e0cdf948\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-20T11:59:51.481057Z","caller":"traceutil/trace.go:171","msg":"trace[854794841] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6eccc838-b684-4cd3-85fc-40e8e0cdf948; range_end:; response_count:1; response_revision:4798; }","duration":"107.352113ms","start":"2026-04-20T11:59:51.373677Z","end":"2026-04-20T11:59:51.481029Z","steps":["trace[854794841] 'agreement among raft nodes before linearized reading' (duration: 107.090186ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:54.601554Z","caller":"traceutil/trace.go:171","msg":"trace[866404336] linearizableReadLoop","detail":"{readStateIndex:5124; appliedIndex:5123; }","duration":"124.870913ms","start":"2026-04-20T11:59:54.476658Z","end":"2026-04-20T11:59:54.601529Z","steps":["trace[866404336] 'read index received' (duration: 124.660237ms)","trace[866404336] 'applied index is now lower than readState.Index' (duration: 209.076µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:59:54.601693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.019387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T11:59:54.601717Z","caller":"traceutil/trace.go:171","msg":"trace[559760893] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4932; }","duration":"125.073099ms","start":"2026-04-20T11:59:54.476637Z","end":"2026-04-20T11:59:54.60171Z","steps":["trace[559760893] 'agreement among raft nodes before linearized reading' (duration: 124.980536ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:54.602046Z","caller":"traceutil/trace.go:171","msg":"trace[446480415] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"149.276976ms","start":"2026-04-20T11:59:54.45276Z","end":"2026-04-20T11:59:54.602037Z","steps":["trace[446480415] 'process raft request' (duration: 148.583808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:36.465477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:35.984766Z","time spent":"480.697923ms","remote":"127.0.0.1:42378","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T12:00:36.465863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.288412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:00:36.465986Z","caller":"traceutil/trace.go:171","msg":"trace[658733130] linearizableReadLoop","detail":"{readStateIndex:5728; appliedIndex:5727; }","duration":"637.05992ms","start":"2026-04-20T12:00:35.828905Z","end":"2026-04-20T12:00:36.465965Z","steps":["trace[658733130] 'read index received' (duration: 217.545912ms)","trace[658733130] 'applied index is now lower than readState.Index' (duration: 419.512578ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:36.466033Z","caller":"traceutil/trace.go:171","msg":"trace[630129039] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"762.382448ms","start":"2026-04-20T12:00:35.70363Z","end":"2026-04-20T12:00:36.466012Z","steps":["trace[630129039] 'process raft request' (duration: 342.810739ms)","trace[630129039] 'compare' (duration: 418.84399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:00:36.466103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.384118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:00:36.466109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:35.703603Z","time spent":"762.472591ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:00:36.466142Z","caller":"traceutil/trace.go:171","msg":"trace[1830794737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5520; }","duration":"488.44653ms","start":"2026-04-20T12:00:35.977681Z","end":"2026-04-20T12:00:36.466128Z","steps":["trace[1830794737] 'agreement among raft nodes before linearized reading' (duration: 488.388008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:36.466173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:35.977665Z","time spent":"488.500852ms","remote":"127.0.0.1:41092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T12:00:36.466415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.507827ms","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-04-20T12:00:36.46645Z","caller":"traceutil/trace.go:171","msg":"trace[549446124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5520; }","duration":"130.599109ms","start":"2026-04-20T12:00:36.335843Z","end":"2026-04-20T12:00:36.466442Z","steps":["trace[549446124] 'agreement among raft nodes before linearized reading' (duration: 130.474786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:36.466738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.807081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8n5lh\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-04-20T12:00:36.466967Z","caller":"traceutil/trace.go:171","msg":"trace[1114970179] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8n5lh; range_end:; response_count:1; response_revision:5520; }","duration":"638.076337ms","start":"2026-04-20T12:00:35.828836Z","end":"2026-04-20T12:00:36.466912Z","steps":["trace[1114970179] 'agreement among raft nodes before linearized reading' (duration: 637.211854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:36.467118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:35.828814Z","time spent":"638.283643ms","remote":"127.0.0.1:41280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-8n5lh\" "} {"level":"info","ts":"2026-04-20T12:01:15.173384Z","caller":"traceutil/trace.go:171","msg":"trace[1834855528] transaction","detail":"{read_only:false; response_revision:5885; number_of_response:1; }","duration":"103.334219ms","start":"2026-04-20T12:01:15.070018Z","end":"2026-04-20T12:01:15.173353Z","steps":["trace[1834855528] 'process raft request' (duration: 102.96913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:27.417526Z","caller":"traceutil/trace.go:171","msg":"trace[1953725375] linearizableReadLoop","detail":"{readStateIndex:6206; appliedIndex:6205; }","duration":"152.217879ms","start":"2026-04-20T12:01:27.265268Z","end":"2026-04-20T12:01:27.417485Z","steps":["trace[1953725375] 'read index received' (duration: 152.121286ms)","trace[1953725375] 'applied index is now lower than readState.Index' (duration: 95.052µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:27.417656Z","caller":"traceutil/trace.go:171","msg":"trace[939573042] transaction","detail":"{read_only:false; response_revision:5986; number_of_response:1; }","duration":"166.07814ms","start":"2026-04-20T12:01:27.25153Z","end":"2026-04-20T12:01:27.417608Z","steps":["trace[939573042] 'process raft request' (duration: 165.833573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:27.417924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.62215ms","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-04-20T12:01:27.418042Z","caller":"traceutil/trace.go:171","msg":"trace[1489077417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5986; }","duration":"152.809085ms","start":"2026-04-20T12:01:27.265208Z","end":"2026-04-20T12:01:27.418017Z","steps":["trace[1489077417] 'agreement among raft nodes before linearized reading' (duration: 152.420114ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:39.738904Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2994} {"level":"info","ts":"2026-04-20T12:01:39.824477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2994,"took":"84.021242ms","hash":2796452773,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":33943552,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T12:01:39.824564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2796452773,"revision":2994,"compact-revision":-1} {"level":"warn","ts":"2026-04-20T12:06:25.241968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.961937ms","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-04-20T12:06:25.242069Z","caller":"traceutil/trace.go:171","msg":"trace[434882547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8199; }","duration":"296.103031ms","start":"2026-04-20T12:06:24.945946Z","end":"2026-04-20T12:06:25.242049Z","steps":["trace[434882547] 'range keys from in-memory index tree' (duration: 295.733881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:25.242097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.566846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:06:25.242181Z","caller":"traceutil/trace.go:171","msg":"trace[67201002] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8199; }","duration":"323.70531ms","start":"2026-04-20T12:06:24.918458Z","end":"2026-04-20T12:06:25.242163Z","steps":["trace[67201002] 'count revisions from in-memory index tree' (duration: 323.466484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:25.24222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:06:24.918433Z","time spent":"323.776322ms","remote":"127.0.0.1:49538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T12:06:25.242032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.478508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:06:25.242333Z","caller":"traceutil/trace.go:171","msg":"trace[1963426299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8199; }","duration":"266.796406ms","start":"2026-04-20T12:06:24.975508Z","end":"2026-04-20T12:06:25.242304Z","steps":["trace[1963426299] 'range keys from in-memory index tree' (duration: 266.403855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:25.242631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.905188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T12:06:25.242698Z","caller":"traceutil/trace.go:171","msg":"trace[736010273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8199; }","duration":"319.991281ms","start":"2026-04-20T12:06:24.92269Z","end":"2026-04-20T12:06:25.242681Z","steps":["trace[736010273] 'range keys from in-memory index tree' (duration: 319.715104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:25.242746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:06:24.922681Z","time spent":"320.049052ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T12:06:25.242761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.919652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T12:06:25.242805Z","caller":"traceutil/trace.go:171","msg":"trace[1635863899] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8199; }","duration":"316.013574ms","start":"2026-04-20T12:06:24.926777Z","end":"2026-04-20T12:06:25.24279Z","steps":["trace[1635863899] 'range keys from in-memory index tree' (duration: 315.791279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:25.242843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:06:24.926738Z","time spent":"316.097486ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T12:06:25.242895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.710215ms","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-04-20T12:06:25.243035Z","caller":"traceutil/trace.go:171","msg":"trace[851121994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8199; }","duration":"309.926111ms","start":"2026-04-20T12:06:24.93308Z","end":"2026-04-20T12:06:25.243006Z","steps":["trace[851121994] 'range keys from in-memory index tree' (duration: 309.485509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:25.243096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:06:24.933068Z","time spent":"310.017333ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T12:06:25.325528Z","caller":"traceutil/trace.go:171","msg":"trace[421947172] linearizableReadLoop","detail":"{readStateIndex:8491; appliedIndex:8490; }","duration":"163.9009ms","start":"2026-04-20T12:06:25.161591Z","end":"2026-04-20T12:06:25.325492Z","steps":["trace[421947172] 'read index received' (duration: 163.717745ms)","trace[421947172] 'applied index is now lower than readState.Index' (duration: 181.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:06:25.325637Z","caller":"traceutil/trace.go:171","msg":"trace[2120020310] transaction","detail":"{read_only:false; response_revision:8200; number_of_response:1; }","duration":"203.421578ms","start":"2026-04-20T12:06:25.122179Z","end":"2026-04-20T12:06:25.325601Z","steps":["trace[2120020310] 'process raft request' (duration: 203.090219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:25.326647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.01792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T12:06:25.326713Z","caller":"traceutil/trace.go:171","msg":"trace[1111623418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8200; }","duration":"165.117792ms","start":"2026-04-20T12:06:25.161581Z","end":"2026-04-20T12:06:25.326699Z","steps":["trace[1111623418] 'agreement among raft nodes before linearized reading' (duration: 164.041694ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:06:39.762361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6086} {"level":"info","ts":"2026-04-20T12:06:39.881825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6086,"took":"117.036564ms","hash":2229444883,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":23433216,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-20T12:06:39.881904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2229444883,"revision":6086,"compact-revision":2994} {"level":"info","ts":"2026-04-20T12:07:03.747429Z","caller":"traceutil/trace.go:171","msg":"trace[1310898188] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"102.525356ms","start":"2026-04-20T12:07:03.644874Z","end":"2026-04-20T12:07:03.747399Z","steps":["trace[1310898188] 'process raft request' (duration: 100.331307ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:05.838941Z","caller":"traceutil/trace.go:171","msg":"trace[1559183197] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8767; }","duration":"256.902881ms","start":"2026-04-20T12:07:05.582013Z","end":"2026-04-20T12:07:05.838915Z","steps":["trace[1559183197] 'read index received' (duration: 256.694236ms)","trace[1559183197] 'applied index is now lower than readState.Index' (duration: 207.715µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:05.83902Z","caller":"traceutil/trace.go:171","msg":"trace[1523808523] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"298.987308ms","start":"2026-04-20T12:07:05.53999Z","end":"2026-04-20T12:07:05.838977Z","steps":["trace[1523808523] 'process raft request' (duration: 298.769382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:05.839187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.150518ms","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-04-20T12:07:05.839237Z","caller":"traceutil/trace.go:171","msg":"trace[611355687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8465; }","duration":"257.252911ms","start":"2026-04-20T12:07:05.581968Z","end":"2026-04-20T12:07:05.839221Z","steps":["trace[611355687] 'agreement among raft nodes before linearized reading' (duration: 257.080126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:05.839351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.862013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-20T12:07:05.839353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.23591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:07:05.839451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.583909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T12:07:05.839406Z","caller":"traceutil/trace.go:171","msg":"trace[877103619] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8465; }","duration":"174.986397ms","start":"2026-04-20T12:07:05.664402Z","end":"2026-04-20T12:07:05.839388Z","steps":["trace[877103619] 'agreement among raft nodes before linearized reading' (duration: 174.800212ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:05.839553Z","caller":"traceutil/trace.go:171","msg":"trace[644833254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8465; }","duration":"195.723122ms","start":"2026-04-20T12:07:05.643808Z","end":"2026-04-20T12:07:05.839531Z","steps":["trace[644833254] 'agreement among raft nodes before linearized reading' (duration: 195.409134ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:05.839473Z","caller":"traceutil/trace.go:171","msg":"trace[1735193136] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8465; }","duration":"151.452577ms","start":"2026-04-20T12:07:05.687998Z","end":"2026-04-20T12:07:05.839451Z","steps":["trace[1735193136] 'agreement among raft nodes before linearized reading' (duration: 151.174439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:05.840358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.936079ms","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-04-20T12:07:05.840476Z","caller":"traceutil/trace.go:171","msg":"trace[67924359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8465; }","duration":"205.864483ms","start":"2026-04-20T12:07:05.634582Z","end":"2026-04-20T12:07:05.840446Z","steps":["trace[67924359] 'agreement among raft nodes before linearized reading' (duration: 204.548718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:18.161225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.829163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:07:18.161599Z","caller":"traceutil/trace.go:171","msg":"trace[303478459] linearizableReadLoop","detail":"{readStateIndex:8877; appliedIndex:8876; }","duration":"257.949178ms","start":"2026-04-20T12:07:17.903632Z","end":"2026-04-20T12:07:18.161581Z","steps":["trace[303478459] 'read index received' (duration: 129.681284ms)","trace[303478459] 'applied index is now lower than readState.Index' (duration: 128.264664ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:18.16167Z","caller":"traceutil/trace.go:171","msg":"trace[274794592] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"286.474752ms","start":"2026-04-20T12:07:17.875183Z","end":"2026-04-20T12:07:18.161657Z","steps":["trace[274794592] 'process raft request' (duration: 158.110575ms)","trace[274794592] 'compare' (duration: 127.586266ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:07:18.161942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.698918ms","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-04-20T12:07:18.161979Z","caller":"traceutil/trace.go:171","msg":"trace[510394581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8570; }","duration":"258.771489ms","start":"2026-04-20T12:07:17.903197Z","end":"2026-04-20T12:07:18.161968Z","steps":["trace[510394581] 'agreement among raft nodes before linearized reading' (duration: 258.648476ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:18.178899Z","caller":"traceutil/trace.go:171","msg":"trace[1966090743] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8571; }","duration":"269.97578ms","start":"2026-04-20T12:07:17.908914Z","end":"2026-04-20T12:07:18.178889Z","steps":["trace[1966090743] 'process raft request' (duration: 269.884687ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:18.187199Z","caller":"traceutil/trace.go:171","msg":"trace[1055253544] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"116.141681ms","start":"2026-04-20T12:07:18.071035Z","end":"2026-04-20T12:07:18.187177Z","steps":["trace[1055253544] 'process raft request' (duration: 115.802492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:18.200037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.169627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:07:18.200169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.263917ms","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-04-20T12:07:18.200227Z","caller":"traceutil/trace.go:171","msg":"trace[500070868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8574; }","duration":"286.316718ms","start":"2026-04-20T12:07:17.913889Z","end":"2026-04-20T12:07:18.200206Z","steps":["trace[500070868] 'agreement among raft nodes before linearized reading' (duration: 286.186754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:18.200469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.546339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-j5mg9\" ","response":"range_response_count:1 size:4646"} {"level":"info","ts":"2026-04-20T12:07:18.200517Z","caller":"traceutil/trace.go:171","msg":"trace[1800741392] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-j5mg9; range_end:; response_count:1; response_revision:8574; }","duration":"129.625402ms","start":"2026-04-20T12:07:18.070878Z","end":"2026-04-20T12:07:18.200504Z","steps":["trace[1800741392] 'agreement among raft nodes before linearized reading' (duration: 129.474538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:18.200733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.11587ms","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-04-20T12:07:18.200777Z","caller":"traceutil/trace.go:171","msg":"trace[16615814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8574; }","duration":"287.196512ms","start":"2026-04-20T12:07:17.91357Z","end":"2026-04-20T12:07:18.200767Z","steps":["trace[16615814] 'agreement among raft nodes before linearized reading' (duration: 287.051548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:18.200969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.135877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T12:07:18.201008Z","caller":"traceutil/trace.go:171","msg":"trace[1187142896] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8574; }","duration":"288.188688ms","start":"2026-04-20T12:07:17.912806Z","end":"2026-04-20T12:07:18.200995Z","steps":["trace[1187142896] 'agreement among raft nodes before linearized reading' (duration: 288.072665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:18.201204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.782137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-j5mg9\" ","response":"range_response_count:1 size:4646"} {"level":"info","ts":"2026-04-20T12:07:18.200175Z","caller":"traceutil/trace.go:171","msg":"trace[488949131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8574; }","duration":"223.356892ms","start":"2026-04-20T12:07:17.976785Z","end":"2026-04-20T12:07:18.200142Z","steps":["trace[488949131] 'agreement among raft nodes before linearized reading' (duration: 223.201748ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:18.201238Z","caller":"traceutil/trace.go:171","msg":"trace[629581995] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-j5mg9; range_end:; response_count:1; response_revision:8574; }","duration":"293.816328ms","start":"2026-04-20T12:07:17.907413Z","end":"2026-04-20T12:07:18.201229Z","steps":["trace[629581995] 'agreement among raft nodes before linearized reading' (duration: 293.693025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:18.20001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.672768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-20T12:07:18.201418Z","caller":"traceutil/trace.go:171","msg":"trace[1764582568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8574; }","duration":"294.152688ms","start":"2026-04-20T12:07:17.907248Z","end":"2026-04-20T12:07:18.2014Z","steps":["trace[1764582568] 'agreement among raft nodes before linearized reading' (duration: 292.564625ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:19.147272Z","caller":"traceutil/trace.go:171","msg":"trace[380980983] linearizableReadLoop","detail":"{readStateIndex:8889; appliedIndex:8888; }","duration":"215.528723ms","start":"2026-04-20T12:07:18.931719Z","end":"2026-04-20T12:07:19.147248Z","steps":["trace[380980983] 'read index received' (duration: 215.374889ms)","trace[380980983] 'applied index is now lower than readState.Index' (duration: 152.744µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:19.147339Z","caller":"traceutil/trace.go:171","msg":"trace[1357937199] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"335.344302ms","start":"2026-04-20T12:07:18.811962Z","end":"2026-04-20T12:07:19.147307Z","steps":["trace[1357937199] 'process raft request' (duration: 335.123066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:19.147507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:18.811947Z","time spent":"335.464915ms","remote":"127.0.0.1:41328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:07:19.147536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.79632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8n5lh\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-04-20T12:07:19.147605Z","caller":"traceutil/trace.go:171","msg":"trace[1112927981] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8n5lh; range_end:; response_count:1; response_revision:8581; }","duration":"215.914273ms","start":"2026-04-20T12:07:18.931675Z","end":"2026-04-20T12:07:19.147589Z","steps":["trace[1112927981] 'agreement among raft nodes before linearized reading' (duration: 215.716468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:19.176537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.965823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-20T12:07:19.17662Z","caller":"traceutil/trace.go:171","msg":"trace[10186827] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"233.574626ms","start":"2026-04-20T12:07:18.943024Z","end":"2026-04-20T12:07:19.176599Z","steps":["trace[10186827] 'process raft request' (duration: 233.176576ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:19.176641Z","caller":"traceutil/trace.go:171","msg":"trace[954671117] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8582; }","duration":"179.110978ms","start":"2026-04-20T12:07:18.997509Z","end":"2026-04-20T12:07:19.17662Z","steps":["trace[954671117] 'agreement among raft nodes before linearized reading' (duration: 178.900502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:19.176661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.851986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:07:19.17674Z","caller":"traceutil/trace.go:171","msg":"trace[1160857272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8582; }","duration":"198.971209ms","start":"2026-04-20T12:07:18.97775Z","end":"2026-04-20T12:07:19.176721Z","steps":["trace[1160857272] 'agreement among raft nodes before linearized reading' (duration: 198.824176ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:20.473077Z","caller":"traceutil/trace.go:171","msg":"trace[964784050] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"155.087574ms","start":"2026-04-20T12:07:20.317962Z","end":"2026-04-20T12:07:20.47305Z","steps":["trace[964784050] 'process raft request' (duration: 155.030473ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:20.473106Z","caller":"traceutil/trace.go:171","msg":"trace[1333528999] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"155.446533ms","start":"2026-04-20T12:07:20.317608Z","end":"2026-04-20T12:07:20.473054Z","steps":["trace[1333528999] 'process raft request' (duration: 155.158666ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:20.473111Z","caller":"traceutil/trace.go:171","msg":"trace[1700558818] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"155.428443ms","start":"2026-04-20T12:07:20.317659Z","end":"2026-04-20T12:07:20.473088Z","steps":["trace[1700558818] 'process raft request' (duration: 155.289959ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:39.143627Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b8.wal"} {"level":"info","ts":"2026-04-20T12:08:30.689228Z","caller":"traceutil/trace.go:171","msg":"trace[1055243777] transaction","detail":"{read_only:false; response_revision:9237; number_of_response:1; }","duration":"154.222091ms","start":"2026-04-20T12:08:30.534972Z","end":"2026-04-20T12:08:30.689194Z","steps":["trace[1055243777] 'process raft request' (duration: 153.956434ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:30.706646Z","caller":"traceutil/trace.go:171","msg":"trace[89656796] transaction","detail":"{read_only:false; response_revision:9238; number_of_response:1; }","duration":"163.278124ms","start":"2026-04-20T12:08:30.543329Z","end":"2026-04-20T12:08:30.706607Z","steps":["trace[89656796] 'process raft request' (duration: 162.828592ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:17.022073Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T12:09:17.028263Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T12:09:17.028334Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T12:11:39.770163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8283} {"level":"info","ts":"2026-04-20T12:11:39.853002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8283,"took":"80.549647ms","hash":355807871,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":25112576,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T12:11:39.853096Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":355807871,"revision":8283,"compact-revision":6086} {"level":"info","ts":"2026-04-20T12:16:39.776189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10708} {"level":"info","ts":"2026-04-20T12:16:39.86244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10708,"took":"84.273471ms","hash":1278216869,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":20238336,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-20T12:16:39.862532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1278216869,"revision":10708,"compact-revision":8283}