{"level":"warn","ts":"2026-05-07T23:18:26.454639Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:18:26.454775Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T23:18:26.454859Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:18:26.454876Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-07T23:18:26.4549Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T23:18:26.456874Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-05-07T23:18:26.456993Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T23:18:26.461342Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.075597ms"} {"level":"info","ts":"2026-05-07T23:18:26.466997Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-05-07T23:18:26.467079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T23:18:26.467101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-05-07T23:18:26.467109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T23:18:26.467122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-05-07T23:18:26.467153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-05-07T23:18:26.476875Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T23:18:26.481829Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T23:18:26.483122Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T23:18:26.485894Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T23:18:26.4862Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:18:26.48628Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:18:26.486205Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T23:18:26.48632Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:18:26.487439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-05-07T23:18:26.488292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-07T23:18:26.492477Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T23:18:26.492736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T23:18:26.492771Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T23:18:26.49334Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-07T23:18:26.493392Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-07T23:18:27.368299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T23:18:27.368342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T23:18:27.368357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-05-07T23:18:27.368368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-05-07T23:18:27.368374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-07T23:18:27.368382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-05-07T23:18:27.368389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-07T23:18:27.369298Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T23:18:27.36942Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:18:27.369532Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T23:18:27.369473Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:18:27.3695Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:18:27.369556Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T23:18:27.371493Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:18:27.371644Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:18:27.371689Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:18:27.372941Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-05-07T23:18:27.373359Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T23:18:29.823209Z","caller":"traceutil/trace.go:171","msg":"trace[2103932570] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"201.304041ms","start":"2026-05-07T23:18:29.621884Z","end":"2026-05-07T23:18:29.823188Z","steps":["trace[2103932570] 'read index received' (duration: 201.26306ms)","trace[2103932570] 'applied index is now lower than readState.Index' (duration: 40.101µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:18:29.823338Z","caller":"traceutil/trace.go:171","msg":"trace[1236869490] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"201.556153ms","start":"2026-05-07T23:18:29.621775Z","end":"2026-05-07T23:18:29.823331Z","steps":["trace[1236869490] 'process raft request' (duration: 201.296661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:29.823772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.880557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T23:18:29.823706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.753317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:18:29.823808Z","caller":"traceutil/trace.go:171","msg":"trace[1629239993] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:71; }","duration":"201.960078ms","start":"2026-05-07T23:18:29.621835Z","end":"2026-05-07T23:18:29.823795Z","steps":["trace[1629239993] 'agreement among raft nodes before linearized reading' (duration: 201.871717ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:18:29.823855Z","caller":"traceutil/trace.go:171","msg":"trace[956357108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"146.98995ms","start":"2026-05-07T23:18:29.676836Z","end":"2026-05-07T23:18:29.823826Z","steps":["trace[956357108] 'agreement among raft nodes before linearized reading' (duration: 146.664606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:30.508783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.146102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-07T23:18:30.509017Z","caller":"traceutil/trace.go:171","msg":"trace[499719654] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"680.190673ms","start":"2026-05-07T23:18:29.828761Z","end":"2026-05-07T23:18:30.508952Z","steps":["trace[499719654] 'process raft request' (duration: 450.273602ms)","trace[499719654] 'compare' (duration: 228.871899ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:18:30.50922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:29.82875Z","time spent":"680.393376ms","remote":"127.0.0.1:39264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:18:30.50925Z","caller":"traceutil/trace.go:171","msg":"trace[855905521] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"680.434596ms","start":"2026-05-07T23:18:29.828781Z","end":"2026-05-07T23:18:30.508995Z","steps":["trace[855905521] 'read index received' (duration: 450.200032ms)","trace[855905521] 'applied index is now lower than readState.Index' (duration: 230.008251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:18:30.509415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.582817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T23:18:30.509538Z","caller":"traceutil/trace.go:171","msg":"trace[384217455] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"680.742029ms","start":"2026-05-07T23:18:29.828764Z","end":"2026-05-07T23:18:30.509506Z","steps":["trace[384217455] 'agreement among raft nodes before linearized reading' (duration: 680.387805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:30.509599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:29.828757Z","time spent":"680.831851ms","remote":"127.0.0.1:39258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-05-07T23:18:30.664521Z","caller":"traceutil/trace.go:171","msg":"trace[1973922975] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"142.28304ms","start":"2026-05-07T23:18:30.522214Z","end":"2026-05-07T23:18:30.664498Z","steps":["trace[1973922975] 'process raft request' (duration: 138.666421ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:18:57.653013Z","caller":"traceutil/trace.go:171","msg":"trace[1192854880] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"379.704161ms","start":"2026-05-07T23:18:57.273282Z","end":"2026-05-07T23:18:57.652986Z","steps":["trace[1192854880] 'process raft request' (duration: 379.48638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:57.653155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.27323Z","time spent":"379.860052ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:18:57.660099Z","caller":"traceutil/trace.go:171","msg":"trace[862765142] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"213.473951ms","start":"2026-05-07T23:18:57.4466Z","end":"2026-05-07T23:18:57.660074Z","steps":["trace[862765142] 'process raft request' (duration: 213.219609ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:18:57.660156Z","caller":"traceutil/trace.go:171","msg":"trace[1253541383] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"386.105035ms","start":"2026-05-07T23:18:57.274044Z","end":"2026-05-07T23:18:57.660149Z","steps":["trace[1253541383] 'process raft request' (duration: 385.110547ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:18:57.660172Z","caller":"traceutil/trace.go:171","msg":"trace[180906036] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"365.04197ms","start":"2026-05-07T23:18:57.295117Z","end":"2026-05-07T23:18:57.660159Z","steps":["trace[180906036] 'process raft request' (duration: 364.490536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:57.660284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.274029Z","time spent":"386.182736ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:18:57.660485Z","caller":"traceutil/trace.go:171","msg":"trace[1502679778] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"370.951729ms","start":"2026-05-07T23:18:57.289475Z","end":"2026-05-07T23:18:57.660427Z","steps":["trace[1502679778] 'process raft request' (duration: 369.926601ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:18:57.660667Z","caller":"traceutil/trace.go:171","msg":"trace[149210221] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"369.361687ms","start":"2026-05-07T23:18:57.291296Z","end":"2026-05-07T23:18:57.660658Z","steps":["trace[149210221] 'process raft request' (duration: 368.231298ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:18:57.660659Z","caller":"traceutil/trace.go:171","msg":"trace[1225004863] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"386.473998ms","start":"2026-05-07T23:18:57.274109Z","end":"2026-05-07T23:18:57.660583Z","steps":["trace[1225004863] 'process raft request' (duration: 385.145587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:57.660751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.291291Z","time spent":"369.433018ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:18:57.660118Z","caller":"traceutil/trace.go:171","msg":"trace[1110160158] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"366.790346ms","start":"2026-05-07T23:18:57.293287Z","end":"2026-05-07T23:18:57.660078Z","steps":["trace[1110160158] 'process raft request' (duration: 366.279202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:57.660936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.274102Z","time spent":"386.683569ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:18:57.660963Z","caller":"traceutil/trace.go:171","msg":"trace[220061121] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"371.695925ms","start":"2026-05-07T23:18:57.289221Z","end":"2026-05-07T23:18:57.660917Z","steps":["trace[220061121] 'process raft request' (duration: 370.115493ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:18:57.660646Z","caller":"traceutil/trace.go:171","msg":"trace[2073928931] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"361.24965ms","start":"2026-05-07T23:18:57.299379Z","end":"2026-05-07T23:18:57.660629Z","steps":["trace[2073928931] 'process raft request' (duration: 360.280432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:57.661268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.299366Z","time spent":"361.810744ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:18:57.660536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.295104Z","time spent":"365.371333ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:18:57.660989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.293247Z","time spent":"367.696432ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:18:57.661605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.28947Z","time spent":"372.083168ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:18:57.661373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.289198Z","time spent":"371.835596ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:18:57.66061Z","caller":"traceutil/trace.go:171","msg":"trace[1249439226] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"369.563129ms","start":"2026-05-07T23:18:57.291032Z","end":"2026-05-07T23:18:57.660595Z","steps":["trace[1249439226] 'process raft request' (duration: 368.45725ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:18:57.660107Z","caller":"traceutil/trace.go:171","msg":"trace[1658060318] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"346.529939ms","start":"2026-05-07T23:18:57.313545Z","end":"2026-05-07T23:18:57.660075Z","steps":["trace[1658060318] 'process raft request' (duration: 346.168926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:18:57.665771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.313532Z","time spent":"352.172575ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:18:57.665813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:18:57.291015Z","time spent":"372.504253ms","remote":"127.0.0.1:38956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:19:04.540833Z","caller":"traceutil/trace.go:171","msg":"trace[1903623528] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"269.851986ms","start":"2026-05-07T23:19:04.270887Z","end":"2026-05-07T23:19:04.540739Z","steps":["trace[1903623528] 'read index received' (duration: 262.016894ms)","trace[1903623528] 'applied index is now lower than readState.Index' (duration: 7.834102ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:04.540936Z","caller":"traceutil/trace.go:171","msg":"trace[746660666] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"777.419978ms","start":"2026-05-07T23:19:03.763461Z","end":"2026-05-07T23:19:04.540881Z","steps":["trace[746660666] 'process raft request' (duration: 769.777518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:04.541085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.184339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T23:19:04.541138Z","caller":"traceutil/trace.go:171","msg":"trace[1852182368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"270.2883ms","start":"2026-05-07T23:19:04.270834Z","end":"2026-05-07T23:19:04.541123Z","steps":["trace[1852182368] 'agreement among raft nodes before linearized reading' (duration: 270.121669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:04.541165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.52446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kj8fx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T23:19:04.541261Z","caller":"traceutil/trace.go:171","msg":"trace[2138652097] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kj8fx; range_end:; response_count:1; response_revision:577; }","duration":"228.689902ms","start":"2026-05-07T23:19:04.312548Z","end":"2026-05-07T23:19:04.541238Z","steps":["trace[2138652097] 'agreement among raft nodes before linearized reading' (duration: 228.44855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:04.541424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:03.763432Z","time spent":"777.61913ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:05.13909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367524418463703,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:19:05.361372Z","caller":"traceutil/trace.go:171","msg":"trace[1556303251] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"815.38485ms","start":"2026-05-07T23:19:04.545951Z","end":"2026-05-07T23:19:05.361336Z","steps":["trace[1556303251] 'process raft request' (duration: 803.939801ms)","trace[1556303251] 'compare' (duration: 10.629193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:05.361549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:04.545933Z","time spent":"815.522771ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:06.634946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141194635s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:06.635383Z","caller":"traceutil/trace.go:171","msg":"trace[356813754] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"2.088077396s","start":"2026-05-07T23:19:04.547267Z","end":"2026-05-07T23:19:06.635345Z","steps":["trace[356813754] 'process raft request' (duration: 946.385747ms)","trace[356813754] 'compare' (duration: 1.140737541s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:06.635509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:04.547245Z","time spent":"2.088223237s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:06.635518Z","caller":"traceutil/trace.go:171","msg":"trace[377741823] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"2.080039462s","start":"2026-05-07T23:19:04.555446Z","end":"2026-05-07T23:19:06.635486Z","steps":["trace[377741823] 'process raft request' (duration: 2.079698919s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:06.635738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:04.555429Z","time spent":"2.080205703s","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:06.6359Z","caller":"traceutil/trace.go:171","msg":"trace[350829919] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:598; }","duration":"1.997253699s","start":"2026-05-07T23:19:04.638633Z","end":"2026-05-07T23:19:06.635887Z","steps":["trace[350829919] 'read index received' (duration: 711.27218ms)","trace[350829919] 'applied index is now lower than readState.Index' (duration: 1.285980219s)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:06.635996Z","caller":"traceutil/trace.go:171","msg":"trace[1511571538] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"2.006719303s","start":"2026-05-07T23:19:04.629268Z","end":"2026-05-07T23:19:06.635987Z","steps":["trace[1511571538] 'process raft request' (duration: 2.005949377s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:06.636054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:04.629235Z","time spent":"2.006795514s","remote":"127.0.0.1:39458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:06.636145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997488391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:06.636228Z","caller":"traceutil/trace.go:171","msg":"trace[1068783358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"1.997617721s","start":"2026-05-07T23:19:04.63859Z","end":"2026-05-07T23:19:06.636208Z","steps":["trace[1068783358] 'agreement among raft nodes before linearized reading' (duration: 1.99745851s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:06.636275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:04.638574Z","time spent":"1.997693533s","remote":"127.0.0.1:38878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:19:07.043862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.801959ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T23:19:07.043957Z","caller":"traceutil/trace.go:171","msg":"trace[12929792] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"407.987648ms","start":"2026-05-07T23:19:06.635953Z","end":"2026-05-07T23:19:07.043941Z","steps":["trace[12929792] 'read index received' (duration: 214.107348ms)","trace[12929792] 'applied index is now lower than readState.Index' (duration: 193.8789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:07.044081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.71668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.044154Z","caller":"traceutil/trace.go:171","msg":"trace[882759577] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:581; }","duration":"414.82496ms","start":"2026-05-07T23:19:06.629318Z","end":"2026-05-07T23:19:07.044143Z","steps":["trace[882759577] 'agreement among raft nodes before linearized reading' (duration: 414.71152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.04429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.628607Z","time spent":"415.644587ms","remote":"127.0.0.1:55766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:19:07.044571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.443928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.044602Z","caller":"traceutil/trace.go:171","msg":"trace[1896871001] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:581; }","duration":"418.519419ms","start":"2026-05-07T23:19:06.626075Z","end":"2026-05-07T23:19:07.044594Z","steps":["trace[1896871001] 'agreement among raft nodes before linearized reading' (duration: 418.472638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.044627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.62605Z","time spent":"418.568649ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:19:07.044773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.70231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.044842Z","caller":"traceutil/trace.go:171","msg":"trace[994270988] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:581; }","duration":"418.802211ms","start":"2026-05-07T23:19:06.626031Z","end":"2026-05-07T23:19:07.044833Z","steps":["trace[994270988] 'agreement among raft nodes before linearized reading' (duration: 418.717031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.044872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.626002Z","time spent":"418.860273ms","remote":"127.0.0.1:55780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:19:07.045008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.927466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.045045Z","caller":"traceutil/trace.go:171","msg":"trace[73056483] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:581; }","duration":"458.026277ms","start":"2026-05-07T23:19:06.587008Z","end":"2026-05-07T23:19:07.045034Z","steps":["trace[73056483] 'agreement among raft nodes before linearized reading' (duration: 457.975737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.045073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.586988Z","time spent":"458.076957ms","remote":"127.0.0.1:55766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:19:07.045199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.342529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.045234Z","caller":"traceutil/trace.go:171","msg":"trace[390204479] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:581; }","duration":"458.397119ms","start":"2026-05-07T23:19:06.586828Z","end":"2026-05-07T23:19:07.045225Z","steps":["trace[390204479] 'agreement among raft nodes before linearized reading' (duration: 458.344109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.045272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.586819Z","time spent":"458.43397ms","remote":"127.0.0.1:55766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:19:07.045402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.35961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.045435Z","caller":"traceutil/trace.go:171","msg":"trace[164919016] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:581; }","duration":"503.39502ms","start":"2026-05-07T23:19:06.542032Z","end":"2026-05-07T23:19:07.045427Z","steps":["trace[164919016] 'agreement among raft nodes before linearized reading' (duration: 503.34574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.045462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.541959Z","time spent":"503.494342ms","remote":"127.0.0.1:55758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:19:07.045595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.071436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.045615Z","caller":"traceutil/trace.go:171","msg":"trace[518260273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:581; }","duration":"504.108036ms","start":"2026-05-07T23:19:06.5415Z","end":"2026-05-07T23:19:07.045608Z","steps":["trace[518260273] 'agreement among raft nodes before linearized reading' (duration: 504.073976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.045634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.541496Z","time spent":"504.132566ms","remote":"127.0.0.1:55758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:19:07.04572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.273477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.045739Z","caller":"traceutil/trace.go:171","msg":"trace[1860487529] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:581; }","duration":"504.317558ms","start":"2026-05-07T23:19:06.541414Z","end":"2026-05-07T23:19:07.045732Z","steps":["trace[1860487529] 'agreement among raft nodes before linearized reading' (duration: 504.288398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.045757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.541404Z","time spent":"504.347718ms","remote":"127.0.0.1:55758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:19:07.045842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.437098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.045864Z","caller":"traceutil/trace.go:171","msg":"trace[984732593] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:581; }","duration":"531.474218ms","start":"2026-05-07T23:19:06.514381Z","end":"2026-05-07T23:19:07.045855Z","steps":["trace[984732593] 'agreement among raft nodes before linearized reading' (duration: 531.445028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.045891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.514373Z","time spent":"531.510339ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:19:07.046195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.66657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.048996Z","caller":"traceutil/trace.go:171","msg":"trace[908681315] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:581; }","duration":"534.641333ms","start":"2026-05-07T23:19:06.514342Z","end":"2026-05-07T23:19:07.048984Z","steps":["trace[908681315] 'agreement among raft nodes before linearized reading' (duration: 531.67283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.046302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.933357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.049041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.514334Z","time spent":"534.694605ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-05-07T23:19:07.049248Z","caller":"traceutil/trace.go:171","msg":"trace[1430006238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"410.723659ms","start":"2026-05-07T23:19:06.638319Z","end":"2026-05-07T23:19:07.049042Z","steps":["trace[1430006238] 'agreement among raft nodes before linearized reading' (duration: 407.950517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.049637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.638296Z","time spent":"411.323663ms","remote":"127.0.0.1:38890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:19:07.046433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.896744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.049831Z","caller":"traceutil/trace.go:171","msg":"trace[1411249196] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:581; }","duration":"281.351281ms","start":"2026-05-07T23:19:06.768452Z","end":"2026-05-07T23:19:07.049804Z","steps":["trace[1411249196] 'agreement among raft nodes before linearized reading' (duration: 277.908485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.046526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.209005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.049961Z","caller":"traceutil/trace.go:171","msg":"trace[540453271] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:581; }","duration":"535.668571ms","start":"2026-05-07T23:19:06.514274Z","end":"2026-05-07T23:19:07.049943Z","steps":["trace[540453271] 'agreement among raft nodes before linearized reading' (duration: 532.218335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.046576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.167395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.04664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.104843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.046654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.752596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.046669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.591773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.046687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.166233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.046696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.371089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.046717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.143087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.046734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.895878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:07.046742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.222887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.050905Z","caller":"traceutil/trace.go:171","msg":"trace[245104935] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:581; }","duration":"281.583931ms","start":"2026-05-07T23:19:06.769312Z","end":"2026-05-07T23:19:07.050896Z","steps":["trace[245104935] 'agreement among raft nodes before linearized reading' (duration: 277.349339ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:07.050909Z","caller":"traceutil/trace.go:171","msg":"trace[345259937] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:581; }","duration":"113.043628ms","start":"2026-05-07T23:19:06.937857Z","end":"2026-05-07T23:19:07.0509Z","steps":["trace[345259937] 'agreement among raft nodes before linearized reading' (duration: 108.738356ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:07.050951Z","caller":"traceutil/trace.go:171","msg":"trace[971247605] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:581; }","duration":"372.344279ms","start":"2026-05-07T23:19:06.678564Z","end":"2026-05-07T23:19:07.050909Z","steps":["trace[971247605] 'agreement among raft nodes before linearized reading' (duration: 368.127657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.051064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.67852Z","time spent":"372.52985ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:19:07.051143Z","caller":"traceutil/trace.go:171","msg":"trace[1447809657] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"555.621027ms","start":"2026-05-07T23:19:06.49551Z","end":"2026-05-07T23:19:07.051131Z","steps":["trace[1447809657] 'agreement among raft nodes before linearized reading' (duration: 551.149943ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:07.051157Z","caller":"traceutil/trace.go:171","msg":"trace[1706208715] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"579.330932ms","start":"2026-05-07T23:19:06.471815Z","end":"2026-05-07T23:19:07.051146Z","steps":["trace[1706208715] 'agreement among raft nodes before linearized reading' (duration: 574.893108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.051201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.495484Z","time spent":"555.697647ms","remote":"127.0.0.1:55750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:19:07.051237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.471807Z","time spent":"579.388913ms","remote":"127.0.0.1:44144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:19:07.046779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.056267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.051421Z","caller":"traceutil/trace.go:171","msg":"trace[1643586806] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"580.700932ms","start":"2026-05-07T23:19:06.470706Z","end":"2026-05-07T23:19:07.051407Z","steps":["trace[1643586806] 'agreement among raft nodes before linearized reading' (duration: 576.048797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.051473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.470703Z","time spent":"580.755852ms","remote":"127.0.0.1:44144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:19:07.046804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.769496ms","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-05-07T23:19:07.051637Z","caller":"traceutil/trace.go:171","msg":"trace[706673184] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:581; }","duration":"113.666654ms","start":"2026-05-07T23:19:06.93796Z","end":"2026-05-07T23:19:07.051626Z","steps":["trace[706673184] 'agreement among raft nodes before linearized reading' (duration: 108.805667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.046801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.285651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.051732Z","caller":"traceutil/trace.go:171","msg":"trace[230551862] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:581; }","duration":"390.222958ms","start":"2026-05-07T23:19:06.661495Z","end":"2026-05-07T23:19:07.051718Z","steps":["trace[230551862] 'agreement among raft nodes before linearized reading' (duration: 385.285771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.051782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.661488Z","time spent":"390.279389ms","remote":"127.0.0.1:55794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:19:07.046857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.137857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.051879Z","caller":"traceutil/trace.go:171","msg":"trace[163908122] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"581.182086ms","start":"2026-05-07T23:19:06.470686Z","end":"2026-05-07T23:19:07.051868Z","steps":["trace[163908122] 'agreement among raft nodes before linearized reading' (duration: 576.130277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.051919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.470678Z","time spent":"581.225677ms","remote":"127.0.0.1:44144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:19:07.046904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.324381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.052042Z","caller":"traceutil/trace.go:171","msg":"trace[1209718498] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:581; }","duration":"639.476291ms","start":"2026-05-07T23:19:06.412555Z","end":"2026-05-07T23:19:07.052031Z","steps":["trace[1209718498] 'agreement among raft nodes before linearized reading' (duration: 634.325161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.052096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.412547Z","time spent":"639.532871ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:19:07.046951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.364879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.052428Z","caller":"traceutil/trace.go:171","msg":"trace[1857594301] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:581; }","duration":"640.858732ms","start":"2026-05-07T23:19:06.411557Z","end":"2026-05-07T23:19:07.052416Z","steps":["trace[1857594301] 'agreement among raft nodes before linearized reading' (duration: 635.369989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.052476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.411528Z","time spent":"640.931142ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:19:07.047002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.461149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.052634Z","caller":"traceutil/trace.go:171","msg":"trace[1019357903] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:581; }","duration":"641.111853ms","start":"2026-05-07T23:19:06.411511Z","end":"2026-05-07T23:19:07.052623Z","steps":["trace[1019357903] 'agreement among raft nodes before linearized reading' (duration: 635.458949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.052698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.411495Z","time spent":"641.183313ms","remote":"127.0.0.1:44128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:19:07.047087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259153699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:19:07.052877Z","caller":"traceutil/trace.go:171","msg":"trace[847766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"1.264973804s","start":"2026-05-07T23:19:05.787892Z","end":"2026-05-07T23:19:07.052866Z","steps":["trace[847766] 'agreement among raft nodes before linearized reading' (duration: 1.259129339s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.052921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:05.787868Z","time spent":"1.265041984s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:19:07.04715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.223528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.053127Z","caller":"traceutil/trace.go:171","msg":"trace[1402846020] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:581; }","duration":"392.207514ms","start":"2026-05-07T23:19:06.66091Z","end":"2026-05-07T23:19:07.053118Z","steps":["trace[1402846020] 'agreement among raft nodes before linearized reading' (duration: 386.208527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.053155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.660848Z","time spent":"392.295335ms","remote":"127.0.0.1:55794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:19:07.048925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.159673ms","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-05-07T23:19:07.05339Z","caller":"traceutil/trace.go:171","msg":"trace[806949409] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:581; }","duration":"407.546582ms","start":"2026-05-07T23:19:06.645807Z","end":"2026-05-07T23:19:07.053353Z","steps":["trace[806949409] 'agreement among raft nodes before linearized reading' (duration: 401.154333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.046425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.954694639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-07T23:19:07.050798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.51426Z","time spent":"536.515477ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-05-07T23:19:07.050847Z","caller":"traceutil/trace.go:171","msg":"trace[1402105068] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"555.316405ms","start":"2026-05-07T23:19:06.495514Z","end":"2026-05-07T23:19:07.05083Z","steps":["trace[1402105068] 'agreement among raft nodes before linearized reading' (duration: 551.100403ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:07.050877Z","caller":"traceutil/trace.go:171","msg":"trace[440138885] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:581; }","duration":"554.490868ms","start":"2026-05-07T23:19:06.496377Z","end":"2026-05-07T23:19:07.050868Z","steps":["trace[440138885] 'agreement among raft nodes before linearized reading' (duration: 550.170255ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:07.050879Z","caller":"traceutil/trace.go:171","msg":"trace[849178721] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:581; }","duration":"389.836845ms","start":"2026-05-07T23:19:06.661026Z","end":"2026-05-07T23:19:07.050863Z","steps":["trace[849178721] 'agreement among raft nodes before linearized reading' (duration: 385.603323ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:07.051376Z","caller":"traceutil/trace.go:171","msg":"trace[1589562907] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:581; }","duration":"282.425599ms","start":"2026-05-07T23:19:06.7685Z","end":"2026-05-07T23:19:07.050926Z","steps":["trace[1589562907] 'agreement among raft nodes before linearized reading' (duration: 278.220216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.046764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.213478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.054985Z","caller":"traceutil/trace.go:171","msg":"trace[418874081] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:581; }","duration":"376.454431ms","start":"2026-05-07T23:19:06.678517Z","end":"2026-05-07T23:19:07.054971Z","steps":["trace[418874081] 'agreement among raft nodes before linearized reading' (duration: 368.221148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.055311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.678503Z","time spent":"376.792444ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-07T23:19:07.05511Z","caller":"traceutil/trace.go:171","msg":"trace[1373798404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.963450576s","start":"2026-05-07T23:19:05.09165Z","end":"2026-05-07T23:19:07.055101Z","steps":["trace[1373798404] 'agreement among raft nodes before linearized reading' (duration: 1.954523206s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.055598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:05.091625Z","time spent":"1.963964271s","remote":"127.0.0.1:39184","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-05-07T23:19:07.055123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.495506Z","time spent":"559.588868ms","remote":"127.0.0.1:55750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:19:07.055159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.645796Z","time spent":"409.354287ms","remote":"127.0.0.1:39148","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-05-07T23:19:07.055185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.661009Z","time spent":"394.1701ms","remote":"127.0.0.1:55794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T23:19:07.055202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.496367Z","time spent":"558.829923ms","remote":"127.0.0.1:55750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-07T23:19:07.046884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.550993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:07.056143Z","caller":"traceutil/trace.go:171","msg":"trace[1372381759] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:581; }","duration":"377.268258ms","start":"2026-05-07T23:19:06.678819Z","end":"2026-05-07T23:19:07.056087Z","steps":["trace[1372381759] 'agreement among raft nodes before linearized reading' (duration: 367.552273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:07.056183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:06.678807Z","time spent":"377.365368ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"info","ts":"2026-05-07T23:19:08.868091Z","caller":"traceutil/trace.go:171","msg":"trace[1608217898] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"222.156192ms","start":"2026-05-07T23:19:08.645901Z","end":"2026-05-07T23:19:08.868057Z","steps":["trace[1608217898] 'process raft request' (duration: 207.908852ms)","trace[1608217898] 'compare' (duration: 14.024228ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:10.63685Z","caller":"traceutil/trace.go:171","msg":"trace[22803746] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"107.888972ms","start":"2026-05-07T23:19:10.528938Z","end":"2026-05-07T23:19:10.636827Z","steps":["trace[22803746] 'process raft request' (duration: 107.800552ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:10.636891Z","caller":"traceutil/trace.go:171","msg":"trace[1028000181] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"114.511693ms","start":"2026-05-07T23:19:10.522325Z","end":"2026-05-07T23:19:10.636837Z","steps":["trace[1028000181] 'process raft request' (duration: 100.871289ms)","trace[1028000181] 'compare' (duration: 13.395843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:22.760413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.938272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:22.760508Z","caller":"traceutil/trace.go:171","msg":"trace[895066439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"120.059966ms","start":"2026-05-07T23:19:22.640429Z","end":"2026-05-07T23:19:22.760489Z","steps":["trace[895066439] 'range keys from in-memory index tree' (duration: 119.88437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:22.760572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.538006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6gzp8\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-07T23:19:22.760648Z","caller":"traceutil/trace.go:171","msg":"trace[771757571] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6gzp8; range_end:; response_count:1; response_revision:739; }","duration":"345.668231ms","start":"2026-05-07T23:19:22.414961Z","end":"2026-05-07T23:19:22.760629Z","steps":["trace[771757571] 'range keys from in-memory index tree' (duration: 345.369999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:22.760686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:22.41494Z","time spent":"345.739823ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6gzp8\" "} {"level":"info","ts":"2026-05-07T23:19:22.897994Z","caller":"traceutil/trace.go:171","msg":"trace[1969631630] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"129.421586ms","start":"2026-05-07T23:19:22.768544Z","end":"2026-05-07T23:19:22.897966Z","steps":["trace[1969631630] 'process raft request' (duration: 129.228339ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:51.626046Z","caller":"traceutil/trace.go:171","msg":"trace[453115471] linearizableReadLoop","detail":"{readStateIndex:1174; appliedIndex:1173; }","duration":"279.16698ms","start":"2026-05-07T23:19:51.346855Z","end":"2026-05-07T23:19:51.626022Z","steps":["trace[453115471] 'read index received' (duration: 279.026646ms)","trace[453115471] 'applied index is now lower than readState.Index' (duration: 139.264µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:51.6261Z","caller":"traceutil/trace.go:171","msg":"trace[1166492390] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"475.181789ms","start":"2026-05-07T23:19:51.150888Z","end":"2026-05-07T23:19:51.62607Z","steps":["trace[1166492390] 'process raft request' (duration: 474.992134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:51.626261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:51.150868Z","time spent":"475.297603ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:51.626397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.454818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:19:51.626578Z","caller":"traceutil/trace.go:171","msg":"trace[801405880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1141; }","duration":"279.698684ms","start":"2026-05-07T23:19:51.346824Z","end":"2026-05-07T23:19:51.626523Z","steps":["trace[801405880] 'agreement among raft nodes before linearized reading' (duration: 279.339874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:51.641517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.9048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:19:51.641565Z","caller":"traceutil/trace.go:171","msg":"trace[970604755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1142; }","duration":"244.975872ms","start":"2026-05-07T23:19:51.396578Z","end":"2026-05-07T23:19:51.641554Z","steps":["trace[970604755] 'agreement among raft nodes before linearized reading' (duration: 244.863969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:51.641661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.121148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T23:19:51.641756Z","caller":"traceutil/trace.go:171","msg":"trace[667278083] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1142; }","duration":"214.267802ms","start":"2026-05-07T23:19:51.427472Z","end":"2026-05-07T23:19:51.64174Z","steps":["trace[667278083] 'agreement among raft nodes before linearized reading' (duration: 213.927682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:51.641905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.224324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T23:19:51.641949Z","caller":"traceutil/trace.go:171","msg":"trace[2014400495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1142; }","duration":"157.328846ms","start":"2026-05-07T23:19:51.484613Z","end":"2026-05-07T23:19:51.641942Z","steps":["trace[2014400495] 'agreement among raft nodes before linearized reading' (duration: 156.779551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:15.109675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.672601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:20:15.110483Z","caller":"traceutil/trace.go:171","msg":"trace[431769804] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1407; }","duration":"130.53459ms","start":"2026-05-07T23:20:14.979924Z","end":"2026-05-07T23:20:15.110458Z","steps":["trace[431769804] 'count revisions from in-memory index tree' (duration: 129.570708ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:26.92205Z","caller":"traceutil/trace.go:171","msg":"trace[491411248] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"153.398155ms","start":"2026-05-07T23:20:26.768623Z","end":"2026-05-07T23:20:26.922021Z","steps":["trace[491411248] 'process raft request' (duration: 153.20712ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:33.13748Z","caller":"traceutil/trace.go:171","msg":"trace[1429720828] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"110.136983ms","start":"2026-05-07T23:20:33.027321Z","end":"2026-05-07T23:20:33.137458Z","steps":["trace[1429720828] 'process raft request' (duration: 109.946379ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:33.13768Z","caller":"traceutil/trace.go:171","msg":"trace[1712059578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1704; }","duration":"100.174241ms","start":"2026-05-07T23:20:33.037494Z","end":"2026-05-07T23:20:33.137668Z","steps":["trace[1712059578] 'agreement among raft nodes before linearized reading' (duration: 100.153331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:33.180571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.297565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:20:33.180656Z","caller":"traceutil/trace.go:171","msg":"trace[1967719094] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"112.428538ms","start":"2026-05-07T23:20:33.068209Z","end":"2026-05-07T23:20:33.180637Z","steps":["trace[1967719094] 'agreement among raft nodes before linearized reading' (duration: 112.298215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:33.180658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.780343ms","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:5"} {"level":"warn","ts":"2026-05-07T23:20:33.180726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.671098ms","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:5"} {"level":"info","ts":"2026-05-07T23:20:33.181252Z","caller":"traceutil/trace.go:171","msg":"trace[1357257426] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"133.209228ms","start":"2026-05-07T23:20:33.048026Z","end":"2026-05-07T23:20:33.181236Z","steps":["trace[1357257426] 'agreement among raft nodes before linearized reading' (duration: 132.644388ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:33.18076Z","caller":"traceutil/trace.go:171","msg":"trace[342414384] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"142.958366ms","start":"2026-05-07T23:20:33.037784Z","end":"2026-05-07T23:20:33.180742Z","steps":["trace[342414384] 'agreement among raft nodes before linearized reading' (duration: 142.701641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:33.180859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.975295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:20:33.181537Z","caller":"traceutil/trace.go:171","msg":"trace[1543700217] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"133.590596ms","start":"2026-05-07T23:20:33.047855Z","end":"2026-05-07T23:20:33.181445Z","steps":["trace[1543700217] 'agreement among raft nodes before linearized reading' (duration: 132.918623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:33.180888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.893782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:20:33.181654Z","caller":"traceutil/trace.go:171","msg":"trace[25755274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:0; response_revision:1705; }","duration":"105.658837ms","start":"2026-05-07T23:20:33.075981Z","end":"2026-05-07T23:20:33.18164Z","steps":["trace[25755274] 'agreement among raft nodes before linearized reading' (duration: 104.840351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:33.180963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.780965ms","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:5"} {"level":"info","ts":"2026-05-07T23:20:33.181022Z","caller":"traceutil/trace.go:171","msg":"trace[1762264084] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"132.553026ms","start":"2026-05-07T23:20:33.048182Z","end":"2026-05-07T23:20:33.180735Z","steps":["trace[1762264084] 'process raft request' (duration: 129.471686ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:33.182375Z","caller":"traceutil/trace.go:171","msg":"trace[1819865286] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1705; }","duration":"114.260423ms","start":"2026-05-07T23:20:33.068098Z","end":"2026-05-07T23:20:33.182358Z","steps":["trace[1819865286] 'agreement among raft nodes before linearized reading' (duration: 112.657492ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:40.574451Z","caller":"traceutil/trace.go:171","msg":"trace[1384542294] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"139.909831ms","start":"2026-05-07T23:20:40.434438Z","end":"2026-05-07T23:20:40.574348Z","steps":["trace[1384542294] 'process raft request' (duration: 139.675587ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:41.232888Z","caller":"traceutil/trace.go:171","msg":"trace[840206011] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"423.115026ms","start":"2026-05-07T23:20:40.809743Z","end":"2026-05-07T23:20:41.232858Z","steps":["trace[840206011] 'process raft request' (duration: 332.772494ms)","trace[840206011] 'compare' (duration: 90.124688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:20:41.233177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:40.809721Z","time spent":"423.245689ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:20:41.237064Z","caller":"traceutil/trace.go:171","msg":"trace[2105744071] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1882; }","duration":"290.996248ms","start":"2026-05-07T23:20:40.94604Z","end":"2026-05-07T23:20:41.237036Z","steps":["trace[2105744071] 'read index received' (duration: 196.484829ms)","trace[2105744071] 'applied index is now lower than readState.Index' (duration: 94.510288ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:41.237297Z","caller":"traceutil/trace.go:171","msg":"trace[1570172603] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"370.392741ms","start":"2026-05-07T23:20:40.866891Z","end":"2026-05-07T23:20:41.237284Z","steps":["trace[1570172603] 'process raft request' (duration: 370.001914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:41.237372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.332763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed5972aa-919c-475c-a577-bbc1e88a34de\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-07T23:20:41.23743Z","caller":"traceutil/trace.go:171","msg":"trace[2124428059] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed5972aa-919c-475c-a577-bbc1e88a34de; range_end:; response_count:1; response_revision:1812; }","duration":"291.421485ms","start":"2026-05-07T23:20:40.945992Z","end":"2026-05-07T23:20:41.237414Z","steps":["trace[2124428059] 'agreement among raft nodes before linearized reading' (duration: 291.321093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:41.237675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:40.866858Z","time spent":"370.696107ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:20:41.761589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.107715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:20:41.761709Z","caller":"traceutil/trace.go:171","msg":"trace[592226925] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1885; }","duration":"331.772121ms","start":"2026-05-07T23:20:41.429918Z","end":"2026-05-07T23:20:41.76169Z","steps":["trace[592226925] 'read index received' (duration: 31.401451ms)","trace[592226925] 'applied index is now lower than readState.Index' (duration: 300.36918ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:41.761845Z","caller":"traceutil/trace.go:171","msg":"trace[228397077] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"409.045825ms","start":"2026-05-07T23:20:41.352735Z","end":"2026-05-07T23:20:41.76178Z","steps":["trace[228397077] 'process raft request' (duration: 108.640645ms)","trace[228397077] 'compare' (duration: 299.84883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:20:41.761929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.019126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T23:20:41.761959Z","caller":"traceutil/trace.go:171","msg":"trace[1513821413] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1814; }","duration":"332.091487ms","start":"2026-05-07T23:20:41.429857Z","end":"2026-05-07T23:20:41.761948Z","steps":["trace[1513821413] 'agreement among raft nodes before linearized reading' (duration: 331.932405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:41.761994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:41.429835Z","time spent":"332.151168ms","remote":"127.0.0.1:38932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-05-07T23:20:41.762007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.232172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:20:41.76209Z","caller":"traceutil/trace.go:171","msg":"trace[1632148762] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1814; }","duration":"254.371694ms","start":"2026-05-07T23:20:41.5077Z","end":"2026-05-07T23:20:41.762071Z","steps":["trace[1632148762] 'agreement among raft nodes before linearized reading' (duration: 254.232581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:41.762059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:41.352708Z","time spent":"409.241998ms","remote":"127.0.0.1:39184","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-05-07T23:20:41.762303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.184689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:20:41.762343Z","caller":"traceutil/trace.go:171","msg":"trace[1666791871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1814; }","duration":"123.24657ms","start":"2026-05-07T23:20:41.639087Z","end":"2026-05-07T23:20:41.762333Z","steps":["trace[1666791871] 'agreement among raft nodes before linearized reading' (duration: 123.193099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:41.76258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.876948ms","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-05-07T23:20:41.762624Z","caller":"traceutil/trace.go:171","msg":"trace[1988077401] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1814; }","duration":"180.959608ms","start":"2026-05-07T23:20:41.581655Z","end":"2026-05-07T23:20:41.762615Z","steps":["trace[1988077401] 'agreement among raft nodes before linearized reading' (duration: 180.812946ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:41.973076Z","caller":"traceutil/trace.go:171","msg":"trace[142032513] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"128.72432ms","start":"2026-05-07T23:20:41.844313Z","end":"2026-05-07T23:20:41.973037Z","steps":["trace[142032513] 'process raft request' (duration: 128.608388ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:41.973602Z","caller":"traceutil/trace.go:171","msg":"trace[825590571] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"176.487459ms","start":"2026-05-07T23:20:41.797067Z","end":"2026-05-07T23:20:41.973554Z","steps":["trace[825590571] 'process raft request' (duration: 134.765211ms)","trace[825590571] 'compare' (duration: 40.405914ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:55.057673Z","caller":"traceutil/trace.go:171","msg":"trace[1722030486] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"194.687607ms","start":"2026-05-07T23:20:54.862951Z","end":"2026-05-07T23:20:55.057639Z","steps":["trace[1722030486] 'read index received' (duration: 194.451493ms)","trace[1722030486] 'applied index is now lower than readState.Index' (duration: 234.723µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:55.057738Z","caller":"traceutil/trace.go:171","msg":"trace[203092309] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"399.546989ms","start":"2026-05-07T23:20:54.658149Z","end":"2026-05-07T23:20:55.057696Z","steps":["trace[203092309] 'process raft request' (duration: 399.295515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:55.057943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:54.658112Z","time spent":"399.732913ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:20:55.05797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.984631ms","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-05-07T23:20:55.058019Z","caller":"traceutil/trace.go:171","msg":"trace[86763190] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"195.060692ms","start":"2026-05-07T23:20:54.862943Z","end":"2026-05-07T23:20:55.058003Z","steps":["trace[86763190] 'agreement among raft nodes before linearized reading' (duration: 194.830429ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:05.405809Z","caller":"traceutil/trace.go:171","msg":"trace[1353645145] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"288.176704ms","start":"2026-05-07T23:21:05.117609Z","end":"2026-05-07T23:21:05.405785Z","steps":["trace[1353645145] 'read index received' (duration: 287.992641ms)","trace[1353645145] 'applied index is now lower than readState.Index' (duration: 183.083µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:05.406015Z","caller":"traceutil/trace.go:171","msg":"trace[1413597509] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"301.740886ms","start":"2026-05-07T23:21:05.104262Z","end":"2026-05-07T23:21:05.406003Z","steps":["trace[1413597509] 'process raft request' (duration: 301.34969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:05.40603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.402487ms","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-05-07T23:21:05.406068Z","caller":"traceutil/trace.go:171","msg":"trace[1408734175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2001; }","duration":"288.492919ms","start":"2026-05-07T23:21:05.117562Z","end":"2026-05-07T23:21:05.406055Z","steps":["trace[1408734175] 'agreement among raft nodes before linearized reading' (duration: 288.325776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:05.406117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:05.104224Z","time spent":"301.838587ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:10.93796Z","caller":"traceutil/trace.go:171","msg":"trace[2002347948] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"121.145307ms","start":"2026-05-07T23:21:10.816779Z","end":"2026-05-07T23:21:10.937925Z","steps":["trace[2002347948] 'process raft request' (duration: 120.946414ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:11.748767Z","caller":"traceutil/trace.go:171","msg":"trace[1647158176] linearizableReadLoop","detail":"{readStateIndex:2112; appliedIndex:2110; }","duration":"298.598054ms","start":"2026-05-07T23:21:11.450142Z","end":"2026-05-07T23:21:11.748741Z","steps":["trace[1647158176] 'read index received' (duration: 280.817196ms)","trace[1647158176] 'applied index is now lower than readState.Index' (duration: 17.779428ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:11.749154Z","caller":"traceutil/trace.go:171","msg":"trace[1124178923] transaction","detail":"{read_only:false; response_revision:2032; number_of_response:1; }","duration":"314.755738ms","start":"2026-05-07T23:21:11.434367Z","end":"2026-05-07T23:21:11.749123Z","steps":["trace[1124178923] 'process raft request' (duration: 314.19696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.749386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.190534ms","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":"warn","ts":"2026-05-07T23:21:11.749889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:11.434309Z","time spent":"315.50692ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:11.750041Z","caller":"traceutil/trace.go:171","msg":"trace[191785823] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2032; }","duration":"299.837693ms","start":"2026-05-07T23:21:11.450118Z","end":"2026-05-07T23:21:11.749956Z","steps":["trace[191785823] 'agreement among raft nodes before linearized reading' (duration: 299.097082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.750298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:11.450106Z","time spent":"300.106948ms","remote":"127.0.0.1:55620","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":"warn","ts":"2026-05-07T23:21:11.767835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.578459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:21:11.767902Z","caller":"traceutil/trace.go:171","msg":"trace[263407438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2033; }","duration":"126.709172ms","start":"2026-05-07T23:21:11.641178Z","end":"2026-05-07T23:21:11.767887Z","steps":["trace[263407438] 'agreement among raft nodes before linearized reading' (duration: 126.529879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.769009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.197045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-07T23:21:11.769094Z","caller":"traceutil/trace.go:171","msg":"trace[1347544082] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2033; }","duration":"195.380698ms","start":"2026-05-07T23:21:11.573691Z","end":"2026-05-07T23:21:11.769072Z","steps":["trace[1347544082] 'agreement among raft nodes before linearized reading' (duration: 193.978236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:12.918683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.365376ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:21:12.918975Z","caller":"traceutil/trace.go:171","msg":"trace[1766959268] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2034; }","duration":"182.691601ms","start":"2026-05-07T23:21:12.736253Z","end":"2026-05-07T23:21:12.918945Z","steps":["trace[1766959268] 'range keys from in-memory index tree' (duration: 182.343426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:12.920535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.924927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:12.920868Z","caller":"traceutil/trace.go:171","msg":"trace[1229468202] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"1.041607141s","start":"2026-05-07T23:21:11.879138Z","end":"2026-05-07T23:21:12.920745Z","steps":["trace[1229468202] 'process raft request' (duration: 594.854026ms)","trace[1229468202] 'compare' (duration: 443.75317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:12.921087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:11.8791Z","time spent":"1.041934477s","remote":"127.0.0.1:39184","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-05-07T23:21:12.997344Z","caller":"traceutil/trace.go:171","msg":"trace[464831282] linearizableReadLoop","detail":"{readStateIndex:2116; appliedIndex:2114; }","duration":"887.409387ms","start":"2026-05-07T23:21:12.109913Z","end":"2026-05-07T23:21:12.997322Z","steps":["trace[464831282] 'read index received' (duration: 364.103544ms)","trace[464831282] 'applied index is now lower than readState.Index' (duration: 523.303943ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:12.9977Z","caller":"traceutil/trace.go:171","msg":"trace[1964695599] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"905.531089ms","start":"2026-05-07T23:21:12.092156Z","end":"2026-05-07T23:21:12.997687Z","steps":["trace[1964695599] 'process raft request' (duration: 904.91799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:12.997826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.092127Z","time spent":"905.61202ms","remote":"127.0.0.1:39184","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-05-07T23:21:12.998063Z","caller":"traceutil/trace.go:171","msg":"trace[1758930089] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"836.080047ms","start":"2026-05-07T23:21:12.161972Z","end":"2026-05-07T23:21:12.998052Z","steps":["trace[1758930089] 'process raft request' (duration: 835.294715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:12.998146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.161944Z","time spent":"836.151638ms","remote":"127.0.0.1:39184","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-05-07T23:21:12.998526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.594845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T23:21:12.998616Z","caller":"traceutil/trace.go:171","msg":"trace[119798089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2037; }","duration":"888.760438ms","start":"2026-05-07T23:21:12.109835Z","end":"2026-05-07T23:21:12.998595Z","steps":["trace[119798089] 'agreement among raft nodes before linearized reading' (duration: 888.487434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:12.998754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.347742ms","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-05-07T23:21:12.998815Z","caller":"traceutil/trace.go:171","msg":"trace[1129199355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2037; }","duration":"878.455864ms","start":"2026-05-07T23:21:12.120347Z","end":"2026-05-07T23:21:12.998803Z","steps":["trace[1129199355] 'agreement among raft nodes before linearized reading' (duration: 878.280321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:12.998851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.120336Z","time spent":"878.507035ms","remote":"127.0.0.1:39184","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-05-07T23:21:12.999758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.584365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T23:21:12.99981Z","caller":"traceutil/trace.go:171","msg":"trace[567777065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2037; }","duration":"814.718016ms","start":"2026-05-07T23:21:12.185079Z","end":"2026-05-07T23:21:12.999797Z","steps":["trace[567777065] 'agreement among raft nodes before linearized reading' (duration: 814.487023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:12.999843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.185065Z","time spent":"814.768298ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T23:21:13.000052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.963486ms","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-05-07T23:21:13.000091Z","caller":"traceutil/trace.go:171","msg":"trace[1047503372] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2037; }","duration":"241.027328ms","start":"2026-05-07T23:21:12.759053Z","end":"2026-05-07T23:21:13.00008Z","steps":["trace[1047503372] 'agreement among raft nodes before linearized reading' (duration: 240.918196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:13.000182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.016008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:21:13.000221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.133273ms","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-05-07T23:21:13.000255Z","caller":"traceutil/trace.go:171","msg":"trace[1624564518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2037; }","duration":"819.193583ms","start":"2026-05-07T23:21:12.181052Z","end":"2026-05-07T23:21:13.000246Z","steps":["trace[1624564518] 'agreement among raft nodes before linearized reading' (duration: 819.101632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:13.000289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.181016Z","time spent":"819.266835ms","remote":"127.0.0.1:39184","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-05-07T23:21:13.000376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.809954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-07T23:21:13.000399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.041264ms","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:5"} {"level":"info","ts":"2026-05-07T23:21:13.000416Z","caller":"traceutil/trace.go:171","msg":"trace[1280962512] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2037; }","duration":"244.892204ms","start":"2026-05-07T23:21:12.755514Z","end":"2026-05-07T23:21:13.000406Z","steps":["trace[1280962512] 'agreement among raft nodes before linearized reading' (duration: 244.764053ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:13.000431Z","caller":"traceutil/trace.go:171","msg":"trace[1352573522] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2037; }","duration":"474.139616ms","start":"2026-05-07T23:21:12.526282Z","end":"2026-05-07T23:21:13.000421Z","steps":["trace[1352573522] 'agreement among raft nodes before linearized reading' (duration: 474.088465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:13.000467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.526243Z","time spent":"474.214686ms","remote":"127.0.0.1:55912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:21:13.000253Z","caller":"traceutil/trace.go:171","msg":"trace[1199620453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2037; }","duration":"353.15193ms","start":"2026-05-07T23:21:12.647085Z","end":"2026-05-07T23:21:13.000237Z","steps":["trace[1199620453] 'agreement among raft nodes before linearized reading' (duration: 353.039408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:13.001578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.64705Z","time spent":"354.51703ms","remote":"127.0.0.1:38878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:21:13.001093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.32349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T23:21:13.001794Z","caller":"traceutil/trace.go:171","msg":"trace[1083709690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2037; }","duration":"879.072282ms","start":"2026-05-07T23:21:12.1227Z","end":"2026-05-07T23:21:13.001772Z","steps":["trace[1083709690] 'agreement among raft nodes before linearized reading' (duration: 877.240825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:13.001867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.122676Z","time spent":"879.171434ms","remote":"127.0.0.1:39184","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":"warn","ts":"2026-05-07T23:21:13.005373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:12.109801Z","time spent":"888.84538ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T23:21:13.447027Z","caller":"traceutil/trace.go:171","msg":"trace[1857799568] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"441.793625ms","start":"2026-05-07T23:21:13.00519Z","end":"2026-05-07T23:21:13.446984Z","steps":["trace[1857799568] 'process raft request' (duration: 433.960818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:13.447224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:13.005165Z","time spent":"441.945157ms","remote":"127.0.0.1:39184","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-05-07T23:21:13.45088Z","caller":"traceutil/trace.go:171","msg":"trace[422192305] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"442.928782ms","start":"2026-05-07T23:21:13.007928Z","end":"2026-05-07T23:21:13.450857Z","steps":["trace[422192305] 'process raft request' (duration: 442.869141ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:13.450905Z","caller":"traceutil/trace.go:171","msg":"trace[2093703687] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"445.228206ms","start":"2026-05-07T23:21:13.005641Z","end":"2026-05-07T23:21:13.450869Z","steps":["trace[2093703687] 'process raft request' (duration: 445.024993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:13.451023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:13.00791Z","time spent":"443.033093ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:13.451104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:13.005619Z","time spent":"445.369148ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:13.451097Z","caller":"traceutil/trace.go:171","msg":"trace[1748487089] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"443.697083ms","start":"2026-05-07T23:21:13.007343Z","end":"2026-05-07T23:21:13.45104Z","steps":["trace[1748487089] 'process raft request' (duration: 443.418739ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:13.451138Z","caller":"traceutil/trace.go:171","msg":"trace[1743014557] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"441.897127ms","start":"2026-05-07T23:21:13.009217Z","end":"2026-05-07T23:21:13.451114Z","steps":["trace[1743014557] 'process raft request' (duration: 441.612013ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:13.451185Z","caller":"traceutil/trace.go:171","msg":"trace[130782548] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"441.41182ms","start":"2026-05-07T23:21:13.009767Z","end":"2026-05-07T23:21:13.451178Z","steps":["trace[130782548] 'process raft request' (duration: 441.281448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:13.451226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:13.009755Z","time spent":"441.45085ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:13.451282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:13.009204Z","time spent":"442.002808ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:13.451293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:13.007331Z","time spent":"443.859705ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:22:10.239739Z","caller":"traceutil/trace.go:171","msg":"trace[74379638] linearizableReadLoop","detail":"{readStateIndex:2487; appliedIndex:2486; }","duration":"119.680185ms","start":"2026-05-07T23:22:10.120031Z","end":"2026-05-07T23:22:10.239711Z","steps":["trace[74379638] 'read index received' (duration: 119.463282ms)","trace[74379638] 'applied index is now lower than readState.Index' (duration: 215.553µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:10.239855Z","caller":"traceutil/trace.go:171","msg":"trace[1948375181] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"146.394585ms","start":"2026-05-07T23:22:10.093429Z","end":"2026-05-07T23:22:10.239824Z","steps":["trace[1948375181] 'process raft request' (duration: 146.096682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:10.239922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.865157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:10.24027Z","caller":"traceutil/trace.go:171","msg":"trace[72164299] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2394; }","duration":"120.250172ms","start":"2026-05-07T23:22:10.120003Z","end":"2026-05-07T23:22:10.240253Z","steps":["trace[72164299] 'agreement among raft nodes before linearized reading' (duration: 119.851567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:10.240701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.968204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-07T23:22:10.240777Z","caller":"traceutil/trace.go:171","msg":"trace[1500811493] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2394; }","duration":"113.075865ms","start":"2026-05-07T23:22:10.12768Z","end":"2026-05-07T23:22:10.240756Z","steps":["trace[1500811493] 'agreement among raft nodes before linearized reading' (duration: 112.860592ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:20.509823Z","caller":"traceutil/trace.go:171","msg":"trace[693163968] linearizableReadLoop","detail":"{readStateIndex:2596; appliedIndex:2595; }","duration":"108.680022ms","start":"2026-05-07T23:22:20.401114Z","end":"2026-05-07T23:22:20.509794Z","steps":["trace[693163968] 'read index received' (duration: 108.470199ms)","trace[693163968] 'applied index is now lower than readState.Index' (duration: 208.323µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:22:20.510175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.029165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-07T23:22:20.510825Z","caller":"traceutil/trace.go:171","msg":"trace[1565237092] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"205.019827ms","start":"2026-05-07T23:22:20.304912Z","end":"2026-05-07T23:22:20.509932Z","steps":["trace[1565237092] 'process raft request' (duration: 204.702164ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:20.511409Z","caller":"traceutil/trace.go:171","msg":"trace[1765798502] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2501; }","duration":"109.175467ms","start":"2026-05-07T23:22:20.401076Z","end":"2026-05-07T23:22:20.510251Z","steps":["trace[1765798502] 'agreement among raft nodes before linearized reading' (duration: 108.904244ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:20.622763Z","caller":"traceutil/trace.go:171","msg":"trace[146500681] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"101.981443ms","start":"2026-05-07T23:22:20.520756Z","end":"2026-05-07T23:22:20.622737Z","steps":["trace[146500681] 'process raft request' (duration: 99.23885ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:21.995856Z","caller":"traceutil/trace.go:171","msg":"trace[64106034] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"159.294089ms","start":"2026-05-07T23:22:21.836536Z","end":"2026-05-07T23:22:21.99583Z","steps":["trace[64106034] 'process raft request' (duration: 158.931954ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:22.276689Z","caller":"traceutil/trace.go:171","msg":"trace[1893055005] linearizableReadLoop","detail":"{readStateIndex:2603; appliedIndex:2601; }","duration":"437.310666ms","start":"2026-05-07T23:22:21.839352Z","end":"2026-05-07T23:22:22.276663Z","steps":["trace[1893055005] 'read index received' (duration: 156.181542ms)","trace[1893055005] 'applied index is now lower than readState.Index' (duration: 281.127594ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:22.27684Z","caller":"traceutil/trace.go:171","msg":"trace[735454272] transaction","detail":"{read_only:false; response_revision:2507; number_of_response:1; }","duration":"440.238769ms","start":"2026-05-07T23:22:21.836568Z","end":"2026-05-07T23:22:22.276807Z","steps":["trace[735454272] 'process raft request' (duration: 439.905336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:22.276978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.605279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T23:22:22.276992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:21.836549Z","time spent":"440.362632ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:22:22.277014Z","caller":"traceutil/trace.go:171","msg":"trace[799040122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2507; }","duration":"437.66624ms","start":"2026-05-07T23:22:21.839337Z","end":"2026-05-07T23:22:22.277003Z","steps":["trace[799040122] 'agreement among raft nodes before linearized reading' (duration: 437.492008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:22.277049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:21.83933Z","time spent":"437.70986ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:22:22.277145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.42646ms","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-05-07T23:22:22.277172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.425669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:22.277191Z","caller":"traceutil/trace.go:171","msg":"trace[968035087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2507; }","duration":"403.481271ms","start":"2026-05-07T23:22:21.873693Z","end":"2026-05-07T23:22:22.277175Z","steps":["trace[968035087] 'agreement among raft nodes before linearized reading' (duration: 403.356909ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:22.277198Z","caller":"traceutil/trace.go:171","msg":"trace[570665850] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2507; }","duration":"197.49603ms","start":"2026-05-07T23:22:22.079694Z","end":"2026-05-07T23:22:22.27719Z","steps":["trace[570665850] 'agreement among raft nodes before linearized reading' (duration: 197.451779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:22.277444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.47742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:22:22.277466Z","caller":"traceutil/trace.go:171","msg":"trace[165247791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2507; }","duration":"403.515212ms","start":"2026-05-07T23:22:21.873945Z","end":"2026-05-07T23:22:22.27746Z","steps":["trace[165247791] 'agreement among raft nodes before linearized reading' (duration: 403.39707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:22.277486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:21.873939Z","time spent":"403.541672ms","remote":"127.0.0.1:39184","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":"warn","ts":"2026-05-07T23:22:22.278844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:21.873685Z","time spent":"403.530701ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T23:22:46.616634Z","caller":"traceutil/trace.go:171","msg":"trace[25563105] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"165.982924ms","start":"2026-05-07T23:22:46.450626Z","end":"2026-05-07T23:22:46.616609Z","steps":["trace[25563105] 'process raft request' (duration: 165.820822ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:46.644449Z","caller":"traceutil/trace.go:171","msg":"trace[1266346875] linearizableReadLoop","detail":"{readStateIndex:2745; appliedIndex:2743; }","duration":"189.039311ms","start":"2026-05-07T23:22:46.455378Z","end":"2026-05-07T23:22:46.644417Z","steps":["trace[1266346875] 'read index received' (duration: 161.13231ms)","trace[1266346875] 'applied index is now lower than readState.Index' (duration: 27.9058ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:22:46.644678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.275463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:22:46.644703Z","caller":"traceutil/trace.go:171","msg":"trace[1033238324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2644; }","duration":"189.324574ms","start":"2026-05-07T23:22:46.455372Z","end":"2026-05-07T23:22:46.644696Z","steps":["trace[1033238324] 'agreement among raft nodes before linearized reading' (duration: 189.186132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:46.644896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.971258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:22:46.64497Z","caller":"traceutil/trace.go:171","msg":"trace[1943438315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2644; }","duration":"178.09135ms","start":"2026-05-07T23:22:46.466862Z","end":"2026-05-07T23:22:46.644953Z","steps":["trace[1943438315] 'agreement among raft nodes before linearized reading' (duration: 177.872197ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:46.644702Z","caller":"traceutil/trace.go:171","msg":"trace[1373994626] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"190.904742ms","start":"2026-05-07T23:22:46.453641Z","end":"2026-05-07T23:22:46.644546Z","steps":["trace[1373994626] 'process raft request' (duration: 190.632849ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:46.758718Z","caller":"traceutil/trace.go:171","msg":"trace[238274960] linearizableReadLoop","detail":"{readStateIndex:2746; appliedIndex:2745; }","duration":"109.298385ms","start":"2026-05-07T23:22:46.649401Z","end":"2026-05-07T23:22:46.7587Z","steps":["trace[238274960] 'read index received' (duration: 65.112384ms)","trace[238274960] 'applied index is now lower than readState.Index' (duration: 44.185041ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:46.758814Z","caller":"traceutil/trace.go:171","msg":"trace[1963250710] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"110.002063ms","start":"2026-05-07T23:22:46.648772Z","end":"2026-05-07T23:22:46.758774Z","steps":["trace[1963250710] 'process raft request' (duration: 65.833312ms)","trace[1963250710] 'compare' (duration: 43.961689ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:46.758873Z","caller":"traceutil/trace.go:171","msg":"trace[586359674] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"109.095153ms","start":"2026-05-07T23:22:46.649765Z","end":"2026-05-07T23:22:46.75886Z","steps":["trace[586359674] 'process raft request' (duration: 108.913601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:46.759004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.582928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-07T23:22:46.759363Z","caller":"traceutil/trace.go:171","msg":"trace[1941149459] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2646; }","duration":"109.974762ms","start":"2026-05-07T23:22:46.64937Z","end":"2026-05-07T23:22:46.759345Z","steps":["trace[1941149459] 'agreement among raft nodes before linearized reading' (duration: 109.407646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:47.043623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.083952ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T23:22:52.761007Z","caller":"traceutil/trace.go:171","msg":"trace[7597307] linearizableReadLoop","detail":"{readStateIndex:2776; appliedIndex:2775; }","duration":"132.58617ms","start":"2026-05-07T23:22:52.628396Z","end":"2026-05-07T23:22:52.760982Z","steps":["trace[7597307] 'read index received' (duration: 132.315087ms)","trace[7597307] 'applied index is now lower than readState.Index' (duration: 269.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:52.761107Z","caller":"traceutil/trace.go:171","msg":"trace[1835623557] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"606.422789ms","start":"2026-05-07T23:22:52.154654Z","end":"2026-05-07T23:22:52.761077Z","steps":["trace[1835623557] 'process raft request' (duration: 606.183216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:52.76128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.860724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ad6bfac2b51115\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-07T23:22:52.761329Z","caller":"traceutil/trace.go:171","msg":"trace[1614180956] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ad6bfac2b51115; range_end:; response_count:1; response_revision:2673; }","duration":"132.973015ms","start":"2026-05-07T23:22:52.628343Z","end":"2026-05-07T23:22:52.761316Z","steps":["trace[1614180956] 'agreement among raft nodes before linearized reading' (duration: 132.795203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:52.761407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:52.154635Z","time spent":"606.650801ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:22:52.761439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.206175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:52.761526Z","caller":"traceutil/trace.go:171","msg":"trace[974613309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2673; }","duration":"121.336576ms","start":"2026-05-07T23:22:52.640167Z","end":"2026-05-07T23:22:52.761503Z","steps":["trace[974613309] 'agreement among raft nodes before linearized reading' (duration: 121.198584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:52.761959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.842372ms","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-05-07T23:22:52.762008Z","caller":"traceutil/trace.go:171","msg":"trace[1646001646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2673; }","duration":"113.908734ms","start":"2026-05-07T23:22:52.648085Z","end":"2026-05-07T23:22:52.761994Z","steps":["trace[1646001646] 'agreement among raft nodes before linearized reading' (duration: 113.795613ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:52.934224Z","caller":"traceutil/trace.go:171","msg":"trace[1604866084] linearizableReadLoop","detail":"{readStateIndex:2779; appliedIndex:2777; }","duration":"153.967196ms","start":"2026-05-07T23:22:52.780233Z","end":"2026-05-07T23:22:52.9342Z","steps":["trace[1604866084] 'read index received' (duration: 58.350883ms)","trace[1604866084] 'applied index is now lower than readState.Index' (duration: 95.614843ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:52.934407Z","caller":"traceutil/trace.go:171","msg":"trace[546218296] transaction","detail":"{read_only:false; response_revision:2675; number_of_response:1; }","duration":"167.97596ms","start":"2026-05-07T23:22:52.766414Z","end":"2026-05-07T23:22:52.93439Z","steps":["trace[546218296] 'process raft request' (duration: 86.601434ms)","trace[546218296] 'compare' (duration: 80.929541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:22:52.935179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.509957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T23:22:52.935263Z","caller":"traceutil/trace.go:171","msg":"trace[974198883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2676; }","duration":"150.607249ms","start":"2026-05-07T23:22:52.78464Z","end":"2026-05-07T23:22:52.935247Z","steps":["trace[974198883] 'agreement among raft nodes before linearized reading' (duration: 150.466637ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:52.934552Z","caller":"traceutil/trace.go:171","msg":"trace[354414451] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"167.650766ms","start":"2026-05-07T23:22:52.766864Z","end":"2026-05-07T23:22:52.934515Z","steps":["trace[354414451] 'process raft request' (duration: 167.282232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:52.934776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.535372ms","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-05-07T23:22:52.935607Z","caller":"traceutil/trace.go:171","msg":"trace[1112166559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2676; }","duration":"155.409481ms","start":"2026-05-07T23:22:52.780177Z","end":"2026-05-07T23:22:52.935587Z","steps":["trace[1112166559] 'agreement among raft nodes before linearized reading' (duration: 154.487341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:52.935801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.41309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-07T23:22:52.935904Z","caller":"traceutil/trace.go:171","msg":"trace[1643464017] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2676; }","duration":"115.566793ms","start":"2026-05-07T23:22:52.820316Z","end":"2026-05-07T23:22:52.935882Z","steps":["trace[1643464017] 'agreement among raft nodes before linearized reading' (duration: 115.138178ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:58.971537Z","caller":"traceutil/trace.go:171","msg":"trace[483368121] linearizableReadLoop","detail":"{readStateIndex:2809; appliedIndex:2808; }","duration":"330.011753ms","start":"2026-05-07T23:22:58.641497Z","end":"2026-05-07T23:22:58.971508Z","steps":["trace[483368121] 'read index received' (duration: 329.812321ms)","trace[483368121] 'applied index is now lower than readState.Index' (duration: 198.252µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:58.97187Z","caller":"traceutil/trace.go:171","msg":"trace[355435428] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"447.811669ms","start":"2026-05-07T23:22:58.524044Z","end":"2026-05-07T23:22:58.971855Z","steps":["trace[355435428] 'process raft request' (duration: 447.334494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:58.972015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:58.524012Z","time spent":"447.898971ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:22:58.9722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.670765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:58.972345Z","caller":"traceutil/trace.go:171","msg":"trace[2057193992] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2705; }","duration":"325.862428ms","start":"2026-05-07T23:22:58.64645Z","end":"2026-05-07T23:22:58.972313Z","steps":["trace[2057193992] 'agreement among raft nodes before linearized reading' (duration: 325.600295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:58.972402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:58.64644Z","time spent":"325.947039ms","remote":"127.0.0.1:39316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:22:58.972397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.876612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:58.972479Z","caller":"traceutil/trace.go:171","msg":"trace[1506072051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2705; }","duration":"330.992773ms","start":"2026-05-07T23:22:58.641465Z","end":"2026-05-07T23:22:58.972458Z","steps":["trace[1506072051] 'agreement among raft nodes before linearized reading' (duration: 330.874372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:58.972514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:58.641453Z","time spent":"331.052884ms","remote":"127.0.0.1:38878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:22:58.97263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.567164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T23:22:58.97267Z","caller":"traceutil/trace.go:171","msg":"trace[773017180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2705; }","duration":"183.662744ms","start":"2026-05-07T23:22:58.788996Z","end":"2026-05-07T23:22:58.972659Z","steps":["trace[773017180] 'agreement among raft nodes before linearized reading' (duration: 183.537283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:57.549216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.923081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T23:23:57.5493Z","caller":"traceutil/trace.go:171","msg":"trace[124492642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3015; }","duration":"137.066986ms","start":"2026-05-07T23:23:57.412216Z","end":"2026-05-07T23:23:57.549283Z","steps":["trace[124492642] 'range keys from in-memory index tree' (duration: 136.738175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:57.549681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.040652ms","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-05-07T23:23:57.549787Z","caller":"traceutil/trace.go:171","msg":"trace[1941343174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3015; }","duration":"136.191267ms","start":"2026-05-07T23:23:57.41357Z","end":"2026-05-07T23:23:57.549762Z","steps":["trace[1941343174] 'range keys from in-memory index tree' (duration: 135.837946ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:57.744657Z","caller":"traceutil/trace.go:171","msg":"trace[1024423767] linearizableReadLoop","detail":"{readStateIndex:3135; appliedIndex:3134; }","duration":"116.141361ms","start":"2026-05-07T23:23:57.628492Z","end":"2026-05-07T23:23:57.744634Z","steps":["trace[1024423767] 'read index received' (duration: 115.907794ms)","trace[1024423767] 'applied index is now lower than readState.Index' (duration: 232.587µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:23:57.74499Z","caller":"traceutil/trace.go:171","msg":"trace[1523922115] transaction","detail":"{read_only:false; response_revision:3018; number_of_response:1; }","duration":"152.010947ms","start":"2026-05-07T23:23:57.592964Z","end":"2026-05-07T23:23:57.744975Z","steps":["trace[1523922115] 'process raft request' (duration: 151.461099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:57.745344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.826813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ad6bfac2b51115\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-07T23:23:57.745395Z","caller":"traceutil/trace.go:171","msg":"trace[142911794] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ad6bfac2b51115; range_end:; response_count:1; response_revision:3018; }","duration":"116.948677ms","start":"2026-05-07T23:23:57.628433Z","end":"2026-05-07T23:23:57.745381Z","steps":["trace[142911794] 'agreement among raft nodes before linearized reading' (duration: 116.757691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:57.746985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.561416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:23:57.747048Z","caller":"traceutil/trace.go:171","msg":"trace[212898801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3019; }","duration":"107.657459ms","start":"2026-05-07T23:23:57.639375Z","end":"2026-05-07T23:23:57.747033Z","steps":["trace[212898801] 'agreement among raft nodes before linearized reading' (duration: 107.551225ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:57.102671Z","caller":"traceutil/trace.go:171","msg":"trace[953754621] transaction","detail":"{read_only:false; response_revision:3331; number_of_response:1; }","duration":"116.424313ms","start":"2026-05-07T23:24:56.986208Z","end":"2026-05-07T23:24:57.102632Z","steps":["trace[953754621] 'process raft request' (duration: 100.625849ms)","trace[953754621] 'compare' (duration: 15.412685ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:24:57.102785Z","caller":"traceutil/trace.go:171","msg":"trace[1313835650] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3332; }","duration":"104.475893ms","start":"2026-05-07T23:24:56.998274Z","end":"2026-05-07T23:24:57.10275Z","steps":["trace[1313835650] 'process raft request' (duration: 104.428582ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:57.10295Z","caller":"traceutil/trace.go:171","msg":"trace[359314021] transaction","detail":"{read_only:false; response_revision:3332; number_of_response:1; }","duration":"105.328675ms","start":"2026-05-07T23:24:56.997583Z","end":"2026-05-07T23:24:57.102912Z","steps":["trace[359314021] 'process raft request' (duration: 104.988777ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:59.235039Z","caller":"traceutil/trace.go:171","msg":"trace[180183415] transaction","detail":"{read_only:false; response_revision:3778; number_of_response:1; }","duration":"104.093427ms","start":"2026-05-07T23:25:59.13091Z","end":"2026-05-07T23:25:59.235003Z","steps":["trace[180183415] 'process raft request' (duration: 103.897633ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:59.535336Z","caller":"traceutil/trace.go:171","msg":"trace[1990673958] transaction","detail":"{read_only:false; response_revision:3783; number_of_response:1; }","duration":"124.167783ms","start":"2026-05-07T23:25:59.411141Z","end":"2026-05-07T23:25:59.535309Z","steps":["trace[1990673958] 'process raft request' (duration: 123.808736ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:59.561099Z","caller":"traceutil/trace.go:171","msg":"trace[2129393760] transaction","detail":"{read_only:false; response_revision:3784; number_of_response:1; }","duration":"147.195789ms","start":"2026-05-07T23:25:59.413866Z","end":"2026-05-07T23:25:59.561062Z","steps":["trace[2129393760] 'process raft request' (duration: 146.422234ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:59.769639Z","caller":"traceutil/trace.go:171","msg":"trace[1387094975] transaction","detail":"{read_only:false; response_revision:3786; number_of_response:1; }","duration":"143.894162ms","start":"2026-05-07T23:25:59.625713Z","end":"2026-05-07T23:25:59.769607Z","steps":["trace[1387094975] 'process raft request' (duration: 96.018563ms)","trace[1387094975] 'compare' (duration: 47.724656ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:00.006Z","caller":"traceutil/trace.go:171","msg":"trace[675145732] transaction","detail":"{read_only:false; response_revision:3789; number_of_response:1; }","duration":"199.869975ms","start":"2026-05-07T23:25:59.806075Z","end":"2026-05-07T23:26:00.005945Z","steps":["trace[675145732] 'process raft request' (duration: 105.083696ms)","trace[675145732] 'compare' (duration: 90.36432ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:00.007507Z","caller":"traceutil/trace.go:171","msg":"trace[910463276] linearizableReadLoop","detail":"{readStateIndex:3937; appliedIndex:3936; }","duration":"142.131827ms","start":"2026-05-07T23:25:59.865247Z","end":"2026-05-07T23:26:00.007379Z","steps":["trace[910463276] 'read index received' (duration: 46.059112ms)","trace[910463276] 'applied index is now lower than readState.Index' (duration: 96.066275ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:00.007754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.446423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:00.007841Z","caller":"traceutil/trace.go:171","msg":"trace[2024403842] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:3789; }","duration":"142.642637ms","start":"2026-05-07T23:25:59.865173Z","end":"2026-05-07T23:26:00.007816Z","steps":["trace[2024403842] 'agreement among raft nodes before linearized reading' (duration: 142.435453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:00.007987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.107245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:00.008058Z","caller":"traceutil/trace.go:171","msg":"trace[310599974] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; range_end:; response_count:0; response_revision:3790; }","duration":"135.257837ms","start":"2026-05-07T23:25:59.872782Z","end":"2026-05-07T23:26:00.008039Z","steps":["trace[310599974] 'agreement among raft nodes before linearized reading' (duration: 135.099094ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:00.008649Z","caller":"traceutil/trace.go:171","msg":"trace[890721318] transaction","detail":"{read_only:false; response_revision:3790; number_of_response:1; }","duration":"139.026283ms","start":"2026-05-07T23:25:59.869597Z","end":"2026-05-07T23:26:00.008623Z","steps":["trace[890721318] 'process raft request' (duration: 136.186375ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:44.483928Z","caller":"traceutil/trace.go:171","msg":"trace[945780736] transaction","detail":"{read_only:false; response_revision:4388; number_of_response:1; }","duration":"132.414636ms","start":"2026-05-07T23:26:44.351497Z","end":"2026-05-07T23:26:44.483912Z","steps":["trace[945780736] 'process raft request' (duration: 132.322234ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:44.491097Z","caller":"traceutil/trace.go:171","msg":"trace[840374245] transaction","detail":"{read_only:false; response_revision:4389; number_of_response:1; }","duration":"116.104232ms","start":"2026-05-07T23:26:44.374963Z","end":"2026-05-07T23:26:44.491067Z","steps":["trace[840374245] 'process raft request' (duration: 115.931109ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:45.654519Z","caller":"traceutil/trace.go:171","msg":"trace[2019173969] transaction","detail":"{read_only:false; response_revision:4433; number_of_response:1; }","duration":"105.15791ms","start":"2026-05-07T23:26:45.549328Z","end":"2026-05-07T23:26:45.654486Z","steps":["trace[2019173969] 'process raft request' (duration: 80.274443ms)","trace[2019173969] 'compare' (duration: 24.703844ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:47.017169Z","caller":"traceutil/trace.go:171","msg":"trace[1826712366] transaction","detail":"{read_only:false; response_revision:4475; number_of_response:1; }","duration":"119.3001ms","start":"2026-05-07T23:26:46.897815Z","end":"2026-05-07T23:26:47.017115Z","steps":["trace[1826712366] 'process raft request' (duration: 119.199928ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:47.057816Z","caller":"traceutil/trace.go:171","msg":"trace[1031709226] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4475; }","duration":"111.63311ms","start":"2026-05-07T23:26:46.94614Z","end":"2026-05-07T23:26:47.057773Z","steps":["trace[1031709226] 'process raft request' (duration: 111.421646ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:47.944518Z","caller":"traceutil/trace.go:171","msg":"trace[243611606] linearizableReadLoop","detail":"{readStateIndex:4658; appliedIndex:4657; }","duration":"119.439488ms","start":"2026-05-07T23:26:47.825055Z","end":"2026-05-07T23:26:47.944495Z","steps":["trace[243611606] 'read index received' (duration: 119.281626ms)","trace[243611606] 'applied index is now lower than readState.Index' (duration: 157.192µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:47.944744Z","caller":"traceutil/trace.go:171","msg":"trace[45305650] transaction","detail":"{read_only:false; response_revision:4482; number_of_response:1; }","duration":"163.884433ms","start":"2026-05-07T23:26:47.780839Z","end":"2026-05-07T23:26:47.944724Z","steps":["trace[45305650] 'process raft request' (duration: 163.541687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:47.944831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.758844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:26:47.945267Z","caller":"traceutil/trace.go:171","msg":"trace[1402816885] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4482; }","duration":"120.251482ms","start":"2026-05-07T23:26:47.825004Z","end":"2026-05-07T23:26:47.945255Z","steps":["trace[1402816885] 'agreement among raft nodes before linearized reading' (duration: 119.764203ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:51.41328Z","caller":"traceutil/trace.go:171","msg":"trace[1014000619] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"181.789335ms","start":"2026-05-07T23:26:51.231464Z","end":"2026-05-07T23:26:51.413253Z","steps":["trace[1014000619] 'process raft request' (duration: 181.52837ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.372257Z","caller":"traceutil/trace.go:171","msg":"trace[402000531] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"365.217463ms","start":"2026-05-07T23:26:52.007009Z","end":"2026-05-07T23:26:52.372226Z","steps":["trace[402000531] 'process raft request' (duration: 364.955588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.37243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.006988Z","time spent":"365.354336ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15286,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:26:52.640297Z","caller":"traceutil/trace.go:171","msg":"trace[2080076041] linearizableReadLoop","detail":"{readStateIndex:4773; appliedIndex:4771; }","duration":"513.846854ms","start":"2026-05-07T23:26:52.126423Z","end":"2026-05-07T23:26:52.64027Z","steps":["trace[2080076041] 'read index received' (duration: 245.665607ms)","trace[2080076041] 'applied index is now lower than readState.Index' (duration: 268.179727ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:52.640569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.081558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:26:52.640625Z","caller":"traceutil/trace.go:171","msg":"trace[1413874439] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4595; }","duration":"514.260111ms","start":"2026-05-07T23:26:52.126351Z","end":"2026-05-07T23:26:52.640611Z","steps":["trace[1413874439] 'agreement among raft nodes before linearized reading' (duration: 514.061417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.640672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.126332Z","time spent":"514.328322ms","remote":"127.0.0.1:58082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:26:52.64037Z","caller":"traceutil/trace.go:171","msg":"trace[1005150626] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"630.444727ms","start":"2026-05-07T23:26:52.009887Z","end":"2026-05-07T23:26:52.640332Z","steps":["trace[1005150626] 'process raft request' (duration: 630.194413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.641052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:52.009879Z","time spent":"631.042528ms","remote":"127.0.0.1:55964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:26:52.658733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.009495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:26:52.658795Z","caller":"traceutil/trace.go:171","msg":"trace[619909559] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4597; }","duration":"172.108457ms","start":"2026-05-07T23:26:52.486673Z","end":"2026-05-07T23:26:52.658782Z","steps":["trace[619909559] 'agreement among raft nodes before linearized reading' (duration: 172.001135ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.659018Z","caller":"traceutil/trace.go:171","msg":"trace[608105748] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"123.211931ms","start":"2026-05-07T23:26:52.535787Z","end":"2026-05-07T23:26:52.658999Z","steps":["trace[608105748] 'process raft request' (duration: 122.533589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.659232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.205262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-07T23:26:52.659331Z","caller":"traceutil/trace.go:171","msg":"trace[1762509366] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4597; }","duration":"274.367445ms","start":"2026-05-07T23:26:52.384947Z","end":"2026-05-07T23:26:52.659315Z","steps":["trace[1762509366] 'agreement among raft nodes before linearized reading' (duration: 274.036179ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.659262Z","caller":"traceutil/trace.go:171","msg":"trace[622436378] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"120.066475ms","start":"2026-05-07T23:26:52.539172Z","end":"2026-05-07T23:26:52.659238Z","steps":["trace[622436378] 'process raft request' (duration: 119.323292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.65972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.762543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:56740"} {"level":"info","ts":"2026-05-07T23:26:52.659797Z","caller":"traceutil/trace.go:171","msg":"trace[1703467735] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4597; }","duration":"274.880444ms","start":"2026-05-07T23:26:52.384902Z","end":"2026-05-07T23:26:52.659783Z","steps":["trace[1703467735] 'agreement among raft nodes before linearized reading' (duration: 274.685231ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.825878Z","caller":"traceutil/trace.go:171","msg":"trace[1205569934] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"161.536528ms","start":"2026-05-07T23:26:52.66431Z","end":"2026-05-07T23:26:52.825846Z","steps":["trace[1205569934] 'process raft request' (duration: 161.334304ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.846986Z","caller":"traceutil/trace.go:171","msg":"trace[1234077007] linearizableReadLoop","detail":"{readStateIndex:4777; appliedIndex:4775; }","duration":"182.210935ms","start":"2026-05-07T23:26:52.66475Z","end":"2026-05-07T23:26:52.846961Z","steps":["trace[1234077007] 'read index received' (duration: 161.05535ms)","trace[1234077007] 'applied index is now lower than readState.Index' (duration: 21.154475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:52.847319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.545191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T23:26:52.847358Z","caller":"traceutil/trace.go:171","msg":"trace[1337579297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4601; }","duration":"182.638032ms","start":"2026-05-07T23:26:52.66471Z","end":"2026-05-07T23:26:52.847348Z","steps":["trace[1337579297] 'agreement among raft nodes before linearized reading' (duration: 182.351597ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.847857Z","caller":"traceutil/trace.go:171","msg":"trace[449013963] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"183.438267ms","start":"2026-05-07T23:26:52.664409Z","end":"2026-05-07T23:26:52.847847Z","steps":["trace[449013963] 'process raft request' (duration: 182.294227ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.84804Z","caller":"traceutil/trace.go:171","msg":"trace[2105073642] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"181.311858ms","start":"2026-05-07T23:26:52.666719Z","end":"2026-05-07T23:26:52.848031Z","steps":["trace[2105073642] 'process raft request' (duration: 180.141647ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:52.848297Z","caller":"traceutil/trace.go:171","msg":"trace[587200202] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"170.339244ms","start":"2026-05-07T23:26:52.677948Z","end":"2026-05-07T23:26:52.848288Z","steps":["trace[587200202] 'process raft request' (duration: 168.95726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.848905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.124653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c4e85d1-9fb5-48d3-8651-8a53ae74018e\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-07T23:26:52.848941Z","caller":"traceutil/trace.go:171","msg":"trace[2091720092] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c4e85d1-9fb5-48d3-8651-8a53ae74018e; range_end:; response_count:1; response_revision:4601; }","duration":"113.175283ms","start":"2026-05-07T23:26:52.735756Z","end":"2026-05-07T23:26:52.848931Z","steps":["trace[2091720092] 'agreement among raft nodes before linearized reading' (duration: 113.00069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.849056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.193742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T23:26:52.849154Z","caller":"traceutil/trace.go:171","msg":"trace[1019295321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4601; }","duration":"153.351544ms","start":"2026-05-07T23:26:52.695782Z","end":"2026-05-07T23:26:52.849133Z","steps":["trace[1019295321] 'agreement among raft nodes before linearized reading' (duration: 152.981948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:52.84929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.805913ms","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-05-07T23:26:52.849392Z","caller":"traceutil/trace.go:171","msg":"trace[2119122132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4601; }","duration":"140.962226ms","start":"2026-05-07T23:26:52.708408Z","end":"2026-05-07T23:26:52.84937Z","steps":["trace[2119122132] 'agreement among raft nodes before linearized reading' (duration: 140.61189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:55.139472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.534884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:26:55.139669Z","caller":"traceutil/trace.go:171","msg":"trace[56659801] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"278.407301ms","start":"2026-05-07T23:26:54.861231Z","end":"2026-05-07T23:26:55.139638Z","steps":["trace[56659801] 'process raft request' (duration: 96.549501ms)","trace[56659801] 'compare' (duration: 181.087767ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:55.196084Z","caller":"traceutil/trace.go:171","msg":"trace[934517101] linearizableReadLoop","detail":"{readStateIndex:4834; appliedIndex:4831; }","duration":"315.41027ms","start":"2026-05-07T23:26:54.880632Z","end":"2026-05-07T23:26:55.196042Z","steps":["trace[934517101] 'read index received' (duration: 77.161559ms)","trace[934517101] 'applied index is now lower than readState.Index' (duration: 238.246861ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:55.19612Z","caller":"traceutil/trace.go:171","msg":"trace[572109458] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"333.976358ms","start":"2026-05-07T23:26:54.862111Z","end":"2026-05-07T23:26:55.196087Z","steps":["trace[572109458] 'process raft request' (duration: 333.715814ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:55.196238Z","caller":"traceutil/trace.go:171","msg":"trace[2063326479] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"332.307728ms","start":"2026-05-07T23:26:54.863891Z","end":"2026-05-07T23:26:55.196199Z","steps":["trace[2063326479] 'process raft request' (duration: 332.082414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:55.196312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:54.862081Z","time spent":"334.10185ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:55.196382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:54.863884Z","time spent":"332.38824ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:55.196569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.193272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-07T23:26:55.19657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.597921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3c4e85d1-9fb5-48d3-8651-8a53ae74018e\" ","response":"range_response_count:1 size:4080"} {"level":"info","ts":"2026-05-07T23:26:55.196631Z","caller":"traceutil/trace.go:171","msg":"trace[1374760052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4653; }","duration":"313.258033ms","start":"2026-05-07T23:26:54.883355Z","end":"2026-05-07T23:26:55.196613Z","steps":["trace[1374760052] 'agreement among raft nodes before linearized reading' (duration: 313.08711ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:55.196635Z","caller":"traceutil/trace.go:171","msg":"trace[1691970701] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3c4e85d1-9fb5-48d3-8651-8a53ae74018e; range_end:; response_count:1; response_revision:4653; }","duration":"186.702263ms","start":"2026-05-07T23:26:55.009918Z","end":"2026-05-07T23:26:55.19662Z","steps":["trace[1691970701] 'agreement among raft nodes before linearized reading' (duration: 186.52171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:55.196659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:54.883318Z","time spent":"313.336024ms","remote":"127.0.0.1:39184","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-05-07T23:26:55.196819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.140581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-07T23:26:55.19678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.151714ms","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-05-07T23:26:55.196838Z","caller":"traceutil/trace.go:171","msg":"trace[1040998745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4653; }","duration":"267.186702ms","start":"2026-05-07T23:26:54.929645Z","end":"2026-05-07T23:26:55.196832Z","steps":["trace[1040998745] 'agreement among raft nodes before linearized reading' (duration: 267.10434ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:55.19687Z","caller":"traceutil/trace.go:171","msg":"trace[878137747] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4653; }","duration":"316.287416ms","start":"2026-05-07T23:26:54.88057Z","end":"2026-05-07T23:26:55.196857Z","steps":["trace[878137747] 'agreement among raft nodes before linearized reading' (duration: 316.017562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:55.196922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:54.880549Z","time spent":"316.359707ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T23:26:55.344401Z","caller":"traceutil/trace.go:171","msg":"trace[1696031420] transaction","detail":"{read_only:false; response_revision:4655; number_of_response:1; }","duration":"142.2078ms","start":"2026-05-07T23:26:55.202167Z","end":"2026-05-07T23:26:55.344375Z","steps":["trace[1696031420] 'process raft request' (duration: 142.112928ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:55.344444Z","caller":"traceutil/trace.go:171","msg":"trace[1012153202] transaction","detail":"{read_only:false; response_revision:4656; number_of_response:1; }","duration":"141.294964ms","start":"2026-05-07T23:26:55.203127Z","end":"2026-05-07T23:26:55.344422Z","steps":["trace[1012153202] 'process raft request' (duration: 141.205843ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:55.344509Z","caller":"traceutil/trace.go:171","msg":"trace[966387987] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"142.531665ms","start":"2026-05-07T23:26:55.201935Z","end":"2026-05-07T23:26:55.344466Z","steps":["trace[966387987] 'process raft request' (duration: 126.565115ms)","trace[966387987] 'compare' (duration: 15.622574ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:59.875623Z","caller":"traceutil/trace.go:171","msg":"trace[1905431166] linearizableReadLoop","detail":"{readStateIndex:4946; appliedIndex:4945; }","duration":"518.640912ms","start":"2026-05-07T23:26:59.356959Z","end":"2026-05-07T23:26:59.8756Z","steps":["trace[1905431166] 'read index received' (duration: 431.598025ms)","trace[1905431166] 'applied index is now lower than readState.Index' (duration: 87.041757ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:59.875845Z","caller":"traceutil/trace.go:171","msg":"trace[78570170] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"564.99681ms","start":"2026-05-07T23:26:59.310835Z","end":"2026-05-07T23:26:59.875832Z","steps":["trace[78570170] 'process raft request' (duration: 477.78507ms)","trace[78570170] 'compare' (duration: 86.859234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:59.875952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.310809Z","time spent":"565.071332ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:59.876199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.251403ms","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-05-07T23:26:59.876235Z","caller":"traceutil/trace.go:171","msg":"trace[1100335852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4764; }","duration":"519.327634ms","start":"2026-05-07T23:26:59.356899Z","end":"2026-05-07T23:26:59.876226Z","steps":["trace[1100335852] 'agreement among raft nodes before linearized reading' (duration: 519.214182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.876268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.356878Z","time spent":"519.383765ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:26:59.876465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.793965ms","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-05-07T23:26:59.876497Z","caller":"traceutil/trace.go:171","msg":"trace[850630712] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4764; }","duration":"518.852676ms","start":"2026-05-07T23:26:59.357637Z","end":"2026-05-07T23:26:59.87649Z","steps":["trace[850630712] 'agreement among raft nodes before linearized reading' (duration: 518.770754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.876537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.357624Z","time spent":"518.892566ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T23:26:59.876878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.473436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:26:59.876916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.009638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T23:26:59.876947Z","caller":"traceutil/trace.go:171","msg":"trace[162818145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4764; }","duration":"227.590108ms","start":"2026-05-07T23:26:59.64934Z","end":"2026-05-07T23:26:59.87693Z","steps":["trace[162818145] 'agreement among raft nodes before linearized reading' (duration: 227.433555ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:59.87698Z","caller":"traceutil/trace.go:171","msg":"trace[1602964845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4764; }","duration":"519.123661ms","start":"2026-05-07T23:26:59.357843Z","end":"2026-05-07T23:26:59.876966Z","steps":["trace[1602964845] 'agreement among raft nodes before linearized reading' (duration: 518.873307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.876917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.202768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c99b6c5-6adf-45ea-aa94-29f551ed779d\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-05-07T23:26:59.877027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.357827Z","time spent":"519.193682ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T23:26:59.877028Z","caller":"traceutil/trace.go:171","msg":"trace[815782850] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c99b6c5-6adf-45ea-aa94-29f551ed779d; range_end:; response_count:1; response_revision:4764; }","duration":"423.31208ms","start":"2026-05-07T23:26:59.453704Z","end":"2026-05-07T23:26:59.877016Z","steps":["trace[815782850] 'agreement among raft nodes before linearized reading' (duration: 423.145687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:59.877065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:59.453683Z","time spent":"423.371921ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c99b6c5-6adf-45ea-aa94-29f551ed779d\" "} {"level":"info","ts":"2026-05-07T23:27:00.128109Z","caller":"traceutil/trace.go:171","msg":"trace[1247447093] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"246.834898ms","start":"2026-05-07T23:26:59.881242Z","end":"2026-05-07T23:27:00.128077Z","steps":["trace[1247447093] 'process raft request' (duration: 246.483422ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:00.157508Z","caller":"traceutil/trace.go:171","msg":"trace[839210518] linearizableReadLoop","detail":"{readStateIndex:4950; appliedIndex:4946; }","duration":"112.767332ms","start":"2026-05-07T23:27:00.044715Z","end":"2026-05-07T23:27:00.157482Z","steps":["trace[839210518] 'read index received' (duration: 83.234998ms)","trace[839210518] 'applied index is now lower than readState.Index' (duration: 29.530724ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:00.157645Z","caller":"traceutil/trace.go:171","msg":"trace[1295521033] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"274.595532ms","start":"2026-05-07T23:26:59.883012Z","end":"2026-05-07T23:27:00.157608Z","steps":["trace[1295521033] 'process raft request' (duration: 274.353016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:00.157687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.957556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:00.157734Z","caller":"traceutil/trace.go:171","msg":"trace[1239336960] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:4768; }","duration":"113.086519ms","start":"2026-05-07T23:27:00.044633Z","end":"2026-05-07T23:27:00.15772Z","steps":["trace[1239336960] 'agreement among raft nodes before linearized reading' (duration: 112.972307ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:00.157867Z","caller":"traceutil/trace.go:171","msg":"trace[311955421] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"275.564349ms","start":"2026-05-07T23:26:59.882291Z","end":"2026-05-07T23:27:00.157856Z","steps":["trace[311955421] 'process raft request' (duration: 274.954177ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:00.158059Z","caller":"traceutil/trace.go:171","msg":"trace[722017692] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"274.509601ms","start":"2026-05-07T23:26:59.88351Z","end":"2026-05-07T23:27:00.15802Z","steps":["trace[722017692] 'process raft request' (duration: 273.907179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.085261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.353808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c99b6c5-6adf-45ea-aa94-29f551ed779d\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-05-07T23:27:01.085357Z","caller":"traceutil/trace.go:171","msg":"trace[1101289100] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c99b6c5-6adf-45ea-aa94-29f551ed779d; range_end:; response_count:1; response_revision:4771; }","duration":"202.507201ms","start":"2026-05-07T23:27:00.882828Z","end":"2026-05-07T23:27:01.085335Z","steps":["trace[1101289100] 'range keys from in-memory index tree' (duration: 202.104214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.085471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.000274ms","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-05-07T23:27:01.085595Z","caller":"traceutil/trace.go:171","msg":"trace[925227184] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4771; }","duration":"201.184687ms","start":"2026-05-07T23:27:00.88438Z","end":"2026-05-07T23:27:01.085565Z","steps":["trace[925227184] 'range keys from in-memory index tree' (duration: 200.646238ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:01.34176Z","caller":"traceutil/trace.go:171","msg":"trace[867808142] linearizableReadLoop","detail":"{readStateIndex:4954; appliedIndex:4953; }","duration":"222.418311ms","start":"2026-05-07T23:27:01.119316Z","end":"2026-05-07T23:27:01.341734Z","steps":["trace[867808142] 'read index received' (duration: 222.229148ms)","trace[867808142] 'applied index is now lower than readState.Index' (duration: 187.883µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:01.34186Z","caller":"traceutil/trace.go:171","msg":"trace[1289511314] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"251.235441ms","start":"2026-05-07T23:27:01.090596Z","end":"2026-05-07T23:27:01.341832Z","steps":["trace[1289511314] 'process raft request' (duration: 250.973677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.341939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.607495ms","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-05-07T23:27:01.342019Z","caller":"traceutil/trace.go:171","msg":"trace[1044459521] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4772; }","duration":"222.748977ms","start":"2026-05-07T23:27:01.119253Z","end":"2026-05-07T23:27:01.342002Z","steps":["trace[1044459521] 'agreement among raft nodes before linearized reading' (duration: 222.607945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.342033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.577549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T23:27:01.342083Z","caller":"traceutil/trace.go:171","msg":"trace[1685045159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4772; }","duration":"182.726673ms","start":"2026-05-07T23:27:01.159344Z","end":"2026-05-07T23:27:01.34207Z","steps":["trace[1685045159] 'agreement among raft nodes before linearized reading' (duration: 182.544629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.342168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.744044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-07T23:27:01.342208Z","caller":"traceutil/trace.go:171","msg":"trace[2138213262] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4772; }","duration":"113.836226ms","start":"2026-05-07T23:27:01.228361Z","end":"2026-05-07T23:27:01.342198Z","steps":["trace[2138213262] 'agreement among raft nodes before linearized reading' (duration: 113.704923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:01.342426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.247432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:27:01.342472Z","caller":"traceutil/trace.go:171","msg":"trace[1862470492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4772; }","duration":"114.330805ms","start":"2026-05-07T23:27:01.228131Z","end":"2026-05-07T23:27:01.342462Z","steps":["trace[1862470492] 'agreement among raft nodes before linearized reading' (duration: 114.07593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:02.740631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.293613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:02.740702Z","caller":"traceutil/trace.go:171","msg":"trace[649806336] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"265.369958ms","start":"2026-05-07T23:27:02.475319Z","end":"2026-05-07T23:27:02.740689Z","steps":["trace[649806336] 'process raft request' (duration: 76.964933ms)","trace[649806336] 'compare' (duration: 188.219992ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:03.035728Z","caller":"traceutil/trace.go:171","msg":"trace[390376505] linearizableReadLoop","detail":"{readStateIndex:4981; appliedIndex:4980; }","duration":"101.120181ms","start":"2026-05-07T23:27:02.93456Z","end":"2026-05-07T23:27:03.03568Z","steps":["trace[390376505] 'read index received' (duration: 26.667282ms)","trace[390376505] 'applied index is now lower than readState.Index' (duration: 74.452129ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:03.035818Z","caller":"traceutil/trace.go:171","msg":"trace[1720647631] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"121.804089ms","start":"2026-05-07T23:27:02.913986Z","end":"2026-05-07T23:27:03.03579Z","steps":["trace[1720647631] 'process raft request' (duration: 121.554255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:03.036034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.461056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-07T23:27:03.03609Z","caller":"traceutil/trace.go:171","msg":"trace[1187703084] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4797; }","duration":"101.584459ms","start":"2026-05-07T23:27:02.934489Z","end":"2026-05-07T23:27:03.036073Z","steps":["trace[1187703084] 'agreement among raft nodes before linearized reading' (duration: 101.346375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:03.168383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.49291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:03.168699Z","caller":"traceutil/trace.go:171","msg":"trace[2118199803] linearizableReadLoop","detail":"{readStateIndex:4984; appliedIndex:4981; }","duration":"127.476554ms","start":"2026-05-07T23:27:03.041206Z","end":"2026-05-07T23:27:03.168682Z","steps":["trace[2118199803] 'read index received' (duration: 12.599928ms)","trace[2118199803] 'applied index is now lower than readState.Index' (duration: 114.875636ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:03.168709Z","caller":"traceutil/trace.go:171","msg":"trace[669804133] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"127.84688ms","start":"2026-05-07T23:27:03.04082Z","end":"2026-05-07T23:27:03.168667Z","steps":["trace[669804133] 'process raft request' (duration: 127.678757ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:03.16883Z","caller":"traceutil/trace.go:171","msg":"trace[1775789739] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"126.269194ms","start":"2026-05-07T23:27:03.0425Z","end":"2026-05-07T23:27:03.168769Z","steps":["trace[1775789739] 'process raft request' (duration: 126.102601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:03.169018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.806551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-438f17d3-dbe8-46ee-82e0-b5641ffa3d01\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-07T23:27:03.169027Z","caller":"traceutil/trace.go:171","msg":"trace[1085775728] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"129.635793ms","start":"2026-05-07T23:27:03.039375Z","end":"2026-05-07T23:27:03.169011Z","steps":["trace[1085775728] 'process raft request' (duration: 14.42093ms)","trace[1085775728] 'compare' (duration: 114.373428ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:03.169058Z","caller":"traceutil/trace.go:171","msg":"trace[687482686] range","detail":"{range_begin:/registry/persistentvolumes/pvc-438f17d3-dbe8-46ee-82e0-b5641ffa3d01; range_end:; response_count:1; response_revision:4802; }","duration":"127.874961ms","start":"2026-05-07T23:27:03.041172Z","end":"2026-05-07T23:27:03.169047Z","steps":["trace[687482686] 'agreement among raft nodes before linearized reading' (duration: 127.742779ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:03.169243Z","caller":"traceutil/trace.go:171","msg":"trace[995192967] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"128.245608ms","start":"2026-05-07T23:27:03.040984Z","end":"2026-05-07T23:27:03.169229Z","steps":["trace[995192967] 'process raft request' (duration: 127.576805ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:03.168827Z","caller":"traceutil/trace.go:171","msg":"trace[400976792] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"125.478689ms","start":"2026-05-07T23:27:03.043309Z","end":"2026-05-07T23:27:03.168787Z","steps":["trace[400976792] 'process raft request' (duration: 125.335057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:03.169408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.724049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-05-07T23:27:03.169453Z","caller":"traceutil/trace.go:171","msg":"trace[1313916568] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4802; }","duration":"127.81204ms","start":"2026-05-07T23:27:03.041627Z","end":"2026-05-07T23:27:03.16944Z","steps":["trace[1313916568] 'agreement among raft nodes before linearized reading' (duration: 127.652317ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:07.010609Z","caller":"traceutil/trace.go:171","msg":"trace[657381432] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"223.088614ms","start":"2026-05-07T23:27:06.787497Z","end":"2026-05-07T23:27:07.010586Z","steps":["trace[657381432] 'process raft request' (duration: 223.048843ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:07.010732Z","caller":"traceutil/trace.go:171","msg":"trace[713659446] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"225.025997ms","start":"2026-05-07T23:27:06.785684Z","end":"2026-05-07T23:27:07.01071Z","steps":["trace[713659446] 'process raft request' (duration: 224.755552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:14.683659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.392543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2319"} {"level":"info","ts":"2026-05-07T23:27:14.683727Z","caller":"traceutil/trace.go:171","msg":"trace[71152034] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4953; }","duration":"196.469844ms","start":"2026-05-07T23:27:14.487241Z","end":"2026-05-07T23:27:14.68371Z","steps":["trace[71152034] 'range keys from in-memory index tree' (duration: 196.196039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:15.850848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367524418474031,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:27:15.888758Z","caller":"traceutil/trace.go:171","msg":"trace[535555183] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"860.00327ms","start":"2026-05-07T23:27:15.028703Z","end":"2026-05-07T23:27:15.888706Z","steps":["trace[535555183] 'process raft request' (duration: 859.835018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:15.888948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.028688Z","time spent":"860.167353ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:15.943121Z","caller":"traceutil/trace.go:171","msg":"trace[1848215877] linearizableReadLoop","detail":"{readStateIndex:5144; appliedIndex:5141; }","duration":"592.581615ms","start":"2026-05-07T23:27:15.350513Z","end":"2026-05-07T23:27:15.943094Z","steps":["trace[1848215877] 'read index received' (duration: 537.955483ms)","trace[1848215877] 'applied index is now lower than readState.Index' (duration: 54.624932ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:15.943176Z","caller":"traceutil/trace.go:171","msg":"trace[1699910900] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"889.518249ms","start":"2026-05-07T23:27:15.053634Z","end":"2026-05-07T23:27:15.943153Z","steps":["trace[1699910900] 'process raft request' (duration: 889.226943ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:15.943247Z","caller":"traceutil/trace.go:171","msg":"trace[2046608983] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"889.469698ms","start":"2026-05-07T23:27:15.05374Z","end":"2026-05-07T23:27:15.94321Z","steps":["trace[2046608983] 'process raft request' (duration: 889.300834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:15.943284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.781619ms","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-05-07T23:27:15.943325Z","caller":"traceutil/trace.go:171","msg":"trace[677354235] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4956; }","duration":"592.885191ms","start":"2026-05-07T23:27:15.350429Z","end":"2026-05-07T23:27:15.943314Z","steps":["trace[677354235] 'agreement among raft nodes before linearized reading' (duration: 592.790609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:15.943372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.350354Z","time spent":"593.007123ms","remote":"127.0.0.1:55836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:27:15.943412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.053735Z","time spent":"889.58647ms","remote":"127.0.0.1:39184","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-05-07T23:27:15.943498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.053618Z","time spent":"889.60897ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:16.433241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.162301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:16.433553Z","caller":"traceutil/trace.go:171","msg":"trace[1734754531] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5144; }","duration":"490.344195ms","start":"2026-05-07T23:27:15.943192Z","end":"2026-05-07T23:27:16.433536Z","steps":["trace[1734754531] 'read index received' (duration: 295.799816ms)","trace[1734754531] 'applied index is now lower than readState.Index' (duration: 194.543329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:16.433705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.484833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:27:16.433675Z","caller":"traceutil/trace.go:171","msg":"trace[244833221] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"493.468138ms","start":"2026-05-07T23:27:15.940157Z","end":"2026-05-07T23:27:16.433625Z","steps":["trace[244833221] 'process raft request' (duration: 493.338246ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:16.433743Z","caller":"traceutil/trace.go:171","msg":"trace[1873074243] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4960; }","duration":"809.575455ms","start":"2026-05-07T23:27:15.624158Z","end":"2026-05-07T23:27:16.433733Z","steps":["trace[1873074243] 'agreement among raft nodes before linearized reading' (duration: 809.474973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.433779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.624134Z","time spent":"809.635666ms","remote":"127.0.0.1:39396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":16,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:27:16.433838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.940139Z","time spent":"493.623401ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:16.434066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.340723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T23:27:16.434103Z","caller":"traceutil/trace.go:171","msg":"trace[325749373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4960; }","duration":"978.417315ms","start":"2026-05-07T23:27:15.455676Z","end":"2026-05-07T23:27:16.434094Z","steps":["trace[325749373] 'agreement among raft nodes before linearized reading' (duration: 978.272513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.434133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.455657Z","time spent":"978.467185ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:27:16.434264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.054434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-07T23:27:16.434308Z","caller":"traceutil/trace.go:171","msg":"trace[925254113] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4960; }","duration":"970.122035ms","start":"2026-05-07T23:27:15.464174Z","end":"2026-05-07T23:27:16.434296Z","steps":["trace[925254113] 'agreement among raft nodes before linearized reading' (duration: 970.011543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.434333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.464163Z","time spent":"970.163086ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-07T23:27:16.434311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.573259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-05-07T23:27:16.434531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.949914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T23:27:16.434565Z","caller":"traceutil/trace.go:171","msg":"trace[667504487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4960; }","duration":"208.002715ms","start":"2026-05-07T23:27:16.226554Z","end":"2026-05-07T23:27:16.434557Z","steps":["trace[667504487] 'agreement among raft nodes before linearized reading' (duration: 207.923883ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:16.434506Z","caller":"traceutil/trace.go:171","msg":"trace[282361974] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4960; }","duration":"391.810952ms","start":"2026-05-07T23:27:16.042673Z","end":"2026-05-07T23:27:16.434484Z","steps":["trace[282361974] 'agreement among raft nodes before linearized reading' (duration: 391.379335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.434594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.941364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:27:16.434633Z","caller":"traceutil/trace.go:171","msg":"trace[892656103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4960; }","duration":"240.019135ms","start":"2026-05-07T23:27:16.194605Z","end":"2026-05-07T23:27:16.434624Z","steps":["trace[892656103] 'agreement among raft nodes before linearized reading' (duration: 239.921723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.43466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.04265Z","time spent":"391.979176ms","remote":"127.0.0.1:39054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-07T23:27:16.434876Z","caller":"traceutil/trace.go:171","msg":"trace[891142490] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"495.106656ms","start":"2026-05-07T23:27:15.939759Z","end":"2026-05-07T23:27:16.434866Z","steps":["trace[891142490] 'process raft request' (duration: 493.683632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.434915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.369948ms","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-05-07T23:27:16.434953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.93975Z","time spent":"495.164727ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:16.434962Z","caller":"traceutil/trace.go:171","msg":"trace[770964491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4960; }","duration":"206.435459ms","start":"2026-05-07T23:27:16.228513Z","end":"2026-05-07T23:27:16.434948Z","steps":["trace[770964491] 'agreement among raft nodes before linearized reading' (duration: 205.981342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.435218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.51427ms","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-05-07T23:27:16.435409Z","caller":"traceutil/trace.go:171","msg":"trace[429825213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4960; }","duration":"974.750733ms","start":"2026-05-07T23:27:15.460633Z","end":"2026-05-07T23:27:16.435384Z","steps":["trace[429825213] 'agreement among raft nodes before linearized reading' (duration: 973.168217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.435218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.881507ms","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":"warn","ts":"2026-05-07T23:27:16.43564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.460615Z","time spent":"975.001248ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:27:16.435799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.595377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-07T23:27:16.439425Z","caller":"traceutil/trace.go:171","msg":"trace[289478657] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4960; }","duration":"902.25549ms","start":"2026-05-07T23:27:15.537149Z","end":"2026-05-07T23:27:16.439405Z","steps":["trace[289478657] 'agreement among raft nodes before linearized reading' (duration: 898.567707ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:16.435905Z","caller":"traceutil/trace.go:171","msg":"trace[2119185327] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"497.242851ms","start":"2026-05-07T23:27:15.938643Z","end":"2026-05-07T23:27:16.435886Z","steps":["trace[2119185327] 'process raft request' (duration: 300.346333ms)","trace[2119185327] 'compare' (duration: 193.624482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:16.439597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.537104Z","time spent":"902.413783ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-07T23:27:16.439635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.938619Z","time spent":"500.954184ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:16.434731Z","caller":"traceutil/trace.go:171","msg":"trace[1830791102] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"495.96538ms","start":"2026-05-07T23:27:15.938758Z","end":"2026-05-07T23:27:16.434723Z","steps":["trace[1830791102] 'process raft request' (duration: 494.576647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.439773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.938737Z","time spent":"500.963355ms","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:16.439048Z","caller":"traceutil/trace.go:171","msg":"trace[284975428] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4960; }","duration":"974.697243ms","start":"2026-05-07T23:27:15.464299Z","end":"2026-05-07T23:27:16.438996Z","steps":["trace[284975428] 'agreement among raft nodes before linearized reading' (duration: 969.79659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.439848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:15.464293Z","time spent":"975.537457ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-07T23:27:16.745578Z","caller":"traceutil/trace.go:171","msg":"trace[2018695791] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"304.115086ms","start":"2026-05-07T23:27:16.441428Z","end":"2026-05-07T23:27:16.745543Z","steps":["trace[2018695791] 'process raft request' (duration: 303.991604ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:16.74584Z","caller":"traceutil/trace.go:171","msg":"trace[1453304744] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"306.78102ms","start":"2026-05-07T23:27:16.439027Z","end":"2026-05-07T23:27:16.745808Z","steps":["trace[1453304744] 'process raft request' (duration: 306.19802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.745851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.441417Z","time spent":"304.316769ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:16.745989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.438992Z","time spent":"306.920462ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:16.745974Z","caller":"traceutil/trace.go:171","msg":"trace[2131543386] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"304.442491ms","start":"2026-05-07T23:27:16.441509Z","end":"2026-05-07T23:27:16.745952Z","steps":["trace[2131543386] 'process raft request' (duration: 303.953463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.746108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.441492Z","time spent":"304.544953ms","remote":"127.0.0.1:39184","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-05-07T23:27:16.746358Z","caller":"traceutil/trace.go:171","msg":"trace[1313773803] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"303.997233ms","start":"2026-05-07T23:27:16.442353Z","end":"2026-05-07T23:27:16.74635Z","steps":["trace[1313773803] 'process raft request' (duration: 303.513405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.746403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.442339Z","time spent":"304.044103ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:16.746584Z","caller":"traceutil/trace.go:171","msg":"trace[797341665] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"302.967996ms","start":"2026-05-07T23:27:16.443599Z","end":"2026-05-07T23:27:16.746567Z","steps":["trace[797341665] 'process raft request' (duration: 302.64754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.746776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.443593Z","time spent":"303.136748ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:16.747116Z","caller":"traceutil/trace.go:171","msg":"trace[478863637] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"301.552182ms","start":"2026-05-07T23:27:16.445547Z","end":"2026-05-07T23:27:16.747099Z","steps":["trace[478863637] 'process raft request' (duration: 301.384249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.747234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.445541Z","time spent":"301.650584ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:16.751838Z","caller":"traceutil/trace.go:171","msg":"trace[372187856] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"305.392427ms","start":"2026-05-07T23:27:16.446427Z","end":"2026-05-07T23:27:16.75182Z","steps":["trace[372187856] 'process raft request' (duration: 300.600546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.75199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.446421Z","time spent":"305.520479ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:16.751843Z","caller":"traceutil/trace.go:171","msg":"trace[1118226369] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5148; }","duration":"310.252339ms","start":"2026-05-07T23:27:16.441566Z","end":"2026-05-07T23:27:16.751818Z","steps":["trace[1118226369] 'read index received' (duration: 81.212989ms)","trace[1118226369] 'applied index is now lower than readState.Index' (duration: 229.03751ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:16.75215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.562034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2c99b6c5-6adf-45ea-aa94-29f551ed779d\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-07T23:27:16.752275Z","caller":"traceutil/trace.go:171","msg":"trace[1165853527] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2c99b6c5-6adf-45ea-aa94-29f551ed779d; range_end:; response_count:1; response_revision:4968; }","duration":"310.723856ms","start":"2026-05-07T23:27:16.441534Z","end":"2026-05-07T23:27:16.752258Z","steps":["trace[1165853527] 'agreement among raft nodes before linearized reading' (duration: 310.35745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.752318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:16.441521Z","time spent":"310.784568ms","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-2c99b6c5-6adf-45ea-aa94-29f551ed779d\" "} {"level":"info","ts":"2026-05-07T23:27:16.859356Z","caller":"traceutil/trace.go:171","msg":"trace[1000666554] linearizableReadLoop","detail":"{readStateIndex:5157; appliedIndex:5156; }","duration":"107.190388ms","start":"2026-05-07T23:27:16.752139Z","end":"2026-05-07T23:27:16.859329Z","steps":["trace[1000666554] 'read index received' (duration: 53.778288ms)","trace[1000666554] 'applied index is now lower than readState.Index' (duration: 53.41034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:16.85969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.513383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:27:16.859809Z","caller":"traceutil/trace.go:171","msg":"trace[1454301810] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4968; }","duration":"269.692656ms","start":"2026-05-07T23:27:16.59009Z","end":"2026-05-07T23:27:16.859783Z","steps":["trace[1454301810] 'agreement among raft nodes before linearized reading' (duration: 269.464662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.859732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.134227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:16.860016Z","caller":"traceutil/trace.go:171","msg":"trace[1772343752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4968; }","duration":"221.479083ms","start":"2026-05-07T23:27:16.638521Z","end":"2026-05-07T23:27:16.860001Z","steps":["trace[1772343752] 'agreement among raft nodes before linearized reading' (duration: 221.139027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:16.860618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.440992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-07T23:27:16.860667Z","caller":"traceutil/trace.go:171","msg":"trace[1124059268] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4968; }","duration":"202.531982ms","start":"2026-05-07T23:27:16.658125Z","end":"2026-05-07T23:27:16.860657Z","steps":["trace[1124059268] 'agreement among raft nodes before linearized reading' (duration: 201.408154ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:34.937695Z","caller":"traceutil/trace.go:171","msg":"trace[1024686980] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"108.131325ms","start":"2026-05-07T23:27:34.829537Z","end":"2026-05-07T23:27:34.937668Z","steps":["trace[1024686980] 'process raft request' (duration: 80.108698ms)","trace[1024686980] 'compare' (duration: 27.60498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:49.337597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.280148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:49.337693Z","caller":"traceutil/trace.go:171","msg":"trace[611419572] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"282.403616ms","start":"2026-05-07T23:27:49.055277Z","end":"2026-05-07T23:27:49.33768Z","steps":["trace[611419572] 'process raft request' (duration: 69.980795ms)","trace[611419572] 'compare' (duration: 212.200217ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:49.340272Z","caller":"traceutil/trace.go:171","msg":"trace[187370988] linearizableReadLoop","detail":"{readStateIndex:5471; appliedIndex:5469; }","duration":"280.819531ms","start":"2026-05-07T23:27:49.059418Z","end":"2026-05-07T23:27:49.340238Z","steps":["trace[187370988] 'read index received' (duration: 65.838639ms)","trace[187370988] 'applied index is now lower than readState.Index' (duration: 214.979992ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:49.340322Z","caller":"traceutil/trace.go:171","msg":"trace[1046476132] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"282.309104ms","start":"2026-05-07T23:27:49.057992Z","end":"2026-05-07T23:27:49.340301Z","steps":["trace[1046476132] 'process raft request' (duration: 282.129741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:49.34049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.052454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T23:27:49.340534Z","caller":"traceutil/trace.go:171","msg":"trace[427936123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5275; }","duration":"281.129525ms","start":"2026-05-07T23:27:49.059392Z","end":"2026-05-07T23:27:49.340521Z","steps":["trace[427936123] 'agreement among raft nodes before linearized reading' (duration: 280.975873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:49.340545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.038604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-07T23:27:49.34057Z","caller":"traceutil/trace.go:171","msg":"trace[31112647] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5275; }","duration":"281.096645ms","start":"2026-05-07T23:27:49.059464Z","end":"2026-05-07T23:27:49.340561Z","steps":["trace[31112647] 'agreement among raft nodes before linearized reading' (duration: 281.000743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:49.340671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.076577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T23:27:49.340689Z","caller":"traceutil/trace.go:171","msg":"trace[1017076048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5275; }","duration":"278.126178ms","start":"2026-05-07T23:27:49.062558Z","end":"2026-05-07T23:27:49.340685Z","steps":["trace[1017076048] 'agreement among raft nodes before linearized reading' (duration: 278.074007ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:49.47484Z","caller":"traceutil/trace.go:171","msg":"trace[439324882] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"127.721143ms","start":"2026-05-07T23:27:49.347095Z","end":"2026-05-07T23:27:49.474816Z","steps":["trace[439324882] 'process raft request' (duration: 124.595393ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:49.475101Z","caller":"traceutil/trace.go:171","msg":"trace[1049660079] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"127.941916ms","start":"2026-05-07T23:27:49.347117Z","end":"2026-05-07T23:27:49.475059Z","steps":["trace[1049660079] 'process raft request' (duration: 127.662091ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:57.686349Z","caller":"traceutil/trace.go:171","msg":"trace[853237525] linearizableReadLoop","detail":"{readStateIndex:5521; appliedIndex:5520; }","duration":"167.349952ms","start":"2026-05-07T23:27:57.518974Z","end":"2026-05-07T23:27:57.686324Z","steps":["trace[853237525] 'read index received' (duration: 167.154409ms)","trace[853237525] 'applied index is now lower than readState.Index' (duration: 194.003µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:57.686473Z","caller":"traceutil/trace.go:171","msg":"trace[1034166617] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"169.355774ms","start":"2026-05-07T23:27:57.517074Z","end":"2026-05-07T23:27:57.68643Z","steps":["trace[1034166617] 'process raft request' (duration: 169.06763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:57.686612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.617677ms","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-05-07T23:27:57.686653Z","caller":"traceutil/trace.go:171","msg":"trace[1069224183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5323; }","duration":"167.706528ms","start":"2026-05-07T23:27:57.518934Z","end":"2026-05-07T23:27:57.686641Z","steps":["trace[1069224183] 'agreement among raft nodes before linearized reading' (duration: 167.554826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:57.687434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.375354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2351250"} {"level":"info","ts":"2026-05-07T23:27:57.687525Z","caller":"traceutil/trace.go:171","msg":"trace[103093511] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5323; }","duration":"162.498276ms","start":"2026-05-07T23:27:57.525006Z","end":"2026-05-07T23:27:57.687504Z","steps":["trace[103093511] 'agreement among raft nodes before linearized reading' (duration: 161.543371ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:58.257098Z","caller":"traceutil/trace.go:171","msg":"trace[986210448] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"126.734201ms","start":"2026-05-07T23:27:58.130291Z","end":"2026-05-07T23:27:58.257025Z","steps":["trace[986210448] 'process raft request' (duration: 101.575366ms)","trace[986210448] 'compare' (duration: 24.769489ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:27.400586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2859} {"level":"info","ts":"2026-05-07T23:28:27.519159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2859,"took":"116.42114ms","hash":1386968402,"current-db-size-bytes":54685696,"current-db-size":"55 MB","current-db-size-in-use-bytes":29507584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-07T23:28:27.519235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1386968402,"revision":2859,"compact-revision":-1} {"level":"info","ts":"2026-05-07T23:30:44.857499Z","caller":"traceutil/trace.go:171","msg":"trace[629757002] linearizableReadLoop","detail":"{readStateIndex:6742; appliedIndex:6741; }","duration":"239.865864ms","start":"2026-05-07T23:30:44.617608Z","end":"2026-05-07T23:30:44.857474Z","steps":["trace[629757002] 'read index received' (duration: 217.39138ms)","trace[629757002] 'applied index is now lower than readState.Index' (duration: 22.473464ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:44.857768Z","caller":"traceutil/trace.go:171","msg":"trace[1253715643] transaction","detail":"{read_only:false; response_revision:6504; number_of_response:1; }","duration":"313.610635ms","start":"2026-05-07T23:30:44.544143Z","end":"2026-05-07T23:30:44.857753Z","steps":["trace[1253715643] 'process raft request' (duration: 290.811916ms)","trace[1253715643] 'compare' (duration: 22.025809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:30:44.857885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:44.544122Z","time spent":"313.679646ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:30:44.858069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.439343ms","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-05-07T23:30:44.858178Z","caller":"traceutil/trace.go:171","msg":"trace[1947438384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6504; }","duration":"240.588454ms","start":"2026-05-07T23:30:44.617565Z","end":"2026-05-07T23:30:44.858154Z","steps":["trace[1947438384] 'agreement among raft nodes before linearized reading' (duration: 240.30215ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:44.865334Z","caller":"traceutil/trace.go:171","msg":"trace[543537113] transaction","detail":"{read_only:false; response_revision:6506; number_of_response:1; }","duration":"104.361396ms","start":"2026-05-07T23:30:44.760957Z","end":"2026-05-07T23:30:44.865318Z","steps":["trace[543537113] 'process raft request' (duration: 104.263895ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:44.865447Z","caller":"traceutil/trace.go:171","msg":"trace[1872040236] transaction","detail":"{read_only:false; response_revision:6505; number_of_response:1; }","duration":"143.107562ms","start":"2026-05-07T23:30:44.722321Z","end":"2026-05-07T23:30:44.865429Z","steps":["trace[1872040236] 'process raft request' (duration: 142.800097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:44.865707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.656345ms","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:7"} {"level":"info","ts":"2026-05-07T23:30:44.865744Z","caller":"traceutil/trace.go:171","msg":"trace[248729350] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:6506; }","duration":"153.708206ms","start":"2026-05-07T23:30:44.712025Z","end":"2026-05-07T23:30:44.865733Z","steps":["trace[248729350] 'agreement among raft nodes before linearized reading' (duration: 153.657765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:44.86692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.748805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4840"} {"level":"info","ts":"2026-05-07T23:30:44.867044Z","caller":"traceutil/trace.go:171","msg":"trace[1246043208] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6506; }","duration":"194.351487ms","start":"2026-05-07T23:30:44.672673Z","end":"2026-05-07T23:30:44.867024Z","steps":["trace[1246043208] 'agreement among raft nodes before linearized reading' (duration: 192.595633ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:31:17.133213Z","caller":"traceutil/trace.go:171","msg":"trace[1219241356] transaction","detail":"{read_only:false; response_revision:6728; number_of_response:1; }","duration":"102.295489ms","start":"2026-05-07T23:31:17.030893Z","end":"2026-05-07T23:31:17.133188Z","steps":["trace[1219241356] 'process raft request' (duration: 102.124936ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:33:27.405816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5558} {"level":"info","ts":"2026-05-07T23:33:27.478056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5558,"took":"70.879886ms","hash":999544444,"current-db-size-bytes":54685696,"current-db-size":"55 MB","current-db-size-in-use-bytes":18735104,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-05-07T23:33:27.478148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":999544444,"revision":5558,"compact-revision":2859}