{"level":"warn","ts":"2026-07-28T19:28:31.332285Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T19:28:31.336977Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.37:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.37:2380","--initial-cluster=instance=https://199.19.213.37:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.37:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.37: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-07-28T19:28:31.337092Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T19:28:31.337105Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-07-28T19:28:31.337133Z","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-07-28T19:28:31.340003Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"]} {"level":"info","ts":"2026-07-28T19:28:31.340142Z","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.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.37: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-07-28T19:28:31.346507Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.075895ms"} {"level":"info","ts":"2026-07-28T19:28:31.352244Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7252794153f4f3cd","cluster-id":"2552855285ac01f"} {"level":"info","ts":"2026-07-28T19:28:31.352389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=()"} {"level":"info","ts":"2026-07-28T19:28:31.352482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 0"} {"level":"info","ts":"2026-07-28T19:28:31.352509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7252794153f4f3cd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-28T19:28:31.352557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became follower at term 1"} {"level":"info","ts":"2026-07-28T19:28:31.352654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"warn","ts":"2026-07-28T19:28:31.35975Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-28T19:28:31.361197Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-28T19:28:31.362231Z","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-07-28T19:28:31.364265Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7252794153f4f3cd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-28T19:28:31.364363Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7252794153f4f3cd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-28T19:28:31.364565Z","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-07-28T19:28:31.364691Z","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-07-28T19:28:31.364702Z","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-07-28T19:28:31.36579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd switched to configuration voters=(8237779989902455757)"} {"level":"info","ts":"2026-07-28T19:28:31.366069Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","added-peer-id":"7252794153f4f3cd","added-peer-peer-urls":["https://199.19.213.37:2380"]} {"level":"info","ts":"2026-07-28T19:28:31.369059Z","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-07-28T19:28:31.369285Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7252794153f4f3cd","initial-advertise-peer-urls":["https://199.19.213.37:2380"],"listen-peer-urls":["https://199.19.213.37:2380"],"advertise-client-urls":["https://199.19.213.37:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.37:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-28T19:28:31.369322Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-28T19:28:31.369444Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-07-28T19:28:31.36946Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.37:2380"} {"level":"info","ts":"2026-07-28T19:28:31.554579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd is starting a new election at term 1"} {"level":"info","ts":"2026-07-28T19:28:31.555863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became pre-candidate at term 1"} {"level":"info","ts":"2026-07-28T19:28:31.555956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgPreVoteResp from 7252794153f4f3cd at term 1"} {"level":"info","ts":"2026-07-28T19:28:31.556515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became candidate at term 2"} {"level":"info","ts":"2026-07-28T19:28:31.556582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd received MsgVoteResp from 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-07-28T19:28:31.556708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7252794153f4f3cd became leader at term 2"} {"level":"info","ts":"2026-07-28T19:28:31.55679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7252794153f4f3cd elected leader 7252794153f4f3cd at term 2"} {"level":"info","ts":"2026-07-28T19:28:31.558031Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T19:28:31.559336Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7252794153f4f3cd","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.37:2379]}","request-path":"/0/members/7252794153f4f3cd/attributes","cluster-id":"2552855285ac01f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-28T19:28:31.559548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T19:28:31.5596Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T19:28:31.559693Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-28T19:28:31.559792Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-28T19:28:31.561068Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2552855285ac01f","local-member-id":"7252794153f4f3cd","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T19:28:31.561214Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T19:28:31.561306Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T19:28:31.564051Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.37:2379"} {"level":"info","ts":"2026-07-28T19:28:31.565009Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-28T19:28:34.303836Z","caller":"traceutil/trace.go:171","msg":"trace[1277175881] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"202.004127ms","start":"2026-07-28T19:28:34.10181Z","end":"2026-07-28T19:28:34.303814Z","steps":["trace[1277175881] 'read index received' (duration: 201.906973ms)","trace[1277175881] 'applied index is now lower than readState.Index' (duration: 96.244µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:28:34.303878Z","caller":"traceutil/trace.go:171","msg":"trace[1516799926] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"203.668739ms","start":"2026-07-28T19:28:34.100183Z","end":"2026-07-28T19:28:34.303851Z","steps":["trace[1516799926] 'process raft request' (duration: 203.530794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:28:34.304097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.219145ms","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-07-28T19:28:34.304219Z","caller":"traceutil/trace.go:171","msg":"trace[799296676] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:74; }","duration":"202.430762ms","start":"2026-07-28T19:28:34.101767Z","end":"2026-07-28T19:28:34.304198Z","steps":["trace[799296676] 'agreement among raft nodes before linearized reading' (duration: 202.167032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:28:34.672854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.845813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-28T19:28:34.672959Z","caller":"traceutil/trace.go:171","msg":"trace[2093446296] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"363.808136ms","start":"2026-07-28T19:28:34.309135Z","end":"2026-07-28T19:28:34.672944Z","steps":["trace[2093446296] 'read index received' (duration: 107.464405ms)","trace[2093446296] 'applied index is now lower than readState.Index' (duration: 256.342441ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:28:34.673002Z","caller":"traceutil/trace.go:171","msg":"trace[2048309906] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"364.920659ms","start":"2026-07-28T19:28:34.308045Z","end":"2026-07-28T19:28:34.672965Z","steps":["trace[2048309906] 'process raft request' (duration: 108.517025ms)","trace[2048309906] 'compare' (duration: 255.718209ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:28:34.673171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.033516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-28T19:28:34.673169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:28:34.308034Z","time spent":"365.061235ms","remote":"127.0.0.1:36990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:28:34.67322Z","caller":"traceutil/trace.go:171","msg":"trace[1842425169] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:75; }","duration":"364.106619ms","start":"2026-07-28T19:28:34.309101Z","end":"2026-07-28T19:28:34.673208Z","steps":["trace[1842425169] 'agreement among raft nodes before linearized reading' (duration: 363.8861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:28:34.673314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:28:34.309079Z","time spent":"364.173452ms","remote":"127.0.0.1:36966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-07-28T19:28:34.893302Z","caller":"traceutil/trace.go:171","msg":"trace[2137588019] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"210.90047ms","start":"2026-07-28T19:28:34.682372Z","end":"2026-07-28T19:28:34.893272Z","steps":["trace[2137588019] 'process raft request' (duration: 193.150535ms)","trace[2137588019] 'compare' (duration: 17.582819ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:29:08.646206Z","caller":"traceutil/trace.go:171","msg":"trace[1698002402] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"300.367543ms","start":"2026-07-28T19:29:08.34581Z","end":"2026-07-28T19:29:08.646177Z","steps":["trace[1698002402] 'process raft request' (duration: 300.195059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:08.646401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:08.34579Z","time spent":"300.495516ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:08.653902Z","caller":"traceutil/trace.go:171","msg":"trace[1400167727] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"296.633284ms","start":"2026-07-28T19:29:08.357222Z","end":"2026-07-28T19:29:08.653855Z","steps":["trace[1400167727] 'process raft request' (duration: 296.413429ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:08.654034Z","caller":"traceutil/trace.go:171","msg":"trace[847813666] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"306.791549ms","start":"2026-07-28T19:29:08.34723Z","end":"2026-07-28T19:29:08.654022Z","steps":["trace[847813666] 'process raft request' (duration: 306.145091ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:08.654085Z","caller":"traceutil/trace.go:171","msg":"trace[1031648196] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:497; }","duration":"246.976672ms","start":"2026-07-28T19:29:08.407082Z","end":"2026-07-28T19:29:08.654059Z","steps":["trace[1031648196] 'read index received' (duration: 238.859401ms)","trace[1031648196] 'applied index is now lower than readState.Index' (duration: 8.116021ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:29:08.654162Z","caller":"traceutil/trace.go:171","msg":"trace[1605523643] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"263.647913ms","start":"2026-07-28T19:29:08.390508Z","end":"2026-07-28T19:29:08.654156Z","steps":["trace[1605523643] 'process raft request' (duration: 263.404596ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:08.654194Z","caller":"traceutil/trace.go:171","msg":"trace[1547112585] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"126.342541ms","start":"2026-07-28T19:29:08.527839Z","end":"2026-07-28T19:29:08.654182Z","steps":["trace[1547112585] 'process raft request' (duration: 126.135205ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:08.654195Z","caller":"traceutil/trace.go:171","msg":"trace[952536981] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"296.989565ms","start":"2026-07-28T19:29:08.357171Z","end":"2026-07-28T19:29:08.65416Z","steps":["trace[952536981] 'process raft request' (duration: 296.4202ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:08.654245Z","caller":"traceutil/trace.go:171","msg":"trace[485305820] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"281.489194ms","start":"2026-07-28T19:29:08.37275Z","end":"2026-07-28T19:29:08.654239Z","steps":["trace[485305820] 'process raft request' (duration: 281.107714ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:08.654145Z","caller":"traceutil/trace.go:171","msg":"trace[259789735] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"284.240985ms","start":"2026-07-28T19:29:08.369882Z","end":"2026-07-28T19:29:08.654123Z","steps":["trace[259789735] 'process raft request' (duration: 283.873355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:08.654122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:08.347199Z","time spent":"306.875781ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:08.654326Z","caller":"traceutil/trace.go:171","msg":"trace[1347929825] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"294.925643ms","start":"2026-07-28T19:29:08.359328Z","end":"2026-07-28T19:29:08.654254Z","steps":["trace[1347929825] 'process raft request' (duration: 294.341707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:08.654413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.332241ms","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-07-28T19:29:08.653802Z","caller":"traceutil/trace.go:171","msg":"trace[116243381] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"299.51745ms","start":"2026-07-28T19:29:08.354257Z","end":"2026-07-28T19:29:08.653775Z","steps":["trace[116243381] 'process raft request' (duration: 299.280774ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:08.65446Z","caller":"traceutil/trace.go:171","msg":"trace[198154931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"247.435754ms","start":"2026-07-28T19:29:08.407008Z","end":"2026-07-28T19:29:08.654444Z","steps":["trace[198154931] 'agreement among raft nodes before linearized reading' (duration: 247.327101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:08.654486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:08.354231Z","time spent":"300.21828ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T19:29:08.654642Z","caller":"traceutil/trace.go:171","msg":"trace[13827284] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"284.939813ms","start":"2026-07-28T19:29:08.369422Z","end":"2026-07-28T19:29:08.654362Z","steps":["trace[13827284] 'process raft request' (duration: 284.293636ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:08.654688Z","caller":"traceutil/trace.go:171","msg":"trace[2002002849] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"303.523025ms","start":"2026-07-28T19:29:08.35114Z","end":"2026-07-28T19:29:08.654664Z","steps":["trace[2002002849] 'process raft request' (duration: 302.340444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:08.654875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:08.351131Z","time spent":"303.616177ms","remote":"127.0.0.1:36668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T19:29:08.656847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.051508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:08.65691Z","caller":"traceutil/trace.go:171","msg":"trace[439153595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"229.14756ms","start":"2026-07-28T19:29:08.427751Z","end":"2026-07-28T19:29:08.656898Z","steps":["trace[439153595] 'agreement among raft nodes before linearized reading' (duration: 229.029676ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:15.162794Z","caller":"traceutil/trace.go:171","msg":"trace[239785628] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"218.401713ms","start":"2026-07-28T19:29:14.944357Z","end":"2026-07-28T19:29:15.162758Z","steps":["trace[239785628] 'process raft request' (duration: 217.612024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.153539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.19088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:29:16.153717Z","caller":"traceutil/trace.go:171","msg":"trace[2104733291] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"901.250479ms","start":"2026-07-28T19:29:15.252419Z","end":"2026-07-28T19:29:16.15367Z","steps":["trace[2104733291] 'process raft request' (duration: 466.735002ms)","trace[2104733291] 'compare' (duration: 434.039165ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:29:16.153771Z","caller":"traceutil/trace.go:171","msg":"trace[2109916076] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"889.242429ms","start":"2026-07-28T19:29:15.264513Z","end":"2026-07-28T19:29:16.153756Z","steps":["trace[2109916076] 'process raft request' (duration: 889.158047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.153836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:15.264501Z","time spent":"889.30531ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:29:16.153876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:15.252404Z","time spent":"901.390892ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:29:16.154009Z","caller":"traceutil/trace.go:171","msg":"trace[156481127] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"899.772992ms","start":"2026-07-28T19:29:15.254224Z","end":"2026-07-28T19:29:16.153997Z","steps":["trace[156481127] 'read index received' (duration: 464.945388ms)","trace[156481127] 'applied index is now lower than readState.Index' (duration: 434.826554ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:29:16.154188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.959047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-28T19:29:16.154237Z","caller":"traceutil/trace.go:171","msg":"trace[98740929] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:595; }","duration":"900.016708ms","start":"2026-07-28T19:29:15.254208Z","end":"2026-07-28T19:29:16.154224Z","steps":["trace[98740929] 'agreement among raft nodes before linearized reading' (duration: 899.934156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.15427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:15.2542Z","time spent":"900.06293ms","remote":"127.0.0.1:36818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-07-28T19:29:16.154349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.306226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-28T19:29:16.154366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.229032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.37\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-28T19:29:16.154421Z","caller":"traceutil/trace.go:171","msg":"trace[1337069356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"737.441398ms","start":"2026-07-28T19:29:15.416963Z","end":"2026-07-28T19:29:16.154405Z","steps":["trace[1337069356] 'agreement among raft nodes before linearized reading' (duration: 737.224003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.154455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:15.416944Z","time spent":"737.50285ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T19:29:16.154469Z","caller":"traceutil/trace.go:171","msg":"trace[1201968857] range","detail":"{range_begin:/registry/masterleases/199.19.213.37; range_end:; response_count:1; response_revision:595; }","duration":"643.365156ms","start":"2026-07-28T19:29:15.511072Z","end":"2026-07-28T19:29:16.154437Z","steps":["trace[1201968857] 'agreement among raft nodes before linearized reading' (duration: 643.150611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.154531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:15.511061Z","time spent":"643.456468ms","remote":"127.0.0.1:36650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.37\" "} {"level":"warn","ts":"2026-07-28T19:29:16.154624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.561701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:16.15468Z","caller":"traceutil/trace.go:171","msg":"trace[1021853628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"727.633112ms","start":"2026-07-28T19:29:15.427028Z","end":"2026-07-28T19:29:16.154661Z","steps":["trace[1021853628] 'agreement among raft nodes before linearized reading' (duration: 727.53375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:16.154721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:15.42702Z","time spent":"727.691783ms","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T19:29:17.651244Z","caller":"traceutil/trace.go:171","msg":"trace[1128917618] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"239.031949ms","start":"2026-07-28T19:29:17.412187Z","end":"2026-07-28T19:29:17.651219Z","steps":["trace[1128917618] 'process raft request' (duration: 238.913456ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:17.656292Z","caller":"traceutil/trace.go:171","msg":"trace[169160231] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"240.918624ms","start":"2026-07-28T19:29:17.41535Z","end":"2026-07-28T19:29:17.656268Z","steps":["trace[169160231] 'process raft request' (duration: 240.801051ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:17.65632Z","caller":"traceutil/trace.go:171","msg":"trace[1060047871] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"240.074073ms","start":"2026-07-28T19:29:17.416227Z","end":"2026-07-28T19:29:17.656301Z","steps":["trace[1060047871] 'process raft request' (duration: 239.958671ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:17.656436Z","caller":"traceutil/trace.go:171","msg":"trace[1340878523] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:634; }","duration":"230.761602ms","start":"2026-07-28T19:29:17.42557Z","end":"2026-07-28T19:29:17.656331Z","steps":["trace[1340878523] 'read index received' (duration: 225.60524ms)","trace[1340878523] 'applied index is now lower than readState.Index' (duration: 5.155372ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:29:17.656558Z","caller":"traceutil/trace.go:171","msg":"trace[888748604] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"241.286512ms","start":"2026-07-28T19:29:17.415247Z","end":"2026-07-28T19:29:17.656534Z","steps":["trace[888748604] 'process raft request' (duration: 240.78968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:17.65666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.021908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:17.656732Z","caller":"traceutil/trace.go:171","msg":"trace[1185877226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"231.168282ms","start":"2026-07-28T19:29:17.425548Z","end":"2026-07-28T19:29:17.656717Z","steps":["trace[1185877226] 'agreement among raft nodes before linearized reading' (duration: 230.994238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:31.141193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.385021ms","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-07-28T19:29:31.141283Z","caller":"traceutil/trace.go:171","msg":"trace[1923189936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"374.540575ms","start":"2026-07-28T19:29:30.766723Z","end":"2026-07-28T19:29:31.141264Z","steps":["trace[1923189936] 'range keys from in-memory index tree' (duration: 374.081425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:31.141331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:30.766699Z","time spent":"374.620316ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T19:29:59.766302Z","caller":"traceutil/trace.go:171","msg":"trace[1697662102] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"338.558923ms","start":"2026-07-28T19:29:59.427714Z","end":"2026-07-28T19:29:59.766273Z","steps":["trace[1697662102] 'read index received' (duration: 338.377561ms)","trace[1697662102] 'applied index is now lower than readState.Index' (duration: 179.962µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:29:59.766481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.747646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:29:59.76656Z","caller":"traceutil/trace.go:171","msg":"trace[1233081602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1154; }","duration":"338.866119ms","start":"2026-07-28T19:29:59.427675Z","end":"2026-07-28T19:29:59.766541Z","steps":["trace[1233081602] 'agreement among raft nodes before linearized reading' (duration: 338.715916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:59.766609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:59.42766Z","time spent":"338.93895ms","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T19:29:59.766561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.757341ms","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-07-28T19:29:59.766716Z","caller":"traceutil/trace.go:171","msg":"trace[499810679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1154; }","duration":"137.968674ms","start":"2026-07-28T19:29:59.628733Z","end":"2026-07-28T19:29:59.766701Z","steps":["trace[499810679] 'agreement among raft nodes before linearized reading' (duration: 137.692989ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:29:59.766891Z","caller":"traceutil/trace.go:171","msg":"trace[378231830] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"488.630341ms","start":"2026-07-28T19:29:59.277729Z","end":"2026-07-28T19:29:59.76636Z","steps":["trace[378231830] 'process raft request' (duration: 488.375637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:29:59.767294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:29:59.277708Z","time spent":"489.320662ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:30:23.112654Z","caller":"traceutil/trace.go:171","msg":"trace[2077051520] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"110.394415ms","start":"2026-07-28T19:30:23.002239Z","end":"2026-07-28T19:30:23.112634Z","steps":["trace[2077051520] 'read index received' (duration: 14.140405ms)","trace[2077051520] 'applied index is now lower than readState.Index' (duration: 96.25313ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:30:23.112687Z","caller":"traceutil/trace.go:171","msg":"trace[1085798643] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"135.551792ms","start":"2026-07-28T19:30:22.977106Z","end":"2026-07-28T19:30:23.112658Z","steps":["trace[1085798643] 'process raft request' (duration: 135.437141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:23.112905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.626278ms","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-07-28T19:30:23.11301Z","caller":"traceutil/trace.go:171","msg":"trace[1820485560] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1417; }","duration":"110.7778ms","start":"2026-07-28T19:30:23.002203Z","end":"2026-07-28T19:30:23.112981Z","steps":["trace[1820485560] 'agreement among raft nodes before linearized reading' (duration: 110.538436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:49.706379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.294806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:30:49.706471Z","caller":"traceutil/trace.go:171","msg":"trace[240857807] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1842; }","duration":"246.441617ms","start":"2026-07-28T19:30:49.460009Z","end":"2026-07-28T19:30:49.70645Z","steps":["trace[240857807] 'count revisions from in-memory index tree' (duration: 246.210965ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:49.726813Z","caller":"traceutil/trace.go:171","msg":"trace[467478643] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"147.632046ms","start":"2026-07-28T19:30:49.579075Z","end":"2026-07-28T19:30:49.726707Z","steps":["trace[467478643] 'read index received' (duration: 147.592886ms)","trace[467478643] 'applied index is now lower than readState.Index' (duration: 38.12µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:30:49.72697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.840209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d675a771-4071-4382-a808-3edcd9c18f72\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-28T19:30:49.727156Z","caller":"traceutil/trace.go:171","msg":"trace[1450852401] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d675a771-4071-4382-a808-3edcd9c18f72; range_end:; response_count:1; response_revision:1843; }","duration":"148.099043ms","start":"2026-07-28T19:30:49.579017Z","end":"2026-07-28T19:30:49.727116Z","steps":["trace[1450852401] 'agreement among raft nodes before linearized reading' (duration: 147.696077ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:49.727114Z","caller":"traceutil/trace.go:171","msg":"trace[2055505934] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"212.016275ms","start":"2026-07-28T19:30:49.515018Z","end":"2026-07-28T19:30:49.727034Z","steps":["trace[2055505934] 'process raft request' (duration: 211.567419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:49.731901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.439846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-28T19:30:49.732009Z","caller":"traceutil/trace.go:171","msg":"trace[113687705] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1844; }","duration":"102.592798ms","start":"2026-07-28T19:30:49.629398Z","end":"2026-07-28T19:30:49.73199Z","steps":["trace[113687705] 'agreement among raft nodes before linearized reading' (duration: 102.369025ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:49.732166Z","caller":"traceutil/trace.go:171","msg":"trace[1902641269] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"136.144309ms","start":"2026-07-28T19:30:49.595975Z","end":"2026-07-28T19:30:49.73212Z","steps":["trace[1902641269] 'process raft request' (duration: 135.684383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:50.516593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.967539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-28T19:30:50.516708Z","caller":"traceutil/trace.go:171","msg":"trace[1114413582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1845; }","duration":"407.137332ms","start":"2026-07-28T19:30:50.109548Z","end":"2026-07-28T19:30:50.516686Z","steps":["trace[1114413582] 'range keys from in-memory index tree' (duration: 406.749937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:50.516782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.109524Z","time spent":"407.244303ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T19:30:50.516968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.652254ms","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-07-28T19:30:50.517053Z","caller":"traceutil/trace.go:171","msg":"trace[1231405621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1845; }","duration":"402.814097ms","start":"2026-07-28T19:30:50.114218Z","end":"2026-07-28T19:30:50.517032Z","steps":["trace[1231405621] 'range keys from in-memory index tree' (duration: 402.385971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:50.517114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.114183Z","time spent":"402.909918ms","remote":"127.0.0.1:36868","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":"info","ts":"2026-07-28T19:30:50.878743Z","caller":"traceutil/trace.go:171","msg":"trace[59712080] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"356.224041ms","start":"2026-07-28T19:30:50.522496Z","end":"2026-07-28T19:30:50.87872Z","steps":["trace[59712080] 'process raft request' (duration: 356.079829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:50.879102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.522478Z","time spent":"356.363142ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:30:50.890616Z","caller":"traceutil/trace.go:171","msg":"trace[1562330179] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"367.817049ms","start":"2026-07-28T19:30:50.522741Z","end":"2026-07-28T19:30:50.890558Z","steps":["trace[1562330179] 'process raft request' (duration: 367.642346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:50.890957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.522735Z","time spent":"368.003451ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:30:51.089742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.279836ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-28T19:30:51.090268Z","caller":"traceutil/trace.go:171","msg":"trace[85745074] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"338.592489ms","start":"2026-07-28T19:30:50.751639Z","end":"2026-07-28T19:30:51.090232Z","steps":["trace[85745074] 'process raft request' (duration: 338.364087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.090489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.751617Z","time spent":"338.781713ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:30:51.090492Z","caller":"traceutil/trace.go:171","msg":"trace[1248141124] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"282.189778ms","start":"2026-07-28T19:30:50.808267Z","end":"2026-07-28T19:30:51.090457Z","steps":["trace[1248141124] 'process raft request' (duration: 281.870834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.090847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.023475ms","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-07-28T19:30:51.090906Z","caller":"traceutil/trace.go:171","msg":"trace[1788534346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1849; }","duration":"396.144276ms","start":"2026-07-28T19:30:50.694747Z","end":"2026-07-28T19:30:51.090892Z","steps":["trace[1788534346] 'agreement among raft nodes before linearized reading' (duration: 395.805451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.090973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.694724Z","time spent":"396.238108ms","remote":"127.0.0.1:36868","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":"info","ts":"2026-07-28T19:30:51.091422Z","caller":"traceutil/trace.go:171","msg":"trace[570920872] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1909; }","duration":"395.376807ms","start":"2026-07-28T19:30:50.694812Z","end":"2026-07-28T19:30:51.090188Z","steps":["trace[570920872] 'read index received' (duration: 183.792394ms)","trace[570920872] 'applied index is now lower than readState.Index' (duration: 211.583263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:30:51.592145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873274794772137,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T19:30:51.714724Z","caller":"traceutil/trace.go:171","msg":"trace[1693491218] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1914; }","duration":"623.236046ms","start":"2026-07-28T19:30:51.091455Z","end":"2026-07-28T19:30:51.714691Z","steps":["trace[1693491218] 'read index received' (duration: 612.937294ms)","trace[1693491218] 'applied index is now lower than readState.Index' (duration: 10.297952ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:30:51.714842Z","caller":"traceutil/trace.go:171","msg":"trace[1147083102] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"787.702777ms","start":"2026-07-28T19:30:50.92713Z","end":"2026-07-28T19:30:51.714832Z","steps":["trace[1147083102] 'process raft request' (duration: 777.267903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.714955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.927098Z","time spent":"787.772338ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:30:51.715112Z","caller":"traceutil/trace.go:171","msg":"trace[1443907472] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"598.262358ms","start":"2026-07-28T19:30:51.116818Z","end":"2026-07-28T19:30:51.71508Z","steps":["trace[1443907472] 'process raft request' (duration: 597.835142ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:30:51.715193Z","caller":"traceutil/trace.go:171","msg":"trace[403932802] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"620.594483ms","start":"2026-07-28T19:30:51.094569Z","end":"2026-07-28T19:30:51.715163Z","steps":["trace[403932802] 'process raft request' (duration: 620.041456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.715407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.845395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-28T19:30:51.715491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.347223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:30:51.715659Z","caller":"traceutil/trace.go:171","msg":"trace[587683687] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1852; }","duration":"396.523585ms","start":"2026-07-28T19:30:51.319027Z","end":"2026-07-28T19:30:51.715551Z","steps":["trace[587683687] 'agreement among raft nodes before linearized reading' (duration: 396.314223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.715708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.971259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d675a771-4071-4382-a808-3edcd9c18f72\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-07-28T19:30:51.715767Z","caller":"traceutil/trace.go:171","msg":"trace[2122728860] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d675a771-4071-4382-a808-3edcd9c18f72; range_end:; response_count:1; response_revision:1852; }","duration":"984.062791ms","start":"2026-07-28T19:30:50.73169Z","end":"2026-07-28T19:30:51.715753Z","steps":["trace[2122728860] 'agreement among raft nodes before linearized reading' (duration: 983.19599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.715223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013402486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-28T19:30:51.715818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.73167Z","time spent":"984.134582ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3768,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d675a771-4071-4382-a808-3edcd9c18f72\" "} {"level":"warn","ts":"2026-07-28T19:30:51.715726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:51.318997Z","time spent":"396.713857ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-07-28T19:30:51.715839Z","caller":"traceutil/trace.go:171","msg":"trace[74911200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"1.014059385s","start":"2026-07-28T19:30:50.701763Z","end":"2026-07-28T19:30:51.715822Z","steps":["trace[74911200] 'agreement among raft nodes before linearized reading' (duration: 1.013371806s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.715281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:51.11679Z","time spent":"598.404269ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:30:51.715855Z","caller":"traceutil/trace.go:171","msg":"trace[551076332] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"976.050918ms","start":"2026-07-28T19:30:50.73948Z","end":"2026-07-28T19:30:51.715531Z","steps":["trace[551076332] 'agreement among raft nodes before linearized reading' (duration: 975.690774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.716194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.739469Z","time spent":"976.666087ms","remote":"127.0.0.1:43082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-28T19:30:51.715439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017233456s","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-07-28T19:30:51.716343Z","caller":"traceutil/trace.go:171","msg":"trace[1497873716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"1.018187678s","start":"2026-07-28T19:30:50.698136Z","end":"2026-07-28T19:30:51.716324Z","steps":["trace[1497873716] 'agreement among raft nodes before linearized reading' (duration: 1.017158454s)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.715919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.701748Z","time spent":"1.014154196s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T19:30:51.715762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:51.09454Z","time spent":"620.973997ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:30:51.716459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.698123Z","time spent":"1.018319469s","remote":"127.0.0.1:36868","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":"warn","ts":"2026-07-28T19:30:51.715882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.719251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:30:51.716789Z","caller":"traceutil/trace.go:171","msg":"trace[1918800668] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1852; }","duration":"975.734734ms","start":"2026-07-28T19:30:50.741041Z","end":"2026-07-28T19:30:51.716776Z","steps":["trace[1918800668] 'agreement among raft nodes before linearized reading' (duration: 974.066193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:30:51.716955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:30:50.741026Z","time spent":"975.816575ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-28T19:31:03.482894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.133731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:31:03.48311Z","caller":"traceutil/trace.go:171","msg":"trace[842574536] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"353.013345ms","start":"2026-07-28T19:31:03.130081Z","end":"2026-07-28T19:31:03.483094Z","steps":["trace[842574536] 'process raft request' (duration: 352.962514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:03.483186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:03.130065Z","time spent":"353.086746ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:03.483539Z","caller":"traceutil/trace.go:171","msg":"trace[787265008] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"356.019361ms","start":"2026-07-28T19:31:03.127489Z","end":"2026-07-28T19:31:03.483508Z","steps":["trace[787265008] 'process raft request' (duration: 41.187693ms)","trace[787265008] 'compare' (duration: 314.001699ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:31:03.483712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:03.127457Z","time spent":"356.170103ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:07.233485Z","caller":"traceutil/trace.go:171","msg":"trace[1746601382] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"257.696421ms","start":"2026-07-28T19:31:06.975768Z","end":"2026-07-28T19:31:07.233464Z","steps":["trace[1746601382] 'process raft request' (duration: 257.57874ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:07.712711Z","caller":"traceutil/trace.go:171","msg":"trace[466457601] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"735.954825ms","start":"2026-07-28T19:31:06.976734Z","end":"2026-07-28T19:31:07.712689Z","steps":["trace[466457601] 'process raft request' (duration: 730.626851ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:07.712769Z","caller":"traceutil/trace.go:171","msg":"trace[1680762913] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2048; }","duration":"646.007562ms","start":"2026-07-28T19:31:07.066741Z","end":"2026-07-28T19:31:07.712749Z","steps":["trace[1680762913] 'read index received' (duration: 166.761006ms)","trace[1680762913] 'applied index is now lower than readState.Index' (duration: 479.245576ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:31:07.712849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:06.976707Z","time spent":"736.070636ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:07.712863Z","caller":"traceutil/trace.go:171","msg":"trace[48500749] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"540.171349ms","start":"2026-07-28T19:31:07.172681Z","end":"2026-07-28T19:31:07.712853Z","steps":["trace[48500749] 'process raft request' (duration: 539.976577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:07.712966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:07.172663Z","time spent":"540.225829ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:31:07.713198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.449677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:31:07.713225Z","caller":"traceutil/trace.go:171","msg":"trace[1248593844] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1982; }","duration":"646.513568ms","start":"2026-07-28T19:31:07.066703Z","end":"2026-07-28T19:31:07.713217Z","steps":["trace[1248593844] 'agreement among raft nodes before linearized reading' (duration: 646.462168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:07.713245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:07.066684Z","time spent":"646.555279ms","remote":"127.0.0.1:38966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-28T19:31:07.724805Z","caller":"traceutil/trace.go:171","msg":"trace[1877700338] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"470.426639ms","start":"2026-07-28T19:31:07.254357Z","end":"2026-07-28T19:31:07.724783Z","steps":["trace[1877700338] 'process raft request' (duration: 470.278258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:07.724963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:07.254325Z","time spent":"470.547881ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:07.725018Z","caller":"traceutil/trace.go:171","msg":"trace[2093166537] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"230.845607ms","start":"2026-07-28T19:31:07.49415Z","end":"2026-07-28T19:31:07.724996Z","steps":["trace[2093166537] 'process raft request' (duration: 230.591784ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:07.725177Z","caller":"traceutil/trace.go:171","msg":"trace[1756490029] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"230.990439ms","start":"2026-07-28T19:31:07.494176Z","end":"2026-07-28T19:31:07.725166Z","steps":["trace[1756490029] 'process raft request' (duration: 230.757626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:07.725191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.188617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:31:07.725231Z","caller":"traceutil/trace.go:171","msg":"trace[2087298507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1985; }","duration":"298.255348ms","start":"2026-07-28T19:31:07.426964Z","end":"2026-07-28T19:31:07.725219Z","steps":["trace[2087298507] 'agreement among raft nodes before linearized reading' (duration: 298.183167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:07.7253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.375733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:31:07.725345Z","caller":"traceutil/trace.go:171","msg":"trace[1981159896] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1985; }","duration":"108.475444ms","start":"2026-07-28T19:31:07.616859Z","end":"2026-07-28T19:31:07.725334Z","steps":["trace[1981159896] 'agreement among raft nodes before linearized reading' (duration: 108.388113ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:30.04911Z","caller":"traceutil/trace.go:171","msg":"trace[721680923] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"258.026804ms","start":"2026-07-28T19:31:29.791059Z","end":"2026-07-28T19:31:30.049086Z","steps":["trace[721680923] 'process raft request' (duration: 257.904502ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:30.202694Z","caller":"traceutil/trace.go:171","msg":"trace[706963057] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2176; }","duration":"398.676747ms","start":"2026-07-28T19:31:29.803988Z","end":"2026-07-28T19:31:30.202665Z","steps":["trace[706963057] 'read index received' (duration: 244.973345ms)","trace[706963057] 'applied index is now lower than readState.Index' (duration: 153.699312ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:30.202745Z","caller":"traceutil/trace.go:171","msg":"trace[44038034] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"305.095621ms","start":"2026-07-28T19:31:29.897614Z","end":"2026-07-28T19:31:30.202709Z","steps":["trace[44038034] 'process raft request' (duration: 304.98096ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:30.202808Z","caller":"traceutil/trace.go:171","msg":"trace[888805179] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"297.751488ms","start":"2026-07-28T19:31:29.905031Z","end":"2026-07-28T19:31:30.202783Z","steps":["trace[888805179] 'process raft request' (duration: 297.594566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:30.202957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:29.897589Z","time spent":"305.246272ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:30.202828Z","caller":"traceutil/trace.go:171","msg":"trace[279496523] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"405.695387ms","start":"2026-07-28T19:31:29.797104Z","end":"2026-07-28T19:31:30.2028Z","steps":["trace[279496523] 'process raft request' (duration: 394.534001ms)","trace[279496523] 'compare' (duration: 10.851412ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:31:30.203211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.221533ms","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":"warn","ts":"2026-07-28T19:31:30.203233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:29.797073Z","time spent":"406.051581ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:30.203258Z","caller":"traceutil/trace.go:171","msg":"trace[2008220749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2108; }","duration":"399.302584ms","start":"2026-07-28T19:31:29.803944Z","end":"2026-07-28T19:31:30.203246Z","steps":["trace[2008220749] 'agreement among raft nodes before linearized reading' (duration: 399.140832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:30.203296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:29.803931Z","time spent":"399.353635ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T19:31:30.203591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.148365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-28T19:31:30.203655Z","caller":"traceutil/trace.go:171","msg":"trace[1453874265] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2108; }","duration":"232.233078ms","start":"2026-07-28T19:31:29.971407Z","end":"2026-07-28T19:31:30.20364Z","steps":["trace[1453874265] 'agreement among raft nodes before linearized reading' (duration: 232.107996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:30.203611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.413996ms","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-07-28T19:31:30.203785Z","caller":"traceutil/trace.go:171","msg":"trace[1337823430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2108; }","duration":"283.598818ms","start":"2026-07-28T19:31:29.920144Z","end":"2026-07-28T19:31:30.203743Z","steps":["trace[1337823430] 'agreement among raft nodes before linearized reading' (duration: 283.374025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:30.203896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.18137ms","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-07-28T19:31:30.204019Z","caller":"traceutil/trace.go:171","msg":"trace[551104741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2108; }","duration":"295.329301ms","start":"2026-07-28T19:31:29.908669Z","end":"2026-07-28T19:31:30.203998Z","steps":["trace[551104741] 'agreement among raft nodes before linearized reading' (duration: 294.876225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:30.204155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.717735ms","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-07-28T19:31:30.204209Z","caller":"traceutil/trace.go:171","msg":"trace[1915757221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2108; }","duration":"295.801916ms","start":"2026-07-28T19:31:29.908392Z","end":"2026-07-28T19:31:30.204193Z","steps":["trace[1915757221] 'agreement among raft nodes before linearized reading' (duration: 295.27691ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:30.385041Z","caller":"traceutil/trace.go:171","msg":"trace[2003843474] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2181; }","duration":"129.63626ms","start":"2026-07-28T19:31:30.255383Z","end":"2026-07-28T19:31:30.385019Z","steps":["trace[2003843474] 'read index received' (duration: 79.489515ms)","trace[2003843474] 'applied index is now lower than readState.Index' (duration: 50.145265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:31:30.385193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.798623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:31:30.385222Z","caller":"traceutil/trace.go:171","msg":"trace[188762288] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2113; }","duration":"129.878123ms","start":"2026-07-28T19:31:30.255334Z","end":"2026-07-28T19:31:30.385212Z","steps":["trace[188762288] 'agreement among raft nodes before linearized reading' (duration: 129.759362ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:30.385134Z","caller":"traceutil/trace.go:171","msg":"trace[1168421419] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"173.731097ms","start":"2026-07-28T19:31:30.211358Z","end":"2026-07-28T19:31:30.385089Z","steps":["trace[1168421419] 'process raft request' (duration: 123.530581ms)","trace[1168421419] 'compare' (duration: 49.743071ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:30.38539Z","caller":"traceutil/trace.go:171","msg":"trace[1259926718] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"173.788168ms","start":"2026-07-28T19:31:30.21153Z","end":"2026-07-28T19:31:30.385318Z","steps":["trace[1259926718] 'process raft request' (duration: 173.428735ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:30.385386Z","caller":"traceutil/trace.go:171","msg":"trace[831140864] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"173.92502ms","start":"2026-07-28T19:31:30.211416Z","end":"2026-07-28T19:31:30.385341Z","steps":["trace[831140864] 'process raft request' (duration: 173.452225ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:30.385457Z","caller":"traceutil/trace.go:171","msg":"trace[1019577510] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"142.330153ms","start":"2026-07-28T19:31:30.243083Z","end":"2026-07-28T19:31:30.385413Z","steps":["trace[1019577510] 'process raft request' (duration: 141.905948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:31.469454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.342201ms","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-07-28T19:31:31.469659Z","caller":"traceutil/trace.go:171","msg":"trace[1433705969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2114; }","duration":"119.532533ms","start":"2026-07-28T19:31:31.350016Z","end":"2026-07-28T19:31:31.469549Z","steps":["trace[1433705969] 'range keys from in-memory index tree' (duration: 119.073388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:31.469898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.244104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-28T19:31:31.469988Z","caller":"traceutil/trace.go:171","msg":"trace[103624547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2114; }","duration":"259.397106ms","start":"2026-07-28T19:31:31.210569Z","end":"2026-07-28T19:31:31.469966Z","steps":["trace[103624547] 'range keys from in-memory index tree' (duration: 258.631247ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:32.304846Z","caller":"traceutil/trace.go:171","msg":"trace[1355893654] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"250.198207ms","start":"2026-07-28T19:31:32.054623Z","end":"2026-07-28T19:31:32.304821Z","steps":["trace[1355893654] 'process raft request' (duration: 250.050335ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:32.752883Z","caller":"traceutil/trace.go:171","msg":"trace[1148666615] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"542.857478ms","start":"2026-07-28T19:31:32.210002Z","end":"2026-07-28T19:31:32.752859Z","steps":["trace[1148666615] 'process raft request' (duration: 542.783376ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:32.752905Z","caller":"traceutil/trace.go:171","msg":"trace[1572062757] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"543.142101ms","start":"2026-07-28T19:31:32.209718Z","end":"2026-07-28T19:31:32.75286Z","steps":["trace[1572062757] 'process raft request' (duration: 542.924507ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:32.752952Z","caller":"traceutil/trace.go:171","msg":"trace[1642915438] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2188; }","duration":"527.562836ms","start":"2026-07-28T19:31:32.225367Z","end":"2026-07-28T19:31:32.75293Z","steps":["trace[1642915438] 'read index received' (duration: 79.369391ms)","trace[1642915438] 'applied index is now lower than readState.Index' (duration: 448.192015ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:32.752992Z","caller":"traceutil/trace.go:171","msg":"trace[2082658962] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"542.429413ms","start":"2026-07-28T19:31:32.210518Z","end":"2026-07-28T19:31:32.752947Z","steps":["trace[2082658962] 'process raft request' (duration: 542.30614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:32.753037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.209972Z","time spent":"542.983979ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:31:32.75306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.209689Z","time spent":"543.292932ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:31:32.753093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.210508Z","time spent":"542.529605ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:31:32.753135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.130853ms","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-07-28T19:31:32.75316Z","caller":"traceutil/trace.go:171","msg":"trace[1187667620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2119; }","duration":"363.189553ms","start":"2026-07-28T19:31:32.389964Z","end":"2026-07-28T19:31:32.753154Z","steps":["trace[1187667620] 'agreement among raft nodes before linearized reading' (duration: 363.101022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:32.753178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.389947Z","time spent":"363.227653ms","remote":"127.0.0.1:36868","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":"warn","ts":"2026-07-28T19:31:32.753171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.792169ms","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-07-28T19:31:32.753212Z","caller":"traceutil/trace.go:171","msg":"trace[133141992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2119; }","duration":"527.840289ms","start":"2026-07-28T19:31:32.225361Z","end":"2026-07-28T19:31:32.753201Z","steps":["trace[133141992] 'agreement among raft nodes before linearized reading' (duration: 527.678658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:32.753249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.852719ms","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-07-28T19:31:32.753244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.225314Z","time spent":"527.92034ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T19:31:32.753251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.592706ms","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-07-28T19:31:32.753261Z","caller":"traceutil/trace.go:171","msg":"trace[663862168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2119; }","duration":"362.90709ms","start":"2026-07-28T19:31:32.39035Z","end":"2026-07-28T19:31:32.753257Z","steps":["trace[663862168] 'agreement among raft nodes before linearized reading' (duration: 362.8641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:32.753279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.390329Z","time spent":"362.94767ms","remote":"127.0.0.1:36868","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":"info","ts":"2026-07-28T19:31:32.753284Z","caller":"traceutil/trace.go:171","msg":"trace[601889312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2119; }","duration":"362.625886ms","start":"2026-07-28T19:31:32.390649Z","end":"2026-07-28T19:31:32.753275Z","steps":["trace[601889312] 'agreement among raft nodes before linearized reading' (duration: 362.532215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:32.753293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.201067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-28T19:31:32.753311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.390616Z","time spent":"362.688347ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T19:31:32.75333Z","caller":"traceutil/trace.go:171","msg":"trace[1974543500] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2119; }","duration":"276.273709ms","start":"2026-07-28T19:31:32.477045Z","end":"2026-07-28T19:31:32.753319Z","steps":["trace[1974543500] 'agreement among raft nodes before linearized reading' (duration: 276.134587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:33.298347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.572815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:31:33.298831Z","caller":"traceutil/trace.go:171","msg":"trace[1847514138] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"538.498511ms","start":"2026-07-28T19:31:32.760296Z","end":"2026-07-28T19:31:33.298794Z","steps":["trace[1847514138] 'process raft request' (duration: 161.38588ms)","trace[1847514138] 'compare' (duration: 376.385593ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:33.298989Z","caller":"traceutil/trace.go:171","msg":"trace[2109546045] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"538.040395ms","start":"2026-07-28T19:31:32.76092Z","end":"2026-07-28T19:31:33.29896Z","steps":["trace[2109546045] 'process raft request' (duration: 537.796023ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:31:33.298866Z","caller":"traceutil/trace.go:171","msg":"trace[43429643] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2193; }","duration":"522.016075ms","start":"2026-07-28T19:31:32.776828Z","end":"2026-07-28T19:31:33.298844Z","steps":["trace[43429643] 'read index received' (duration: 1.320885ms)","trace[43429643] 'applied index is now lower than readState.Index' (duration: 520.69399ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:31:33.299264Z","caller":"traceutil/trace.go:171","msg":"trace[1882365083] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"537.212736ms","start":"2026-07-28T19:31:32.762017Z","end":"2026-07-28T19:31:33.29923Z","steps":["trace[1882365083] 'process raft request' (duration: 536.782601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:33.299366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.527712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c68af2cfa14b54\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-28T19:31:33.299435Z","caller":"traceutil/trace.go:171","msg":"trace[1506552712] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c68af2cfa14b54; range_end:; response_count:1; response_revision:2123; }","duration":"522.626162ms","start":"2026-07-28T19:31:32.77679Z","end":"2026-07-28T19:31:33.299416Z","steps":["trace[1506552712] 'agreement among raft nodes before linearized reading' (duration: 522.44641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:33.299408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.454989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-28T19:31:33.29944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.762011Z","time spent":"537.342228ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:31:33.29948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.776778Z","time spent":"522.691343ms","remote":"127.0.0.1:36692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c68af2cfa14b54\" "} {"level":"info","ts":"2026-07-28T19:31:33.299496Z","caller":"traceutil/trace.go:171","msg":"trace[56928880] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2123; }","duration":"171.56106ms","start":"2026-07-28T19:31:33.127919Z","end":"2026-07-28T19:31:33.29948Z","steps":["trace[56928880] 'agreement among raft nodes before linearized reading' (duration: 171.435419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:33.299828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.537621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:127275"} {"level":"warn","ts":"2026-07-28T19:31:33.29917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.760202Z","time spent":"538.704293ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:31:33.299871Z","caller":"traceutil/trace.go:171","msg":"trace[676410414] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2123; }","duration":"173.578002ms","start":"2026-07-28T19:31:33.126281Z","end":"2026-07-28T19:31:33.299859Z","steps":["trace[676410414] 'agreement among raft nodes before linearized reading' (duration: 173.333679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:33.299913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.535701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-07-28T19:31:33.299957Z","caller":"traceutil/trace.go:171","msg":"trace[197450515] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2123; }","duration":"265.623592ms","start":"2026-07-28T19:31:33.034322Z","end":"2026-07-28T19:31:33.299946Z","steps":["trace[197450515] 'agreement among raft nodes before linearized reading' (duration: 265.512851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:33.299143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.760913Z","time spent":"538.147586ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:31:33.300793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.184674ms","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-07-28T19:31:33.300858Z","caller":"traceutil/trace.go:171","msg":"trace[467017922] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2123; }","duration":"305.317486ms","start":"2026-07-28T19:31:32.995526Z","end":"2026-07-28T19:31:33.300844Z","steps":["trace[467017922] 'agreement among raft nodes before linearized reading' (duration: 305.198805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:31:33.302419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:31:32.9955Z","time spent":"306.899514ms","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-28T19:32:23.812806Z","caller":"traceutil/trace.go:171","msg":"trace[429872743] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"255.275164ms","start":"2026-07-28T19:32:23.557507Z","end":"2026-07-28T19:32:23.812782Z","steps":["trace[429872743] 'process raft request' (duration: 255.147842ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:32:23.875475Z","caller":"traceutil/trace.go:171","msg":"trace[2133996798] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"316.479073ms","start":"2026-07-28T19:32:23.558972Z","end":"2026-07-28T19:32:23.875451Z","steps":["trace[2133996798] 'process raft request' (duration: 311.500051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:23.87563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:23.558952Z","time spent":"316.581873ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:32:23.875798Z","caller":"traceutil/trace.go:171","msg":"trace[1386583116] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"315.917597ms","start":"2026-07-28T19:32:23.559872Z","end":"2026-07-28T19:32:23.87579Z","steps":["trace[1386583116] 'process raft request' (duration: 315.464172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:23.875846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:23.55986Z","time spent":"315.963108ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:32:23.875761Z","caller":"traceutil/trace.go:171","msg":"trace[290025831] linearizableReadLoop","detail":"{readStateIndex:2588; appliedIndex:2585; }","duration":"147.518717ms","start":"2026-07-28T19:32:23.728196Z","end":"2026-07-28T19:32:23.875715Z","steps":["trace[290025831] 'read index received' (duration: 84.512669ms)","trace[290025831] 'applied index is now lower than readState.Index' (duration: 63.004668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:32:23.876055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.86363ms","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-07-28T19:32:23.876088Z","caller":"traceutil/trace.go:171","msg":"trace[2022531028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2503; }","duration":"147.942781ms","start":"2026-07-28T19:32:23.728137Z","end":"2026-07-28T19:32:23.87608Z","steps":["trace[2022531028] 'agreement among raft nodes before linearized reading' (duration: 147.682348ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:32:51.97981Z","caller":"traceutil/trace.go:171","msg":"trace[1936448735] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"201.838747ms","start":"2026-07-28T19:32:51.777951Z","end":"2026-07-28T19:32:51.97979Z","steps":["trace[1936448735] 'process raft request' (duration: 201.523485ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:32:52.445863Z","caller":"traceutil/trace.go:171","msg":"trace[702521626] transaction","detail":"{read_only:false; response_revision:2659; number_of_response:1; }","duration":"411.234883ms","start":"2026-07-28T19:32:52.034601Z","end":"2026-07-28T19:32:52.445836Z","steps":["trace[702521626] 'process raft request' (duration: 411.171161ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:32:52.445883Z","caller":"traceutil/trace.go:171","msg":"trace[1958851829] linearizableReadLoop","detail":"{readStateIndex:2751; appliedIndex:2748; }","duration":"409.632232ms","start":"2026-07-28T19:32:52.036224Z","end":"2026-07-28T19:32:52.445857Z","steps":["trace[1958851829] 'read index received' (duration: 4.555385ms)","trace[1958851829] 'applied index is now lower than readState.Index' (duration: 405.074187ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:32:52.445874Z","caller":"traceutil/trace.go:171","msg":"trace[493329160] transaction","detail":"{read_only:false; response_revision:2660; number_of_response:1; }","duration":"383.061903ms","start":"2026-07-28T19:32:52.06279Z","end":"2026-07-28T19:32:52.445852Z","steps":["trace[493329160] 'process raft request' (duration: 383.025581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:52.446096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.844271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:32:52.446144Z","caller":"traceutil/trace.go:171","msg":"trace[1507575278] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2660; }","duration":"409.912843ms","start":"2026-07-28T19:32:52.036219Z","end":"2026-07-28T19:32:52.446132Z","steps":["trace[1507575278] 'agreement among raft nodes before linearized reading' (duration: 409.813959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:52.446101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:52.062767Z","time spent":"383.192049ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:32:52.446189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:52.036195Z","time spent":"409.979646ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-28T19:32:52.44602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:52.034586Z","time spent":"411.344748ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:32:52.446319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.864583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T19:32:52.446368Z","caller":"traceutil/trace.go:171","msg":"trace[1230403455] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2660; }","duration":"360.943757ms","start":"2026-07-28T19:32:52.08541Z","end":"2026-07-28T19:32:52.446354Z","steps":["trace[1230403455] 'agreement among raft nodes before linearized reading' (duration: 360.76428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:32:52.4464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:52.085394Z","time spent":"360.999028ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-28T19:32:52.446425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.321057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c68b042bf539ae\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-28T19:32:52.446456Z","caller":"traceutil/trace.go:171","msg":"trace[1673590638] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c68b042bf539ae; range_end:; response_count:1; response_revision:2660; }","duration":"111.379ms","start":"2026-07-28T19:32:52.335064Z","end":"2026-07-28T19:32:52.446443Z","steps":["trace[1673590638] 'agreement among raft nodes before linearized reading' (duration: 111.284416ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:32:52.447039Z","caller":"traceutil/trace.go:171","msg":"trace[1195996419] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"413.118907ms","start":"2026-07-28T19:32:52.0339Z","end":"2026-07-28T19:32:52.447019Z","steps":["trace[1195996419] 'process raft request' (duration: 323.223121ms)","trace[1195996419] 'compare' (duration: 88.531003ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:32:52.447125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:32:52.033872Z","time spent":"413.21433ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T19:36:35.982987Z","caller":"traceutil/trace.go:171","msg":"trace[1519342252] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"173.05131ms","start":"2026-07-28T19:36:35.809909Z","end":"2026-07-28T19:36:35.98296Z","steps":["trace[1519342252] 'process raft request' (duration: 172.024402ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:35.988514Z","caller":"traceutil/trace.go:171","msg":"trace[1424886656] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"112.585347ms","start":"2026-07-28T19:36:35.875907Z","end":"2026-07-28T19:36:35.988492Z","steps":["trace[1424886656] 'process raft request' (duration: 112.430985ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:35.988497Z","caller":"traceutil/trace.go:171","msg":"trace[1798795758] transaction","detail":"{read_only:false; response_revision:4545; number_of_response:1; }","duration":"104.831502ms","start":"2026-07-28T19:36:35.883649Z","end":"2026-07-28T19:36:35.988481Z","steps":["trace[1798795758] 'process raft request' (duration: 104.802612ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:35.988545Z","caller":"traceutil/trace.go:171","msg":"trace[2131065400] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"104.929874ms","start":"2026-07-28T19:36:35.883609Z","end":"2026-07-28T19:36:35.988539Z","steps":["trace[2131065400] 'process raft request' (duration: 104.816872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:38.931606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17567873274794778690,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T19:36:39.046682Z","caller":"traceutil/trace.go:171","msg":"trace[539796616] linearizableReadLoop","detail":"{readStateIndex:4786; appliedIndex:4785; }","duration":"615.627191ms","start":"2026-07-28T19:36:38.43103Z","end":"2026-07-28T19:36:39.046658Z","steps":["trace[539796616] 'read index received' (duration: 615.459868ms)","trace[539796616] 'applied index is now lower than readState.Index' (duration: 166.303µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:36:39.046803Z","caller":"traceutil/trace.go:171","msg":"trace[231963326] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"616.388776ms","start":"2026-07-28T19:36:38.430403Z","end":"2026-07-28T19:36:39.046792Z","steps":["trace[231963326] 'process raft request' (duration: 616.09608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:39.046914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:36:38.430385Z","time spent":"616.450097ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T19:36:39.047177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.108365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4a6d2c87-5ab3-4b5f-b671-32588e0a9771\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-07-28T19:36:39.047224Z","caller":"traceutil/trace.go:171","msg":"trace[1665699905] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4a6d2c87-5ab3-4b5f-b671-32588e0a9771; range_end:; response_count:1; response_revision:4619; }","duration":"425.188495ms","start":"2026-07-28T19:36:38.622023Z","end":"2026-07-28T19:36:39.047212Z","steps":["trace[1665699905] 'agreement among raft nodes before linearized reading' (duration: 425.130984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:39.047254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:36:38.622002Z","time spent":"425.244167ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4a6d2c87-5ab3-4b5f-b671-32588e0a9771\" "} {"level":"warn","ts":"2026-07-28T19:36:39.047221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.785963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:36:39.047307Z","caller":"traceutil/trace.go:171","msg":"trace[1945519111] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4619; }","duration":"549.938366ms","start":"2026-07-28T19:36:38.49735Z","end":"2026-07-28T19:36:39.047288Z","steps":["trace[1945519111] 'agreement among raft nodes before linearized reading' (duration: 549.775113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:39.047132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.097971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T19:36:39.047349Z","caller":"traceutil/trace.go:171","msg":"trace[847147898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4619; }","duration":"616.372704ms","start":"2026-07-28T19:36:38.430971Z","end":"2026-07-28T19:36:39.047344Z","steps":["trace[847147898] 'agreement among raft nodes before linearized reading' (duration: 616.11543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:39.047363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:36:38.43091Z","time spent":"616.449496ms","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T19:36:39.047329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.011045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-2klhf-tls\" ","response":"range_response_count:1 size:1966"} {"level":"warn","ts":"2026-07-28T19:36:39.047367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T19:36:38.497331Z","time spent":"550.022517ms","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-28T19:36:39.047417Z","caller":"traceutil/trace.go:171","msg":"trace[1006763054] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-2klhf-tls; range_end:; response_count:1; response_revision:4619; }","duration":"195.131087ms","start":"2026-07-28T19:36:38.852256Z","end":"2026-07-28T19:36:39.047387Z","steps":["trace[1006763054] 'agreement among raft nodes before linearized reading' (duration: 194.889433ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:41.18325Z","caller":"traceutil/trace.go:171","msg":"trace[313817825] linearizableReadLoop","detail":"{readStateIndex:4809; appliedIndex:4808; }","duration":"126.451006ms","start":"2026-07-28T19:36:41.056768Z","end":"2026-07-28T19:36:41.183219Z","steps":["trace[313817825] 'read index received' (duration: 53.418247ms)","trace[313817825] 'applied index is now lower than readState.Index' (duration: 73.028598ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:36:41.183685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.901723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4a6d2c87-5ab3-4b5f-b671-32588e0a9771\" ","response":"range_response_count:1 size:3948"} {"level":"info","ts":"2026-07-28T19:36:41.18374Z","caller":"traceutil/trace.go:171","msg":"trace[1600406908] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4a6d2c87-5ab3-4b5f-b671-32588e0a9771; range_end:; response_count:1; response_revision:4641; }","duration":"126.998765ms","start":"2026-07-28T19:36:41.05673Z","end":"2026-07-28T19:36:41.183729Z","steps":["trace[1600406908] 'agreement among raft nodes before linearized reading' (duration: 126.851493ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:41.358911Z","caller":"traceutil/trace.go:171","msg":"trace[1711174579] linearizableReadLoop","detail":"{readStateIndex:4812; appliedIndex:4811; }","duration":"138.857282ms","start":"2026-07-28T19:36:41.220028Z","end":"2026-07-28T19:36:41.358885Z","steps":["trace[1711174579] 'read index received' (duration: 72.747433ms)","trace[1711174579] 'applied index is now lower than readState.Index' (duration: 66.108519ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:36:41.35942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.373321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-28T19:36:41.359509Z","caller":"traceutil/trace.go:171","msg":"trace[2064660707] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4644; }","duration":"139.482333ms","start":"2026-07-28T19:36:41.220013Z","end":"2026-07-28T19:36:41.359496Z","steps":["trace[2064660707] 'agreement among raft nodes before linearized reading' (duration: 139.044806ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:41.359794Z","caller":"traceutil/trace.go:171","msg":"trace[461716969] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"142.453284ms","start":"2026-07-28T19:36:41.216525Z","end":"2026-07-28T19:36:41.358979Z","steps":["trace[461716969] 'process raft request' (duration: 76.263465ms)","trace[461716969] 'compare' (duration: 65.888814ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:36:42.475156Z","caller":"traceutil/trace.go:171","msg":"trace[1476544650] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"159.637507ms","start":"2026-07-28T19:36:42.315503Z","end":"2026-07-28T19:36:42.47514Z","steps":["trace[1476544650] 'process raft request' (duration: 159.539986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:42.475366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.187941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-07-28T19:36:42.475426Z","caller":"traceutil/trace.go:171","msg":"trace[266779621] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4666; }","duration":"132.280283ms","start":"2026-07-28T19:36:42.343132Z","end":"2026-07-28T19:36:42.475412Z","steps":["trace[266779621] 'agreement among raft nodes before linearized reading' (duration: 132.11695ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:42.475181Z","caller":"traceutil/trace.go:171","msg":"trace[624268935] linearizableReadLoop","detail":"{readStateIndex:4834; appliedIndex:4834; }","duration":"131.983428ms","start":"2026-07-28T19:36:42.343168Z","end":"2026-07-28T19:36:42.475151Z","steps":["trace[624268935] 'read index received' (duration: 131.975157ms)","trace[624268935] 'applied index is now lower than readState.Index' (duration: 6.761µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:36:52.000683Z","caller":"traceutil/trace.go:171","msg":"trace[2038940267] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"111.96774ms","start":"2026-07-28T19:36:51.888689Z","end":"2026-07-28T19:36:52.000657Z","steps":["trace[2038940267] 'process raft request' (duration: 111.800007ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:52.189074Z","caller":"traceutil/trace.go:171","msg":"trace[141585040] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"151.464988ms","start":"2026-07-28T19:36:52.037587Z","end":"2026-07-28T19:36:52.189052Z","steps":["trace[141585040] 'process raft request' (duration: 151.346406ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:52.189377Z","caller":"traceutil/trace.go:171","msg":"trace[791029792] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"149.580566ms","start":"2026-07-28T19:36:52.039767Z","end":"2026-07-28T19:36:52.189347Z","steps":["trace[791029792] 'process raft request' (duration: 149.247601ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:52.18938Z","caller":"traceutil/trace.go:171","msg":"trace[378373868] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4955; }","duration":"144.938117ms","start":"2026-07-28T19:36:52.04442Z","end":"2026-07-28T19:36:52.189358Z","steps":["trace[378373868] 'read index received' (duration: 61.894833ms)","trace[378373868] 'applied index is now lower than readState.Index' (duration: 83.042274ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T19:36:52.189559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.12133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T19:36:52.18961Z","caller":"traceutil/trace.go:171","msg":"trace[82946324] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4785; }","duration":"145.256652ms","start":"2026-07-28T19:36:52.044344Z","end":"2026-07-28T19:36:52.189601Z","steps":["trace[82946324] 'agreement among raft nodes before linearized reading' (duration: 145.0912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:52.189781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.019873ms","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-07-28T19:36:52.189856Z","caller":"traceutil/trace.go:171","msg":"trace[962024422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4785; }","duration":"137.126554ms","start":"2026-07-28T19:36:52.052709Z","end":"2026-07-28T19:36:52.189836Z","steps":["trace[962024422] 'agreement among raft nodes before linearized reading' (duration: 136.913071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:52.701888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.474306ms","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-07-28T19:36:52.701956Z","caller":"traceutil/trace.go:171","msg":"trace[1427031402] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:4789; }","duration":"115.556297ms","start":"2026-07-28T19:36:52.586382Z","end":"2026-07-28T19:36:52.701939Z","steps":["trace[1427031402] 'agreement among raft nodes before linearized reading' (duration: 115.448595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:36:52.701913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.114828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-28T19:36:52.702543Z","caller":"traceutil/trace.go:171","msg":"trace[446064723] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4789; }","duration":"109.775328ms","start":"2026-07-28T19:36:52.592739Z","end":"2026-07-28T19:36:52.702514Z","steps":["trace[446064723] 'agreement among raft nodes before linearized reading' (duration: 109.019236ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:36:52.849599Z","caller":"traceutil/trace.go:171","msg":"trace[1076763119] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"101.210133ms","start":"2026-07-28T19:36:52.748362Z","end":"2026-07-28T19:36:52.849572Z","steps":["trace[1076763119] 'process raft request' (duration: 19.530543ms)","trace[1076763119] 'compare' (duration: 81.361225ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:37:04.987453Z","caller":"traceutil/trace.go:171","msg":"trace[599661678] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"114.916207ms","start":"2026-07-28T19:37:04.872506Z","end":"2026-07-28T19:37:04.987423Z","steps":["trace[599661678] 'process raft request' (duration: 114.625531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T19:37:20.505434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.933544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:37:20.505547Z","caller":"traceutil/trace.go:171","msg":"trace[1816205857] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"155.53906ms","start":"2026-07-28T19:37:20.349989Z","end":"2026-07-28T19:37:20.505528Z","steps":["trace[1816205857] 'process raft request' (duration: 41.410603ms)","trace[1816205857] 'compare' (duration: 113.823862ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T19:38:31.876567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2867} {"level":"warn","ts":"2026-07-28T19:38:32.013265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.337965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T19:38:32.013442Z","caller":"traceutil/trace.go:171","msg":"trace[1147792493] transaction","detail":"{read_only:false; response_revision:5589; number_of_response:1; }","duration":"117.452567ms","start":"2026-07-28T19:38:31.895959Z","end":"2026-07-28T19:38:32.013412Z","steps":["trace[1147792493] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 113.154541ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T19:38:32.053365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2867,"took":"175.328212ms","hash":1391673073,"current-db-size-bytes":55463936,"current-db-size":"56 MB","current-db-size-in-use-bytes":29765632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-28T19:38:32.053453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1391673073,"revision":2867,"compact-revision":-1} {"level":"info","ts":"2026-07-28T19:43:31.883274Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5588} {"level":"info","ts":"2026-07-28T19:43:31.983987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5588,"took":"98.304221ms","hash":3247772874,"current-db-size-bytes":55463936,"current-db-size":"56 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-28T19:43:31.984064Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3247772874,"revision":5588,"compact-revision":2867}