{"level":"warn","ts":"2026-09-08T15:40:05.746411Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:40:05.746587Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.68: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://162.253.55.68:2380","--initial-cluster=instance=https://162.253.55.68:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.68:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.68: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-09-08T15:40:05.747884Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:40:05.747906Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.68:2380"]} {"level":"info","ts":"2026-09-08T15:40:05.747947Z","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-09-08T15:40:05.748733Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.68:2379"]} {"level":"info","ts":"2026-09-08T15:40:05.748882Z","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://162.253.55.68:2380"],"listen-peer-urls":["https://162.253.55.68:2380"],"advertise-client-urls":["https://162.253.55.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.68: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-09-08T15:40:05.753206Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.997574ms"} {"level":"info","ts":"2026-09-08T15:40:05.772939Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f84e77da319eb1ed","cluster-id":"fd76717a29435cbd"} {"level":"info","ts":"2026-09-08T15:40:05.773017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:40:05.773037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became follower at term 0"} {"level":"info","ts":"2026-09-08T15:40:05.773078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f84e77da319eb1ed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:40:05.773104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became follower at term 1"} {"level":"info","ts":"2026-09-08T15:40:05.773175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed switched to configuration voters=(17892370148608618989)"} {"level":"warn","ts":"2026-09-08T15:40:05.778932Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:40:05.781049Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:40:05.784696Z","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-09-08T15:40:05.786543Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f84e77da319eb1ed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:40:05.787064Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f84e77da319eb1ed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:40:05.787381Z","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-09-08T15:40:05.788095Z","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-09-08T15:40:05.788109Z","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-09-08T15:40:05.788655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed switched to configuration voters=(17892370148608618989)"} {"level":"info","ts":"2026-09-08T15:40:05.788882Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fd76717a29435cbd","local-member-id":"f84e77da319eb1ed","added-peer-id":"f84e77da319eb1ed","added-peer-peer-urls":["https://162.253.55.68:2380"]} {"level":"info","ts":"2026-09-08T15:40:05.789884Z","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-09-08T15:40:05.78999Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.68:2380"} {"level":"info","ts":"2026-09-08T15:40:05.790034Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.68:2380"} {"level":"info","ts":"2026-09-08T15:40:05.790223Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f84e77da319eb1ed","initial-advertise-peer-urls":["https://162.253.55.68:2380"],"listen-peer-urls":["https://162.253.55.68:2380"],"advertise-client-urls":["https://162.253.55.68:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.68:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:40:05.790277Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:40:06.374403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:40:06.374476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:40:06.374499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed received MsgPreVoteResp from f84e77da319eb1ed at term 1"} {"level":"info","ts":"2026-09-08T15:40:06.374517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:40:06.374528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed received MsgVoteResp from f84e77da319eb1ed at term 2"} {"level":"info","ts":"2026-09-08T15:40:06.374543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f84e77da319eb1ed became leader at term 2"} {"level":"info","ts":"2026-09-08T15:40:06.374555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f84e77da319eb1ed elected leader f84e77da319eb1ed at term 2"} {"level":"info","ts":"2026-09-08T15:40:06.376037Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f84e77da319eb1ed","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.68:2379]}","request-path":"/0/members/f84e77da319eb1ed/attributes","cluster-id":"fd76717a29435cbd","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:40:06.376315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:40:06.376399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:40:06.376887Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:40:06.377723Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:40:06.377782Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:40:06.380144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T15:40:06.38058Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fd76717a29435cbd","local-member-id":"f84e77da319eb1ed","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:40:06.380706Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:40:06.380742Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:40:06.39245Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.68:2379"} {"level":"warn","ts":"2026-09-08T15:40:09.217639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.176973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-08T15:40:09.217831Z","caller":"traceutil/trace.go:171","msg":"trace[2031894035] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"296.514425ms","start":"2026-09-08T15:40:08.921289Z","end":"2026-09-08T15:40:09.217804Z","steps":["trace[2031894035] 'process raft request' (duration: 129.640066ms)","trace[2031894035] 'compare' (duration: 166.005677ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:09.218141Z","caller":"traceutil/trace.go:171","msg":"trace[608752995] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"294.884514ms","start":"2026-09-08T15:40:08.923211Z","end":"2026-09-08T15:40:09.218096Z","steps":["trace[608752995] 'read index received' (duration: 127.722675ms)","trace[608752995] 'applied index is now lower than readState.Index' (duration: 167.157739ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:40:09.218428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.168404ms","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-09-08T15:40:09.218498Z","caller":"traceutil/trace.go:171","msg":"trace[368194928] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:76; }","duration":"295.322161ms","start":"2026-09-08T15:40:08.923153Z","end":"2026-09-08T15:40:09.218476Z","steps":["trace[368194928] 'agreement among raft nodes before linearized reading' (duration: 295.171545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:09.21866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.764535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-08T15:40:09.218744Z","caller":"traceutil/trace.go:171","msg":"trace[1436986363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"253.864768ms","start":"2026-09-08T15:40:08.964844Z","end":"2026-09-08T15:40:09.218708Z","steps":["trace[1436986363] 'agreement among raft nodes before linearized reading' (duration: 253.64945ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:40:17.319837Z","caller":"traceutil/trace.go:171","msg":"trace[2141886297] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"108.585308ms","start":"2026-09-08T15:40:17.210998Z","end":"2026-09-08T15:40:17.319584Z","steps":["trace[2141886297] 'process raft request' (duration: 40.917696ms)","trace[2141886297] 'compare' (duration: 67.390493ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:48.228618Z","caller":"traceutil/trace.go:171","msg":"trace[1957910904] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"164.978398ms","start":"2026-09-08T15:40:48.063613Z","end":"2026-09-08T15:40:48.228591Z","steps":["trace[1957910904] 'process raft request' (duration: 164.809084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:48.850565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.390385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:40:48.850908Z","caller":"traceutil/trace.go:171","msg":"trace[1463754] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"392.633921ms","start":"2026-09-08T15:40:48.458245Z","end":"2026-09-08T15:40:48.850879Z","steps":["trace[1463754] 'process raft request' (duration: 71.670951ms)","trace[1463754] 'compare' (duration: 319.961753ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:48.851046Z","caller":"traceutil/trace.go:171","msg":"trace[486147811] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:591; }","duration":"318.922074ms","start":"2026-09-08T15:40:48.532106Z","end":"2026-09-08T15:40:48.851028Z","steps":["trace[486147811] 'read index received' (duration: 258.552983ms)","trace[486147811] 'applied index is now lower than readState.Index' (duration: 60.36822ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:48.851206Z","caller":"traceutil/trace.go:171","msg":"trace[1228396818] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"368.837947ms","start":"2026-09-08T15:40:48.482355Z","end":"2026-09-08T15:40:48.851193Z","steps":["trace[1228396818] 'process raft request' (duration: 368.57878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:48.851276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:48.482334Z","time spent":"368.907538ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:40:48.851347Z","caller":"traceutil/trace.go:171","msg":"trace[1953737825] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"363.666555ms","start":"2026-09-08T15:40:48.487665Z","end":"2026-09-08T15:40:48.851331Z","steps":["trace[1953737825] 'process raft request' (duration: 363.315515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:48.851446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:48.487656Z","time spent":"363.746647ms","remote":"127.0.0.1:55900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:40:48.851496Z","caller":"traceutil/trace.go:171","msg":"trace[196845344] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"385.753882ms","start":"2026-09-08T15:40:48.465732Z","end":"2026-09-08T15:40:48.851485Z","steps":["trace[196845344] 'process raft request' (duration: 384.9522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:48.851648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.539202ms","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-09-08T15:40:48.851685Z","caller":"traceutil/trace.go:171","msg":"trace[2082467672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"319.616654ms","start":"2026-09-08T15:40:48.532059Z","end":"2026-09-08T15:40:48.851675Z","steps":["trace[2082467672] 'agreement among raft nodes before linearized reading' (duration: 319.549602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:48.851717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:48.532043Z","time spent":"319.667235ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-08T15:40:48.851059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:48.458231Z","time spent":"392.743284ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:40:48.851955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.625423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:40:48.851989Z","caller":"traceutil/trace.go:171","msg":"trace[2136852941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"271.700855ms","start":"2026-09-08T15:40:48.580279Z","end":"2026-09-08T15:40:48.85198Z","steps":["trace[2136852941] 'agreement among raft nodes before linearized reading' (duration: 271.647584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:48.852013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:48.465711Z","time spent":"385.809214ms","remote":"127.0.0.1:55900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:40:48.852333Z","caller":"traceutil/trace.go:171","msg":"trace[1098019304] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"383.443748ms","start":"2026-09-08T15:40:48.468877Z","end":"2026-09-08T15:40:48.85232Z","steps":["trace[1098019304] 'process raft request' (duration: 381.989718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:48.852435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:48.468865Z","time spent":"383.533942ms","remote":"127.0.0.1:55900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T15:40:55.003479Z","caller":"traceutil/trace.go:171","msg":"trace[1458821122] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"111.936875ms","start":"2026-09-08T15:40:54.891506Z","end":"2026-09-08T15:40:55.003443Z","steps":["trace[1458821122] 'process raft request' (duration: 34.457762ms)","trace[1458821122] 'compare' (duration: 77.33195ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:55.483439Z","caller":"traceutil/trace.go:171","msg":"trace[1831865354] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"591.491493ms","start":"2026-09-08T15:40:54.891912Z","end":"2026-09-08T15:40:55.483403Z","steps":["trace[1831865354] 'process raft request' (duration: 547.016932ms)","trace[1831865354] 'compare' (duration: 43.425934ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:40:55.483595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:54.89188Z","time spent":"591.637336ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:40:55.48464Z","caller":"traceutil/trace.go:171","msg":"trace[799255229] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"590.146476ms","start":"2026-09-08T15:40:54.894471Z","end":"2026-09-08T15:40:55.484617Z","steps":["trace[799255229] 'process raft request' (duration: 588.519273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:55.484784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:54.89445Z","time spent":"590.26296ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:40:55.485493Z","caller":"traceutil/trace.go:171","msg":"trace[1565331323] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"576.633608ms","start":"2026-09-08T15:40:54.908842Z","end":"2026-09-08T15:40:55.485476Z","steps":["trace[1565331323] 'process raft request' (duration: 576.216248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:55.485626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:54.908828Z","time spent":"576.754512ms","remote":"127.0.0.1:56018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:40:55.488232Z","caller":"traceutil/trace.go:171","msg":"trace[1082694125] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"535.414347ms","start":"2026-09-08T15:40:54.95279Z","end":"2026-09-08T15:40:55.488204Z","steps":["trace[1082694125] 'process raft request' (duration: 532.378537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:55.488784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:40:54.952767Z","time spent":"535.850097ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T15:40:57.10829Z","caller":"traceutil/trace.go:171","msg":"trace[2048330114] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"100.466003ms","start":"2026-09-08T15:40:57.007794Z","end":"2026-09-08T15:40:57.10826Z","steps":["trace[2048330114] 'read index received' (duration: 99.943419ms)","trace[2048330114] 'applied index is now lower than readState.Index' (duration: 521.434µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:40:57.108355Z","caller":"traceutil/trace.go:171","msg":"trace[491033899] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"124.175604ms","start":"2026-09-08T15:40:56.984156Z","end":"2026-09-08T15:40:57.108332Z","steps":["trace[491033899] 'process raft request' (duration: 123.681801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:40:57.108596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.785732ms","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-09-08T15:40:57.108654Z","caller":"traceutil/trace.go:171","msg":"trace[1005259560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"100.852564ms","start":"2026-09-08T15:40:57.007784Z","end":"2026-09-08T15:40:57.108637Z","steps":["trace[1005259560] 'agreement among raft nodes before linearized reading' (duration: 100.617238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:41:43.546277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.823095ms","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-09-08T15:41:43.546371Z","caller":"traceutil/trace.go:171","msg":"trace[793197024] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1221; }","duration":"142.970538ms","start":"2026-09-08T15:41:43.40338Z","end":"2026-09-08T15:41:43.546351Z","steps":["trace[793197024] 'count revisions from in-memory index tree' (duration: 142.729912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:41:43.546436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.003601ms","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-09-08T15:41:43.546522Z","caller":"traceutil/trace.go:171","msg":"trace[87373535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"198.139214ms","start":"2026-09-08T15:41:43.348362Z","end":"2026-09-08T15:41:43.546501Z","steps":["trace[87373535] 'range keys from in-memory index tree' (duration: 197.819567ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:42:33.498722Z","caller":"traceutil/trace.go:171","msg":"trace[2137346750] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"119.443905ms","start":"2026-09-08T15:42:33.37923Z","end":"2026-09-08T15:42:33.498674Z","steps":["trace[2137346750] 'read index received' (duration: 119.251911ms)","trace[2137346750] 'applied index is now lower than readState.Index' (duration: 191.033µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:42:33.498756Z","caller":"traceutil/trace.go:171","msg":"trace[871750923] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"159.709515ms","start":"2026-09-08T15:42:33.339021Z","end":"2026-09-08T15:42:33.49873Z","steps":["trace[871750923] 'process raft request' (duration: 159.46363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:33.498977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.733571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-002df7bd-c971-4dbc-847d-3cad61b535cb\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-08T15:42:33.499019Z","caller":"traceutil/trace.go:171","msg":"trace[1249555984] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-002df7bd-c971-4dbc-847d-3cad61b535cb; range_end:; response_count:1; response_revision:1903; }","duration":"119.828872ms","start":"2026-09-08T15:42:33.379177Z","end":"2026-09-08T15:42:33.499006Z","steps":["trace[1249555984] 'agreement among raft nodes before linearized reading' (duration: 119.674579ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:42:46.454375Z","caller":"traceutil/trace.go:171","msg":"trace[1796202560] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"172.152575ms","start":"2026-09-08T15:42:46.282196Z","end":"2026-09-08T15:42:46.454348Z","steps":["trace[1796202560] 'process raft request' (duration: 172.062813ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:42:46.454772Z","caller":"traceutil/trace.go:171","msg":"trace[971812891] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"199.350754ms","start":"2026-09-08T15:42:46.255374Z","end":"2026-09-08T15:42:46.454725Z","steps":["trace[971812891] 'process raft request' (duration: 148.54457ms)","trace[971812891] 'compare' (duration: 50.165732ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:42:46.658762Z","caller":"traceutil/trace.go:171","msg":"trace[348009524] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"106.888742ms","start":"2026-09-08T15:42:46.551835Z","end":"2026-09-08T15:42:46.658724Z","steps":["trace[348009524] 'process raft request' (duration: 42.570636ms)","trace[348009524] 'compare' (duration: 64.00443ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:42:47.058884Z","caller":"traceutil/trace.go:171","msg":"trace[63957033] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"315.530511ms","start":"2026-09-08T15:42:46.743316Z","end":"2026-09-08T15:42:47.058846Z","steps":["trace[63957033] 'process raft request' (duration: 315.286637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:47.058994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.782236ms","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":"warn","ts":"2026-09-08T15:42:47.05904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:42:46.74329Z","time spent":"315.673963ms","remote":"127.0.0.1:56018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5588,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:42:47.059056Z","caller":"traceutil/trace.go:171","msg":"trace[519317257] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2015; }","duration":"310.899258ms","start":"2026-09-08T15:42:46.748139Z","end":"2026-09-08T15:42:47.059038Z","steps":["trace[519317257] 'agreement among raft nodes before linearized reading' (duration: 310.787276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:42:47.059131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:42:46.74813Z","time spent":"310.988399ms","remote":"127.0.0.1:48408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-09-08T15:42:47.058813Z","caller":"traceutil/trace.go:171","msg":"trace[1816123032] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"310.598372ms","start":"2026-09-08T15:42:46.748186Z","end":"2026-09-08T15:42:47.058785Z","steps":["trace[1816123032] 'read index received' (duration: 310.363548ms)","trace[1816123032] 'applied index is now lower than readState.Index' (duration: 233.924µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:42:47.060187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.616917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11977"} {"level":"info","ts":"2026-09-08T15:42:47.060282Z","caller":"traceutil/trace.go:171","msg":"trace[22719722] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2015; }","duration":"229.77107ms","start":"2026-09-08T15:42:46.830482Z","end":"2026-09-08T15:42:47.060253Z","steps":["trace[22719722] 'agreement among raft nodes before linearized reading' (duration: 229.356192ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:23.510852Z","caller":"traceutil/trace.go:171","msg":"trace[796790904] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2307; }","duration":"209.039116ms","start":"2026-09-08T15:43:23.301783Z","end":"2026-09-08T15:43:23.510822Z","steps":["trace[796790904] 'read index received' (duration: 208.67475ms)","trace[796790904] 'applied index is now lower than readState.Index' (duration: 363.096µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:43:23.511014Z","caller":"traceutil/trace.go:171","msg":"trace[861310205] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"231.9293ms","start":"2026-09-08T15:43:23.279072Z","end":"2026-09-08T15:43:23.511001Z","steps":["trace[861310205] 'process raft request' (duration: 231.513283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:23.51119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.832554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:43:23.511244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.433913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:43:23.511288Z","caller":"traceutil/trace.go:171","msg":"trace[946558380] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2223; }","duration":"168.972306ms","start":"2026-09-08T15:43:23.34229Z","end":"2026-09-08T15:43:23.511263Z","steps":["trace[946558380] 'agreement among raft nodes before linearized reading' (duration: 168.811373ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:23.511341Z","caller":"traceutil/trace.go:171","msg":"trace[1579453062] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2223; }","duration":"209.573395ms","start":"2026-09-08T15:43:23.301747Z","end":"2026-09-08T15:43:23.51132Z","steps":["trace[1579453062] 'agreement among raft nodes before linearized reading' (duration: 209.387191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:43:23.844164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.281252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:43:23.844256Z","caller":"traceutil/trace.go:171","msg":"trace[1152997969] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2223; }","duration":"144.385163ms","start":"2026-09-08T15:43:23.69985Z","end":"2026-09-08T15:43:23.844235Z","steps":["trace[1152997969] 'count revisions from in-memory index tree' (duration: 144.180501ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:23.844685Z","caller":"traceutil/trace.go:171","msg":"trace[923922001] linearizableReadLoop","detail":"{readStateIndex:2310; appliedIndex:2308; }","duration":"124.884988ms","start":"2026-09-08T15:43:23.719782Z","end":"2026-09-08T15:43:23.844667Z","steps":["trace[923922001] 'read index received' (duration: 29.389565ms)","trace[923922001] 'applied index is now lower than readState.Index' (duration: 95.494313ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:43:23.844853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.085402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:43:23.844802Z","caller":"traceutil/trace.go:171","msg":"trace[216699806] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"129.67454ms","start":"2026-09-08T15:43:23.715081Z","end":"2026-09-08T15:43:23.844756Z","steps":["trace[216699806] 'process raft request' (duration: 129.462617ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:23.844918Z","caller":"traceutil/trace.go:171","msg":"trace[1778212113] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2225; }","duration":"125.189213ms","start":"2026-09-08T15:43:23.719712Z","end":"2026-09-08T15:43:23.844901Z","steps":["trace[1778212113] 'agreement among raft nodes before linearized reading' (duration: 125.085981ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:43:23.845041Z","caller":"traceutil/trace.go:171","msg":"trace[1661669132] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"131.826028ms","start":"2026-09-08T15:43:23.713192Z","end":"2026-09-08T15:43:23.845018Z","steps":["trace[1661669132] 'process raft request' (duration: 36.06373ms)","trace[1661669132] 'compare' (duration: 94.859143ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:44:18.013414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.321122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:44:18.013544Z","caller":"traceutil/trace.go:171","msg":"trace[57144854] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2619; }","duration":"104.516949ms","start":"2026-09-08T15:44:17.909003Z","end":"2026-09-08T15:44:18.01352Z","steps":["trace[57144854] 'count revisions from in-memory index tree' (duration: 104.240229ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:44:50.536789Z","caller":"traceutil/trace.go:171","msg":"trace[1913732071] linearizableReadLoop","detail":"{readStateIndex:2905; appliedIndex:2905; }","duration":"117.629225ms","start":"2026-09-08T15:44:50.419132Z","end":"2026-09-08T15:44:50.536762Z","steps":["trace[1913732071] 'read index received' (duration: 117.621105ms)","trace[1913732071] 'applied index is now lower than readState.Index' (duration: 6.26µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:44:50.536799Z","caller":"traceutil/trace.go:171","msg":"trace[1216653473] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"166.739254ms","start":"2026-09-08T15:44:50.370029Z","end":"2026-09-08T15:44:50.536768Z","steps":["trace[1216653473] 'process raft request' (duration: 166.58966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:50.536972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.82692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-08T15:44:50.536994Z","caller":"traceutil/trace.go:171","msg":"trace[1152846510] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2800; }","duration":"117.890462ms","start":"2026-09-08T15:44:50.419096Z","end":"2026-09-08T15:44:50.536987Z","steps":["trace[1152846510] 'agreement among raft nodes before linearized reading' (duration: 117.749588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:50.844536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.855407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:44:50.844659Z","caller":"traceutil/trace.go:171","msg":"trace[692002847] linearizableReadLoop","detail":"{readStateIndex:2907; appliedIndex:2906; }","duration":"236.212208ms","start":"2026-09-08T15:44:50.60843Z","end":"2026-09-08T15:44:50.844642Z","steps":["trace[692002847] 'read index received' (duration: 124.128314ms)","trace[692002847] 'applied index is now lower than readState.Index' (duration: 112.082774ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:44:50.844752Z","caller":"traceutil/trace.go:171","msg":"trace[1155093071] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"264.413347ms","start":"2026-09-08T15:44:50.580326Z","end":"2026-09-08T15:44:50.84474Z","steps":["trace[1155093071] 'process raft request' (duration: 152.216471ms)","trace[1155093071] 'compare' (duration: 111.732493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:44:50.845071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.62694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:44:50.845141Z","caller":"traceutil/trace.go:171","msg":"trace[266013897] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2802; }","duration":"236.730713ms","start":"2026-09-08T15:44:50.608391Z","end":"2026-09-08T15:44:50.845122Z","steps":["trace[266013897] 'agreement among raft nodes before linearized reading' (duration: 236.585788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:44:50.845573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.617899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18d362f2829ee9c6\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-09-08T15:44:50.845664Z","caller":"traceutil/trace.go:171","msg":"trace[113825496] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18d362f2829ee9c6; range_end:; response_count:1; response_revision:2802; }","duration":"134.746343ms","start":"2026-09-08T15:44:50.710901Z","end":"2026-09-08T15:44:50.845647Z","steps":["trace[113825496] 'agreement among raft nodes before linearized reading' (duration: 133.875068ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:46.643165Z","caller":"traceutil/trace.go:171","msg":"trace[699025957] transaction","detail":"{read_only:false; response_revision:3094; number_of_response:1; }","duration":"155.145213ms","start":"2026-09-08T15:45:46.487989Z","end":"2026-09-08T15:45:46.643134Z","steps":["trace[699025957] 'process raft request' (duration: 154.200279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:47.090288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.760365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:47.090408Z","caller":"traceutil/trace.go:171","msg":"trace[1269943120] transaction","detail":"{read_only:false; response_revision:3097; number_of_response:1; }","duration":"198.62704ms","start":"2026-09-08T15:45:46.891749Z","end":"2026-09-08T15:45:47.090376Z","steps":["trace[1269943120] 'process raft request' (duration: 21.69799ms)","trace[1269943120] 'compare' (duration: 176.531039ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:47.815534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.48644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:47.815848Z","caller":"traceutil/trace.go:171","msg":"trace[568106272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3101; }","duration":"236.84912ms","start":"2026-09-08T15:45:47.578972Z","end":"2026-09-08T15:45:47.815821Z","steps":["trace[568106272] 'range keys from in-memory index tree' (duration: 236.392098ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:47.816207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.093778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:47.816356Z","caller":"traceutil/trace.go:171","msg":"trace[700557182] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3101; }","duration":"139.322334ms","start":"2026-09-08T15:45:47.67701Z","end":"2026-09-08T15:45:47.816332Z","steps":["trace[700557182] 'count revisions from in-memory index tree' (duration: 139.023226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:47.816465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.037288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:47.816542Z","caller":"traceutil/trace.go:171","msg":"trace[717742186] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:3101; }","duration":"179.89431ms","start":"2026-09-08T15:45:47.636629Z","end":"2026-09-08T15:45:47.816523Z","steps":["trace[717742186] 'count revisions from in-memory index tree' (duration: 178.946295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.551775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.126014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-08T15:45:49.552048Z","caller":"traceutil/trace.go:171","msg":"trace[549384862] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3106; }","duration":"378.473662ms","start":"2026-09-08T15:45:49.173549Z","end":"2026-09-08T15:45:49.552023Z","steps":["trace[549384862] 'range keys from in-memory index tree' (duration: 377.863167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.552064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.784176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-09-08T15:45:49.552162Z","caller":"traceutil/trace.go:171","msg":"trace[209565103] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3106; }","duration":"304.93311ms","start":"2026-09-08T15:45:49.247207Z","end":"2026-09-08T15:45:49.55214Z","steps":["trace[209565103] 'range keys from in-memory index tree' (duration: 304.612872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.55222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.247178Z","time spent":"305.028812ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-08T15:45:49.552242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.173527Z","time spent":"378.603876ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-08T15:45:49.552872Z","caller":"traceutil/trace.go:171","msg":"trace[557923584] linearizableReadLoop","detail":"{readStateIndex:3224; appliedIndex:3223; }","duration":"155.515507ms","start":"2026-09-08T15:45:49.39733Z","end":"2026-09-08T15:45:49.552846Z","steps":["trace[557923584] 'read index received' (duration: 92.790797ms)","trace[557923584] 'applied index is now lower than readState.Index' (duration: 62.72309ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:49.552958Z","caller":"traceutil/trace.go:171","msg":"trace[1695114802] transaction","detail":"{read_only:false; response_revision:3107; number_of_response:1; }","duration":"305.654469ms","start":"2026-09-08T15:45:49.247276Z","end":"2026-09-08T15:45:49.552931Z","steps":["trace[1695114802] 'process raft request' (duration: 242.97486ms)","trace[1695114802] 'compare' (duration: 60.902873ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:49.553082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:49.247242Z","time spent":"305.762832ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:49.553104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.765704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T15:45:49.553152Z","caller":"traceutil/trace.go:171","msg":"trace[1252256699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3107; }","duration":"155.844395ms","start":"2026-09-08T15:45:49.397292Z","end":"2026-09-08T15:45:49.553137Z","steps":["trace[1252256699] 'agreement among raft nodes before linearized reading' (duration: 155.715933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.553841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.544645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T15:45:49.55391Z","caller":"traceutil/trace.go:171","msg":"trace[1718510389] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3107; }","duration":"139.656057ms","start":"2026-09-08T15:45:49.414236Z","end":"2026-09-08T15:45:49.553892Z","steps":["trace[1718510389] 'agreement among raft nodes before linearized reading' (duration: 139.414991ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:49.708496Z","caller":"traceutil/trace.go:171","msg":"trace[886616299] transaction","detail":"{read_only:false; response_revision:3109; number_of_response:1; }","duration":"150.284395ms","start":"2026-09-08T15:45:49.558183Z","end":"2026-09-08T15:45:49.708467Z","steps":["trace[886616299] 'process raft request' (duration: 107.965229ms)","trace[886616299] 'compare' (duration: 42.11079ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:49.708808Z","caller":"traceutil/trace.go:171","msg":"trace[17892207] transaction","detail":"{read_only:false; response_revision:3110; number_of_response:1; }","duration":"149.002223ms","start":"2026-09-08T15:45:49.559716Z","end":"2026-09-08T15:45:49.708718Z","steps":["trace[17892207] 'process raft request' (duration: 148.701505ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:49.70884Z","caller":"traceutil/trace.go:171","msg":"trace[773593964] linearizableReadLoop","detail":"{readStateIndex:3226; appliedIndex:3224; }","duration":"149.755633ms","start":"2026-09-08T15:45:49.559064Z","end":"2026-09-08T15:45:49.70882Z","steps":["trace[773593964] 'read index received' (duration: 24.764835ms)","trace[773593964] 'applied index is now lower than readState.Index' (duration: 124.989748ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:49.709045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.959498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-09-08T15:45:49.709111Z","caller":"traceutil/trace.go:171","msg":"trace[451242912] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3111; }","duration":"150.06445ms","start":"2026-09-08T15:45:49.55903Z","end":"2026-09-08T15:45:49.709095Z","steps":["trace[451242912] 'agreement among raft nodes before linearized reading' (duration: 149.850154ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:49.709234Z","caller":"traceutil/trace.go:171","msg":"trace[794548903] transaction","detail":"{read_only:false; response_revision:3111; number_of_response:1; }","duration":"148.982602ms","start":"2026-09-08T15:45:49.560183Z","end":"2026-09-08T15:45:49.709166Z","steps":["trace[794548903] 'process raft request' (duration: 148.439389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.738852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.263836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:49.738929Z","caller":"traceutil/trace.go:171","msg":"trace[894614048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3112; }","duration":"162.39727ms","start":"2026-09-08T15:45:49.576512Z","end":"2026-09-08T15:45:49.73891Z","steps":["trace[894614048] 'agreement among raft nodes before linearized reading' (duration: 162.263307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:49.738961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.364048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:49.739037Z","caller":"traceutil/trace.go:171","msg":"trace[1368832716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:3112; }","duration":"106.576254ms","start":"2026-09-08T15:45:49.63244Z","end":"2026-09-08T15:45:49.739017Z","steps":["trace[1368832716] 'agreement among raft nodes before linearized reading' (duration: 106.340658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:50.163292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.715634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:50.163389Z","caller":"traceutil/trace.go:171","msg":"trace[376394548] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:3112; }","duration":"184.852697ms","start":"2026-09-08T15:45:49.978513Z","end":"2026-09-08T15:45:50.163366Z","steps":["trace[376394548] 'count revisions from in-memory index tree' (duration: 184.555841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:50.720769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.230023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:50.720913Z","caller":"traceutil/trace.go:171","msg":"trace[1085849536] transaction","detail":"{read_only:false; response_revision:3115; number_of_response:1; }","duration":"150.837561ms","start":"2026-09-08T15:45:50.57005Z","end":"2026-09-08T15:45:50.720888Z","steps":["trace[1085849536] 'process raft request' (duration: 49.387922ms)","trace[1085849536] 'compare' (duration: 101.077179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:51.109092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.257746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:45:51.109198Z","caller":"traceutil/trace.go:171","msg":"trace[625857030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3117; }","duration":"108.380429ms","start":"2026-09-08T15:45:51.000793Z","end":"2026-09-08T15:45:51.109173Z","steps":["trace[625857030] 'range keys from in-memory index tree' (duration: 107.902868ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:51.886688Z","caller":"traceutil/trace.go:171","msg":"trace[1243722330] linearizableReadLoop","detail":"{readStateIndex:3240; appliedIndex:3239; }","duration":"172.640089ms","start":"2026-09-08T15:45:51.714025Z","end":"2026-09-08T15:45:51.886665Z","steps":["trace[1243722330] 'read index received' (duration: 172.382653ms)","trace[1243722330] 'applied index is now lower than readState.Index' (duration: 256.396µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:51.886732Z","caller":"traceutil/trace.go:171","msg":"trace[1947868672] transaction","detail":"{read_only:false; response_revision:3122; number_of_response:1; }","duration":"282.023763ms","start":"2026-09-08T15:45:51.60468Z","end":"2026-09-08T15:45:51.886704Z","steps":["trace[1947868672] 'process raft request' (duration: 281.816548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:51.887057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.902657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-08T15:45:51.887116Z","caller":"traceutil/trace.go:171","msg":"trace[1565443645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3122; }","duration":"172.973718ms","start":"2026-09-08T15:45:51.714127Z","end":"2026-09-08T15:45:51.887101Z","steps":["trace[1565443645] 'agreement among raft nodes before linearized reading' (duration: 172.689221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:51.887083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.02266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T15:45:51.887182Z","caller":"traceutil/trace.go:171","msg":"trace[1477747036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3122; }","duration":"173.174254ms","start":"2026-09-08T15:45:51.713983Z","end":"2026-09-08T15:45:51.887157Z","steps":["trace[1477747036] 'agreement among raft nodes before linearized reading' (duration: 172.826485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:53.020065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.598589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:45:53.020167Z","caller":"traceutil/trace.go:171","msg":"trace[642240438] transaction","detail":"{read_only:false; response_revision:3127; number_of_response:1; }","duration":"222.759874ms","start":"2026-09-08T15:45:52.797388Z","end":"2026-09-08T15:45:53.020148Z","steps":["trace[642240438] 'process raft request' (duration: 53.999971ms)","trace[642240438] 'compare' (duration: 168.499257ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:53.340753Z","caller":"traceutil/trace.go:171","msg":"trace[695071857] transaction","detail":"{read_only:false; response_revision:3129; number_of_response:1; }","duration":"127.591248ms","start":"2026-09-08T15:45:53.213127Z","end":"2026-09-08T15:45:53.340719Z","steps":["trace[695071857] 'process raft request' (duration: 102.05325ms)","trace[695071857] 'compare' (duration: 25.427015ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:54.059053Z","caller":"traceutil/trace.go:171","msg":"trace[1156536864] linearizableReadLoop","detail":"{readStateIndex:3250; appliedIndex:3249; }","duration":"123.031421ms","start":"2026-09-08T15:45:53.935976Z","end":"2026-09-08T15:45:54.059007Z","steps":["trace[1156536864] 'read index received' (duration: 29.366043ms)","trace[1156536864] 'applied index is now lower than readState.Index' (duration: 93.664178ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:54.059307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.317318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T15:45:54.059369Z","caller":"traceutil/trace.go:171","msg":"trace[1901892135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3132; }","duration":"123.430941ms","start":"2026-09-08T15:45:53.93592Z","end":"2026-09-08T15:45:54.059351Z","steps":["trace[1901892135] 'agreement among raft nodes before linearized reading' (duration: 123.211555ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:54.059485Z","caller":"traceutil/trace.go:171","msg":"trace[129339890] transaction","detail":"{read_only:false; response_revision:3132; number_of_response:1; }","duration":"139.923422ms","start":"2026-09-08T15:45:53.919538Z","end":"2026-09-08T15:45:54.059462Z","steps":["trace[129339890] 'process raft request' (duration: 45.848894ms)","trace[129339890] 'compare' (duration: 93.176826ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:54.277221Z","caller":"traceutil/trace.go:171","msg":"trace[1505831259] linearizableReadLoop","detail":"{readStateIndex:3251; appliedIndex:3250; }","duration":"196.501738ms","start":"2026-09-08T15:45:54.080694Z","end":"2026-09-08T15:45:54.277196Z","steps":["trace[1505831259] 'read index received' (duration: 111.56671ms)","trace[1505831259] 'applied index is now lower than readState.Index' (duration: 84.934068ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:54.277491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.743965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:45:54.277542Z","caller":"traceutil/trace.go:171","msg":"trace[426756742] transaction","detail":"{read_only:false; response_revision:3133; number_of_response:1; }","duration":"213.472162ms","start":"2026-09-08T15:45:54.064056Z","end":"2026-09-08T15:45:54.277528Z","steps":["trace[426756742] 'process raft request' (duration: 128.220026ms)","trace[426756742] 'compare' (duration: 84.817855ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:54.277577Z","caller":"traceutil/trace.go:171","msg":"trace[787347350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3133; }","duration":"196.893798ms","start":"2026-09-08T15:45:54.080662Z","end":"2026-09-08T15:45:54.277556Z","steps":["trace[787347350] 'agreement among raft nodes before linearized reading' (duration: 196.710414ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:55.311842Z","caller":"traceutil/trace.go:171","msg":"trace[1994195713] transaction","detail":"{read_only:false; response_revision:3138; number_of_response:1; }","duration":"287.937723ms","start":"2026-09-08T15:45:55.023859Z","end":"2026-09-08T15:45:55.311796Z","steps":["trace[1994195713] 'process raft request' (duration: 263.374532ms)","trace[1994195713] 'compare' (duration: 24.142842ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:55.311836Z","caller":"traceutil/trace.go:171","msg":"trace[2122175564] linearizableReadLoop","detail":"{readStateIndex:3256; appliedIndex:3255; }","duration":"280.899099ms","start":"2026-09-08T15:45:55.030906Z","end":"2026-09-08T15:45:55.311805Z","steps":["trace[2122175564] 'read index received' (duration: 256.43279ms)","trace[2122175564] 'applied index is now lower than readState.Index' (duration: 24.443499ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:55.312012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.075783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:55.312371Z","caller":"traceutil/trace.go:171","msg":"trace[553357711] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:3138; }","duration":"281.476483ms","start":"2026-09-08T15:45:55.030864Z","end":"2026-09-08T15:45:55.312341Z","steps":["trace[553357711] 'agreement among raft nodes before linearized reading' (duration: 281.059913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:56.11031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.079799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-08T15:45:56.110387Z","caller":"traceutil/trace.go:171","msg":"trace[467574390] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3142; }","duration":"191.197662ms","start":"2026-09-08T15:45:55.91917Z","end":"2026-09-08T15:45:56.110368Z","steps":["trace[467574390] 'range keys from in-memory index tree' (duration: 190.857413ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:56.238853Z","caller":"traceutil/trace.go:171","msg":"trace[972523292] transaction","detail":"{read_only:false; response_revision:3143; number_of_response:1; }","duration":"124.220294ms","start":"2026-09-08T15:45:56.114607Z","end":"2026-09-08T15:45:56.238827Z","steps":["trace[972523292] 'process raft request' (duration: 124.06421ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:56.273894Z","caller":"traceutil/trace.go:171","msg":"trace[2115838138] transaction","detail":"{read_only:false; response_revision:3144; number_of_response:1; }","duration":"158.26041ms","start":"2026-09-08T15:45:56.115606Z","end":"2026-09-08T15:45:56.273867Z","steps":["trace[2115838138] 'process raft request' (duration: 158.073826ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:57.638939Z","caller":"traceutil/trace.go:171","msg":"trace[1315426684] linearizableReadLoop","detail":"{readStateIndex:3269; appliedIndex:3268; }","duration":"171.625182ms","start":"2026-09-08T15:45:57.467289Z","end":"2026-09-08T15:45:57.638914Z","steps":["trace[1315426684] 'read index received' (duration: 78.841113ms)","trace[1315426684] 'applied index is now lower than readState.Index' (duration: 92.782749ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:45:57.63902Z","caller":"traceutil/trace.go:171","msg":"trace[1896528586] transaction","detail":"{read_only:false; response_revision:3150; number_of_response:1; }","duration":"174.202477ms","start":"2026-09-08T15:45:57.464787Z","end":"2026-09-08T15:45:57.63899Z","steps":["trace[1896528586] 'process raft request' (duration: 100.42751ms)","trace[1896528586] 'compare' (duration: 73.465429ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:45:57.639181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.834318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:45:57.639202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.889869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7341"} {"level":"info","ts":"2026-09-08T15:45:57.63923Z","caller":"traceutil/trace.go:171","msg":"trace[1192948942] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3150; }","duration":"146.936811ms","start":"2026-09-08T15:45:57.492273Z","end":"2026-09-08T15:45:57.63921Z","steps":["trace[1192948942] 'agreement among raft nodes before linearized reading' (duration: 146.839398ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:57.639267Z","caller":"traceutil/trace.go:171","msg":"trace[1356955291] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3150; }","duration":"172.003232ms","start":"2026-09-08T15:45:57.467248Z","end":"2026-09-08T15:45:57.639251Z","steps":["trace[1356955291] 'agreement among raft nodes before linearized reading' (duration: 171.789716ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:58.613865Z","caller":"traceutil/trace.go:171","msg":"trace[1258372676] transaction","detail":"{read_only:false; response_revision:3154; number_of_response:1; }","duration":"301.451455ms","start":"2026-09-08T15:45:58.312392Z","end":"2026-09-08T15:45:58.613843Z","steps":["trace[1258372676] 'process raft request' (duration: 301.27621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:58.613987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:45:58.312375Z","time spent":"301.546268ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:45:59.773872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.340057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:45:59.773964Z","caller":"traceutil/trace.go:171","msg":"trace[1602131381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3158; }","duration":"130.44292ms","start":"2026-09-08T15:45:59.6435Z","end":"2026-09-08T15:45:59.773943Z","steps":["trace[1602131381] 'range keys from in-memory index tree' (duration: 130.054231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:45:59.774529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.571927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:45:59.774626Z","caller":"traceutil/trace.go:171","msg":"trace[63775502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3158; }","duration":"195.67915ms","start":"2026-09-08T15:45:59.578928Z","end":"2026-09-08T15:45:59.774607Z","steps":["trace[63775502] 'range keys from in-memory index tree' (duration: 195.449664ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:45:59.958385Z","caller":"traceutil/trace.go:171","msg":"trace[1559988674] transaction","detail":"{read_only:false; response_revision:3160; number_of_response:1; }","duration":"180.309881ms","start":"2026-09-08T15:45:59.778048Z","end":"2026-09-08T15:45:59.958358Z","steps":["trace[1559988674] 'process raft request' (duration: 108.963319ms)","trace[1559988674] 'compare' (duration: 71.249289ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:00.377184Z","caller":"traceutil/trace.go:171","msg":"trace[706568372] linearizableReadLoop","detail":"{readStateIndex:3280; appliedIndex:3279; }","duration":"289.828519ms","start":"2026-09-08T15:46:00.087329Z","end":"2026-09-08T15:46:00.377157Z","steps":["trace[706568372] 'read index received' (duration: 194.984463ms)","trace[706568372] 'applied index is now lower than readState.Index' (duration: 94.843096ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:00.377323Z","caller":"traceutil/trace.go:171","msg":"trace[550310858] transaction","detail":"{read_only:false; response_revision:3161; number_of_response:1; }","duration":"315.011219ms","start":"2026-09-08T15:46:00.06228Z","end":"2026-09-08T15:46:00.377292Z","steps":["trace[550310858] 'process raft request' (duration: 220.018549ms)","trace[550310858] 'compare' (duration: 94.734714ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:00.377956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.610779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.68\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-08T15:46:00.378036Z","caller":"traceutil/trace.go:171","msg":"trace[777125870] range","detail":"{range_begin:/registry/masterleases/162.253.55.68; range_end:; response_count:1; response_revision:3161; }","duration":"290.742612ms","start":"2026-09-08T15:46:00.087271Z","end":"2026-09-08T15:46:00.378013Z","steps":["trace[777125870] 'agreement among raft nodes before linearized reading' (duration: 290.503256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:00.377992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.436603ms","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-09-08T15:46:00.378222Z","caller":"traceutil/trace.go:171","msg":"trace[1849666855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3161; }","duration":"104.70456ms","start":"2026-09-08T15:46:00.273494Z","end":"2026-09-08T15:46:00.378199Z","steps":["trace[1849666855] 'agreement among raft nodes before linearized reading' (duration: 103.810648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:00.378007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:00.062253Z","time spent":"315.109332ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:01.768655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.359567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T15:46:01.768752Z","caller":"traceutil/trace.go:171","msg":"trace[700034888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3169; }","duration":"187.484579ms","start":"2026-09-08T15:46:01.581243Z","end":"2026-09-08T15:46:01.768728Z","steps":["trace[700034888] 'range keys from in-memory index tree' (duration: 187.164612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:01.769343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.127574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:01.769407Z","caller":"traceutil/trace.go:171","msg":"trace[1756361114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3169; }","duration":"190.239878ms","start":"2026-09-08T15:46:01.579151Z","end":"2026-09-08T15:46:01.769391Z","steps":["trace[1756361114] 'range keys from in-memory index tree' (duration: 190.037413ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:02.002065Z","caller":"traceutil/trace.go:171","msg":"trace[377514570] transaction","detail":"{read_only:false; response_revision:3171; number_of_response:1; }","duration":"204.312273ms","start":"2026-09-08T15:46:01.79772Z","end":"2026-09-08T15:46:02.002033Z","steps":["trace[377514570] 'process raft request' (duration: 203.579265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:02.940467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.762814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T15:46:02.940553Z","caller":"traceutil/trace.go:171","msg":"trace[1875190694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3175; }","duration":"283.898977ms","start":"2026-09-08T15:46:02.656633Z","end":"2026-09-08T15:46:02.940532Z","steps":["trace[1875190694] 'range keys from in-memory index tree' (duration: 283.518688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:02.940993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.878088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-08T15:46:02.941032Z","caller":"traceutil/trace.go:171","msg":"trace[152527889] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3175; }","duration":"268.969351ms","start":"2026-09-08T15:46:02.672051Z","end":"2026-09-08T15:46:02.941021Z","steps":["trace[152527889] 'range keys from in-memory index tree' (duration: 268.744815ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:03.262994Z","caller":"traceutil/trace.go:171","msg":"trace[1042835644] transaction","detail":"{read_only:false; response_revision:3176; number_of_response:1; }","duration":"317.845887ms","start":"2026-09-08T15:46:02.945115Z","end":"2026-09-08T15:46:03.262961Z","steps":["trace[1042835644] 'process raft request' (duration: 317.632332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:03.263272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:02.945091Z","time spent":"317.99517ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:03.586079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.98533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:03.586281Z","caller":"traceutil/trace.go:171","msg":"trace[1993666484] transaction","detail":"{read_only:false; response_revision:3179; number_of_response:1; }","duration":"140.935293ms","start":"2026-09-08T15:46:03.445329Z","end":"2026-09-08T15:46:03.586264Z","steps":["trace[1993666484] 'process raft request' (duration: 140.898532ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:03.586307Z","caller":"traceutil/trace.go:171","msg":"trace[1243543277] transaction","detail":"{read_only:false; response_revision:3177; number_of_response:1; }","duration":"641.023903ms","start":"2026-09-08T15:46:02.945248Z","end":"2026-09-08T15:46:03.586271Z","steps":["trace[1243543277] 'process raft request' (duration: 502.764306ms)","trace[1243543277] 'compare' (duration: 137.896858ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:03.586495Z","caller":"traceutil/trace.go:171","msg":"trace[1056578414] transaction","detail":"{read_only:false; response_revision:3178; number_of_response:1; }","duration":"621.612467ms","start":"2026-09-08T15:46:02.96487Z","end":"2026-09-08T15:46:03.586482Z","steps":["trace[1056578414] 'process raft request' (duration: 621.295379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:03.586554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:02.964844Z","time spent":"621.673178ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:03.586589Z","caller":"traceutil/trace.go:171","msg":"trace[226461004] linearizableReadLoop","detail":"{readStateIndex:3297; appliedIndex:3295; }","duration":"640.394617ms","start":"2026-09-08T15:46:02.946131Z","end":"2026-09-08T15:46:03.586526Z","steps":["trace[226461004] 'read index received' (duration: 316.677118ms)","trace[226461004] 'applied index is now lower than readState.Index' (duration: 323.711099ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:03.586808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.672295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-08T15:46:03.586848Z","caller":"traceutil/trace.go:171","msg":"trace[1119773789] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3179; }","duration":"640.736396ms","start":"2026-09-08T15:46:02.946101Z","end":"2026-09-08T15:46:03.586837Z","steps":["trace[1119773789] 'agreement among raft nodes before linearized reading' (duration: 640.583912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:03.586876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:02.94609Z","time spent":"640.777927ms","remote":"127.0.0.1:56018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-08T15:46:03.586843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:02.945226Z","time spent":"641.254629ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:03.587078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.355773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T15:46:03.587128Z","caller":"traceutil/trace.go:171","msg":"trace[553247411] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3179; }","duration":"170.489427ms","start":"2026-09-08T15:46:03.416624Z","end":"2026-09-08T15:46:03.587114Z","steps":["trace[553247411] 'agreement among raft nodes before linearized reading' (duration: 170.144498ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:03.944081Z","caller":"traceutil/trace.go:171","msg":"trace[1596830778] transaction","detail":"{read_only:false; response_revision:3180; number_of_response:1; }","duration":"105.808081ms","start":"2026-09-08T15:46:03.838246Z","end":"2026-09-08T15:46:03.944054Z","steps":["trace[1596830778] 'process raft request' (duration: 105.671938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.359806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.431457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:04.359921Z","caller":"traceutil/trace.go:171","msg":"trace[1976995911] linearizableReadLoop","detail":"{readStateIndex:3301; appliedIndex:3300; }","duration":"334.378384ms","start":"2026-09-08T15:46:04.02552Z","end":"2026-09-08T15:46:04.359899Z","steps":["trace[1976995911] 'read index received' (duration: 163.723901ms)","trace[1976995911] 'applied index is now lower than readState.Index' (duration: 170.652453ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:04.359956Z","caller":"traceutil/trace.go:171","msg":"trace[666666613] transaction","detail":"{read_only:false; response_revision:3181; number_of_response:1; }","duration":"351.970915ms","start":"2026-09-08T15:46:04.007958Z","end":"2026-09-08T15:46:04.359929Z","steps":["trace[666666613] 'process raft request' (duration: 181.300872ms)","trace[666666613] 'compare' (duration: 170.187441ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:04.360211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:04.007924Z","time spent":"352.20399ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:04.36019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.66145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:46:04.360282Z","caller":"traceutil/trace.go:171","msg":"trace[1774903067] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:3181; }","duration":"334.794333ms","start":"2026-09-08T15:46:04.02547Z","end":"2026-09-08T15:46:04.360265Z","steps":["trace[1774903067] 'agreement among raft nodes before linearized reading' (duration: 334.590228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.360299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.668032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T15:46:04.36035Z","caller":"traceutil/trace.go:171","msg":"trace[1539576286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3181; }","duration":"305.745113ms","start":"2026-09-08T15:46:04.054592Z","end":"2026-09-08T15:46:04.360337Z","steps":["trace[1539576286] 'agreement among raft nodes before linearized reading' (duration: 305.584929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.360382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:04.054581Z","time spent":"305.792964ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-08T15:46:04.360376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:04.025452Z","time spent":"334.904217ms","remote":"127.0.0.1:56154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":23,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-09-08T15:46:04.560102Z","caller":"traceutil/trace.go:171","msg":"trace[1694374292] transaction","detail":"{read_only:false; response_revision:3182; number_of_response:1; }","duration":"194.876574ms","start":"2026-09-08T15:46:04.365191Z","end":"2026-09-08T15:46:04.560067Z","steps":["trace[1694374292] 'process raft request' (duration: 167.397052ms)","trace[1694374292] 'compare' (duration: 27.275287ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:04.953127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.851542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:04.953238Z","caller":"traceutil/trace.go:171","msg":"trace[1385823402] linearizableReadLoop","detail":"{readStateIndex:3304; appliedIndex:3302; }","duration":"374.532416ms","start":"2026-09-08T15:46:04.578685Z","end":"2026-09-08T15:46:04.953218Z","steps":["trace[1385823402] 'read index received' (duration: 60.837268ms)","trace[1385823402] 'applied index is now lower than readState.Index' (duration: 313.693358ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:04.953321Z","caller":"traceutil/trace.go:171","msg":"trace[1423391556] transaction","detail":"{read_only:false; response_revision:3184; number_of_response:1; }","duration":"389.371398ms","start":"2026-09-08T15:46:04.563926Z","end":"2026-09-08T15:46:04.953297Z","steps":["trace[1423391556] 'process raft request' (duration: 182.996173ms)","trace[1423391556] 'compare' (duration: 205.706199ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:04.953418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.7097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:04.953499Z","caller":"traceutil/trace.go:171","msg":"trace[475680474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3184; }","duration":"374.861194ms","start":"2026-09-08T15:46:04.578618Z","end":"2026-09-08T15:46:04.953479Z","steps":["trace[475680474] 'agreement among raft nodes before linearized reading' (duration: 374.696111ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:04.953542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:04.578589Z","time spent":"374.945625ms","remote":"127.0.0.1:55840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T15:46:04.953613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.119455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-09-08T15:46:04.953512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:04.563908Z","time spent":"389.545651ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:04.95367Z","caller":"traceutil/trace.go:171","msg":"trace[1444753854] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3184; }","duration":"239.215857ms","start":"2026-09-08T15:46:04.714439Z","end":"2026-09-08T15:46:04.953655Z","steps":["trace[1444753854] 'agreement among raft nodes before linearized reading' (duration: 238.89335ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:05.788217Z","caller":"traceutil/trace.go:171","msg":"trace[172409329] linearizableReadLoop","detail":"{readStateIndex:3307; appliedIndex:3306; }","duration":"371.810932ms","start":"2026-09-08T15:46:05.416377Z","end":"2026-09-08T15:46:05.788188Z","steps":["trace[172409329] 'read index received' (duration: 371.772871ms)","trace[172409329] 'applied index is now lower than readState.Index' (duration: 36.341µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:05.788335Z","caller":"traceutil/trace.go:171","msg":"trace[241233674] transaction","detail":"{read_only:false; response_revision:3187; number_of_response:1; }","duration":"432.188675ms","start":"2026-09-08T15:46:05.356135Z","end":"2026-09-08T15:46:05.788324Z","steps":["trace[241233674] 'process raft request' (duration: 431.401806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.788435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.356108Z","time spent":"432.252555ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:05.788465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.073839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T15:46:05.789114Z","caller":"traceutil/trace.go:171","msg":"trace[193488224] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3187; }","duration":"372.765485ms","start":"2026-09-08T15:46:05.416321Z","end":"2026-09-08T15:46:05.789086Z","steps":["trace[193488224] 'agreement among raft nodes before linearized reading' (duration: 372.021137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.789231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.416294Z","time spent":"372.92017ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-09-08T15:46:05.841784Z","caller":"traceutil/trace.go:171","msg":"trace[1118333530] transaction","detail":"{read_only:false; response_revision:3188; number_of_response:1; }","duration":"249.664312ms","start":"2026-09-08T15:46:05.592101Z","end":"2026-09-08T15:46:05.841765Z","steps":["trace[1118333530] 'process raft request' (duration: 249.409716ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:46:05.841771Z","caller":"traceutil/trace.go:171","msg":"trace[466519367] transaction","detail":"{read_only:false; response_revision:3189; number_of_response:1; }","duration":"248.534844ms","start":"2026-09-08T15:46:05.593208Z","end":"2026-09-08T15:46:05.841743Z","steps":["trace[466519367] 'process raft request' (duration: 248.471112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.966701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.9632ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-08T15:46:05.966836Z","caller":"traceutil/trace.go:171","msg":"trace[900065646] linearizableReadLoop","detail":"{readStateIndex:3310; appliedIndex:3307; }","duration":"178.490495ms","start":"2026-09-08T15:46:05.788285Z","end":"2026-09-08T15:46:05.966775Z","steps":["trace[900065646] 'read index received' (duration: 53.131996ms)","trace[900065646] 'applied index is now lower than readState.Index' (duration: 125.357179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:46:05.966966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.926263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18d362f2829ee9c6\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-09-08T15:46:05.967017Z","caller":"traceutil/trace.go:171","msg":"trace[414125641] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18d362f2829ee9c6; range_end:; response_count:1; response_revision:3189; }","duration":"256.023476ms","start":"2026-09-08T15:46:05.710981Z","end":"2026-09-08T15:46:05.967004Z","steps":["trace[414125641] 'agreement among raft nodes before linearized reading' (duration: 255.850083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.966994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.875807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T15:46:05.967081Z","caller":"traceutil/trace.go:171","msg":"trace[1226106188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3189; }","duration":"374.99991ms","start":"2026-09-08T15:46:05.592059Z","end":"2026-09-08T15:46:05.967059Z","steps":["trace[1226106188] 'agreement among raft nodes before linearized reading' (duration: 374.773926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.967164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.59204Z","time spent":"375.111153ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-08T15:46:05.967246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.528498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-09-08T15:46:05.967288Z","caller":"traceutil/trace.go:171","msg":"trace[2060852398] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:3189; }","duration":"174.629041ms","start":"2026-09-08T15:46:05.792648Z","end":"2026-09-08T15:46:05.967277Z","steps":["trace[2060852398] 'agreement among raft nodes before linearized reading' (duration: 174.345384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.967673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.112879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:46:05.967738Z","caller":"traceutil/trace.go:171","msg":"trace[325599191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3189; }","duration":"388.906039ms","start":"2026-09-08T15:46:05.578818Z","end":"2026-09-08T15:46:05.967724Z","steps":["trace[325599191] 'agreement among raft nodes before linearized reading' (duration: 388.067638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:05.968135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.578796Z","time spent":"389.3233ms","remote":"127.0.0.1:55840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T15:46:06.399895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.420914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:06.400472Z","caller":"traceutil/trace.go:171","msg":"trace[1774599164] linearizableReadLoop","detail":"{readStateIndex:3313; appliedIndex:3311; }","duration":"390.358477ms","start":"2026-09-08T15:46:06.010088Z","end":"2026-09-08T15:46:06.400446Z","steps":["trace[1774599164] 'read index received' (duration: 147.297557ms)","trace[1774599164] 'applied index is now lower than readState.Index' (duration: 243.05941ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:06.400498Z","caller":"traceutil/trace.go:171","msg":"trace[875282379] transaction","detail":"{read_only:false; response_revision:3191; number_of_response:1; }","duration":"427.640827ms","start":"2026-09-08T15:46:05.972802Z","end":"2026-09-08T15:46:06.400443Z","steps":["trace[875282379] 'process raft request' (duration: 184.520436ms)","trace[875282379] 'compare' (duration: 242.088216ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:46:06.400645Z","caller":"traceutil/trace.go:171","msg":"trace[811757473] transaction","detail":"{read_only:false; response_revision:3192; number_of_response:1; }","duration":"427.226706ms","start":"2026-09-08T15:46:05.973296Z","end":"2026-09-08T15:46:06.400522Z","steps":["trace[811757473] 'process raft request' (duration: 427.051752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:06.400806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.972782Z","time spent":"427.878553ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:46:06.400791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.935833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"warn","ts":"2026-09-08T15:46:06.400849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.973282Z","time spent":"427.462683ms","remote":"127.0.0.1:56078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:46:06.400891Z","caller":"traceutil/trace.go:171","msg":"trace[359950941] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3192; }","duration":"430.082615ms","start":"2026-09-08T15:46:05.970784Z","end":"2026-09-08T15:46:06.400867Z","steps":["trace[359950941] 'agreement among raft nodes before linearized reading' (duration: 429.742897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:06.400952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:46:05.970768Z","time spent":"430.167457ms","remote":"127.0.0.1:56022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1341,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-09-08T15:46:06.404675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.397765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-08T15:46:06.404772Z","caller":"traceutil/trace.go:171","msg":"trace[744517307] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3193; }","duration":"208.542129ms","start":"2026-09-08T15:46:06.196211Z","end":"2026-09-08T15:46:06.404753Z","steps":["trace[744517307] 'agreement among raft nodes before linearized reading' (duration: 208.024556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:46:06.894069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.137292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:46:06.894168Z","caller":"traceutil/trace.go:171","msg":"trace[477748764] transaction","detail":"{read_only:false; response_revision:3196; number_of_response:1; }","duration":"247.182249ms","start":"2026-09-08T15:46:06.646966Z","end":"2026-09-08T15:46:06.894148Z","steps":["trace[477748764] 'process raft request' (duration: 90.893653ms)","trace[477748764] 'compare' (duration: 156.03904ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:20.759107Z","caller":"traceutil/trace.go:171","msg":"trace[1197481602] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"101.837856ms","start":"2026-09-08T15:49:20.657247Z","end":"2026-09-08T15:49:20.759085Z","steps":["trace[1197481602] 'process raft request' (duration: 92.265436ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:21.562499Z","caller":"traceutil/trace.go:171","msg":"trace[80693837] linearizableReadLoop","detail":"{readStateIndex:5000; appliedIndex:4995; }","duration":"208.102746ms","start":"2026-09-08T15:49:21.354387Z","end":"2026-09-08T15:49:21.56249Z","steps":["trace[80693837] 'read index received' (duration: 6.0609ms)","trace[80693837] 'applied index is now lower than readState.Index' (duration: 202.041256ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:21.562558Z","caller":"traceutil/trace.go:171","msg":"trace[375204032] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"209.154968ms","start":"2026-09-08T15:49:21.353399Z","end":"2026-09-08T15:49:21.562554Z","steps":["trace[375204032] 'process raft request' (duration: 209.045505ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:21.562619Z","caller":"traceutil/trace.go:171","msg":"trace[2045100732] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"208.048544ms","start":"2026-09-08T15:49:21.354565Z","end":"2026-09-08T15:49:21.562614Z","steps":["trace[2045100732] 'process raft request' (duration: 207.899412ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:21.562659Z","caller":"traceutil/trace.go:171","msg":"trace[1123359405] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"209.32182ms","start":"2026-09-08T15:49:21.353332Z","end":"2026-09-08T15:49:21.562654Z","steps":["trace[1123359405] 'process raft request' (duration: 209.066896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:21.562739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.34816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-08T15:49:21.562756Z","caller":"traceutil/trace.go:171","msg":"trace[1975290538] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4817; }","duration":"208.403152ms","start":"2026-09-08T15:49:21.354347Z","end":"2026-09-08T15:49:21.56275Z","steps":["trace[1975290538] 'agreement among raft nodes before linearized reading' (duration: 208.32783ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:21.562499Z","caller":"traceutil/trace.go:171","msg":"trace[1026950905] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"209.383052ms","start":"2026-09-08T15:49:21.353102Z","end":"2026-09-08T15:49:21.562485Z","steps":["trace[1026950905] 'process raft request' (duration: 111.488686ms)","trace[1026950905] 'compare' (duration: 97.705723ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:21.606065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.48907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T15:49:21.606225Z","caller":"traceutil/trace.go:171","msg":"trace[1069249079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4818; }","duration":"179.696634ms","start":"2026-09-08T15:49:21.426507Z","end":"2026-09-08T15:49:21.606204Z","steps":["trace[1069249079] 'agreement among raft nodes before linearized reading' (duration: 179.373087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:21.60624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.253004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T15:49:21.606436Z","caller":"traceutil/trace.go:171","msg":"trace[1546981355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4818; }","duration":"185.472549ms","start":"2026-09-08T15:49:21.420939Z","end":"2026-09-08T15:49:21.606411Z","steps":["trace[1546981355] 'agreement among raft nodes before linearized reading' (duration: 185.167023ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:22.24102Z","caller":"traceutil/trace.go:171","msg":"trace[1286532319] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"130.659753ms","start":"2026-09-08T15:49:22.110339Z","end":"2026-09-08T15:49:22.240998Z","steps":["trace[1286532319] 'process raft request' (duration: 130.50878ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:24.951462Z","caller":"traceutil/trace.go:171","msg":"trace[1935413006] linearizableReadLoop","detail":"{readStateIndex:5083; appliedIndex:5082; }","duration":"153.622954ms","start":"2026-09-08T15:49:24.797813Z","end":"2026-09-08T15:49:24.951436Z","steps":["trace[1935413006] 'read index received' (duration: 153.545602ms)","trace[1935413006] 'applied index is now lower than readState.Index' (duration: 75.941µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:24.951606Z","caller":"traceutil/trace.go:171","msg":"trace[1542542868] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"180.794099ms","start":"2026-09-08T15:49:24.770777Z","end":"2026-09-08T15:49:24.951571Z","steps":["trace[1542542868] 'process raft request' (duration: 180.478593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:24.951795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.884089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:24.951913Z","caller":"traceutil/trace.go:171","msg":"trace[938899518] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4897; }","duration":"154.108284ms","start":"2026-09-08T15:49:24.797781Z","end":"2026-09-08T15:49:24.951889Z","steps":["trace[938899518] 'agreement among raft nodes before linearized reading' (duration: 153.795837ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:30.633459Z","caller":"traceutil/trace.go:171","msg":"trace[1678697053] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"192.48176ms","start":"2026-09-08T15:49:30.440943Z","end":"2026-09-08T15:49:30.633425Z","steps":["trace[1678697053] 'process raft request' (duration: 181.935712ms)","trace[1678697053] 'compare' (duration: 10.225951ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:30.666679Z","caller":"traceutil/trace.go:171","msg":"trace[834545887] linearizableReadLoop","detail":"{readStateIndex:5162; appliedIndex:5160; }","duration":"168.700131ms","start":"2026-09-08T15:49:30.497939Z","end":"2026-09-08T15:49:30.666639Z","steps":["trace[834545887] 'read index received' (duration: 124.944469ms)","trace[834545887] 'applied index is now lower than readState.Index' (duration: 43.754692ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:30.666795Z","caller":"traceutil/trace.go:171","msg":"trace[381491470] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"209.074226ms","start":"2026-09-08T15:49:30.457709Z","end":"2026-09-08T15:49:30.666784Z","steps":["trace[381491470] 'process raft request' (duration: 208.7321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:30.667047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.088809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-08T15:49:30.667092Z","caller":"traceutil/trace.go:171","msg":"trace[1416699530] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4972; }","duration":"169.18597ms","start":"2026-09-08T15:49:30.497891Z","end":"2026-09-08T15:49:30.667077Z","steps":["trace[1416699530] 'agreement among raft nodes before linearized reading' (duration: 169.038837ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:30.741686Z","caller":"traceutil/trace.go:171","msg":"trace[1719021966] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"101.632672ms","start":"2026-09-08T15:49:30.640026Z","end":"2026-09-08T15:49:30.741659Z","steps":["trace[1719021966] 'process raft request' (duration: 101.312185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:30.741769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.847137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:30.741899Z","caller":"traceutil/trace.go:171","msg":"trace[1982714007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4973; }","duration":"163.002951ms","start":"2026-09-08T15:49:30.578851Z","end":"2026-09-08T15:49:30.741854Z","steps":["trace[1982714007] 'agreement among raft nodes before linearized reading' (duration: 162.805337ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:31.378446Z","caller":"traceutil/trace.go:171","msg":"trace[30918950] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"143.28472ms","start":"2026-09-08T15:49:31.235128Z","end":"2026-09-08T15:49:31.378412Z","steps":["trace[30918950] 'process raft request' (duration: 120.844948ms)","trace[30918950] 'compare' (duration: 22.142946ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:31.668752Z","caller":"traceutil/trace.go:171","msg":"trace[1332351855] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5172; }","duration":"201.089709ms","start":"2026-09-08T15:49:31.467632Z","end":"2026-09-08T15:49:31.668722Z","steps":["trace[1332351855] 'read index received' (duration: 160.01745ms)","trace[1332351855] 'applied index is now lower than readState.Index' (duration: 41.070959ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:31.668919Z","caller":"traceutil/trace.go:171","msg":"trace[1123970462] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"237.697729ms","start":"2026-09-08T15:49:31.431087Z","end":"2026-09-08T15:49:31.668784Z","steps":["trace[1123970462] 'process raft request' (duration: 196.64453ms)","trace[1123970462] 'compare' (duration: 40.811684ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:31.669201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.552737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-08T15:49:31.669269Z","caller":"traceutil/trace.go:171","msg":"trace[1814330271] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4983; }","duration":"201.665789ms","start":"2026-09-08T15:49:31.46758Z","end":"2026-09-08T15:49:31.669246Z","steps":["trace[1814330271] 'agreement among raft nodes before linearized reading' (duration: 201.241902ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:32.545462Z","caller":"traceutil/trace.go:171","msg":"trace[1394511107] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"101.748831ms","start":"2026-09-08T15:49:32.443673Z","end":"2026-09-08T15:49:32.545422Z","steps":["trace[1394511107] 'process raft request' (duration: 101.502767ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:06.472708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2885} {"level":"info","ts":"2026-09-08T15:50:06.683684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2885,"took":"209.470763ms","hash":3535035957,"current-db-size-bytes":52314112,"current-db-size":"52 MB","current-db-size-in-use-bytes":27623424,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T15:50:06.683816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3535035957,"revision":2885,"compact-revision":-1} {"level":"info","ts":"2026-09-08T15:53:24.379004Z","caller":"traceutil/trace.go:171","msg":"trace[1385215211] transaction","detail":"{read_only:false; response_revision:6805; number_of_response:1; }","duration":"108.699506ms","start":"2026-09-08T15:53:24.27028Z","end":"2026-09-08T15:53:24.37898Z","steps":["trace[1385215211] 'process raft request' (duration: 108.404917ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:24.439971Z","caller":"traceutil/trace.go:171","msg":"trace[1619830709] transaction","detail":"{read_only:false; response_revision:6806; number_of_response:1; }","duration":"165.780029ms","start":"2026-09-08T15:53:24.274159Z","end":"2026-09-08T15:53:24.439939Z","steps":["trace[1619830709] 'process raft request' (duration: 165.576662ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:43.548342Z","caller":"traceutil/trace.go:171","msg":"trace[1363573663] transaction","detail":"{read_only:false; response_revision:6957; number_of_response:1; }","duration":"100.602414ms","start":"2026-09-08T15:53:43.447712Z","end":"2026-09-08T15:53:43.548314Z","steps":["trace[1363573663] 'process raft request' (duration: 52.322481ms)","trace[1363573663] 'compare' (duration: 48.125128ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:55:06.483389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5356} {"level":"info","ts":"2026-09-08T15:55:06.562355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5356,"took":"77.280714ms","hash":451262122,"current-db-size-bytes":52314112,"current-db-size":"52 MB","current-db-size-in-use-bytes":18673664,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-08T15:55:06.562432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":451262122,"revision":5356,"compact-revision":2885}