{"level":"warn","ts":"2026-10-10T10:18:20.771711Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-10T10:18:20.771813Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.167: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.167:2380","--initial-cluster=instance=https://162.253.55.167:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.167:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.167: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-10-10T10:18:20.771884Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-10T10:18:20.7719Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-10-10T10:18:20.771925Z","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-10-10T10:18:20.772769Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"]} {"level":"info","ts":"2026-10-10T10:18:20.772872Z","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.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.167: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-10-10T10:18:20.78197Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.845453ms"} {"level":"info","ts":"2026-10-10T10:18:20.79341Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"83287f3a81241ad4","cluster-id":"185ed9b8ed398b9a"} {"level":"info","ts":"2026-10-10T10:18:20.793579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=()"} {"level":"info","ts":"2026-10-10T10:18:20.793612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 0"} {"level":"info","ts":"2026-10-10T10:18:20.793624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 83287f3a81241ad4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-10T10:18:20.793639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 1"} {"level":"info","ts":"2026-10-10T10:18:20.793688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"warn","ts":"2026-10-10T10:18:20.805645Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-10T10:18:20.807545Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-10T10:18:20.808851Z","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-10-10T10:18:20.811294Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"83287f3a81241ad4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-10T10:18:20.811496Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"83287f3a81241ad4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-10T10:18:20.811845Z","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-10-10T10:18:20.811949Z","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-10-10T10:18:20.81196Z","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-10-10T10:18:20.812464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"info","ts":"2026-10-10T10:18:20.812604Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","added-peer-id":"83287f3a81241ad4","added-peer-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-10-10T10:18:20.824219Z","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-10-10T10:18:20.824483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"83287f3a81241ad4","initial-advertise-peer-urls":["https://162.253.55.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-10T10:18:20.824523Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-10T10:18:20.824607Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-10-10T10:18:20.824632Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-10-10T10:18:21.594156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 is starting a new election at term 1"} {"level":"info","ts":"2026-10-10T10:18:21.594247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-10T10:18:21.594309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgPreVoteResp from 83287f3a81241ad4 at term 1"} {"level":"info","ts":"2026-10-10T10:18:21.594344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became candidate at term 2"} {"level":"info","ts":"2026-10-10T10:18:21.594359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgVoteResp from 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-10-10T10:18:21.594374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became leader at term 2"} {"level":"info","ts":"2026-10-10T10:18:21.594393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 83287f3a81241ad4 elected leader 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-10-10T10:18:21.595891Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"83287f3a81241ad4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.167:2379]}","request-path":"/0/members/83287f3a81241ad4/attributes","cluster-id":"185ed9b8ed398b9a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-10T10:18:21.595965Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-10T10:18:21.595937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-10T10:18:21.596075Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-10T10:18:21.596118Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-10T10:18:21.596404Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:18:21.597739Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:18:21.597855Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:18:21.597875Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-10T10:18:21.599499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-10T10:18:21.599677Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.167:2379"} {"level":"info","ts":"2026-10-10T10:18:25.155913Z","caller":"traceutil/trace.go:171","msg":"trace[457874455] transaction","detail":"{read_only:false; response_revision:143; number_of_response:1; }","duration":"264.956517ms","start":"2026-10-10T10:18:24.890923Z","end":"2026-10-10T10:18:25.15588Z","steps":["trace[457874455] 'process raft request' (duration: 202.002545ms)","trace[457874455] 'compare' (duration: 62.82755ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:18:29.270344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.085217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-10T10:18:29.270467Z","caller":"traceutil/trace.go:171","msg":"trace[1848013009] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:287; }","duration":"181.287661ms","start":"2026-10-10T10:18:29.089156Z","end":"2026-10-10T10:18:29.270444Z","steps":["trace[1848013009] 'range keys from in-memory index tree' (duration: 180.979646ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:18:29.487207Z","caller":"traceutil/trace.go:171","msg":"trace[1507432308] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:297; }","duration":"136.387363ms","start":"2026-10-10T10:18:29.350792Z","end":"2026-10-10T10:18:29.48718Z","steps":["trace[1507432308] 'read index received' (duration: 136.2611ms)","trace[1507432308] 'applied index is now lower than readState.Index' (duration: 124.823µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:18:29.487264Z","caller":"traceutil/trace.go:171","msg":"trace[417842394] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"213.152733ms","start":"2026-10-10T10:18:29.274076Z","end":"2026-10-10T10:18:29.487229Z","steps":["trace[417842394] 'process raft request' (duration: 212.914048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:18:29.487599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.673268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-10T10:18:29.487564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.716818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-10T10:18:29.487654Z","caller":"traceutil/trace.go:171","msg":"trace[1173540706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:288; }","duration":"136.865391ms","start":"2026-10-10T10:18:29.350776Z","end":"2026-10-10T10:18:29.487642Z","steps":["trace[1173540706] 'agreement among raft nodes before linearized reading' (duration: 136.564066ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:18:29.487684Z","caller":"traceutil/trace.go:171","msg":"trace[934210591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:288; }","duration":"136.902122ms","start":"2026-10-10T10:18:29.350757Z","end":"2026-10-10T10:18:29.48766Z","steps":["trace[934210591] 'agreement among raft nodes before linearized reading' (duration: 136.553476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:18:30.358256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.474577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:18:30.358387Z","caller":"traceutil/trace.go:171","msg":"trace[76391352] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"863.25246ms","start":"2026-10-10T10:18:29.495115Z","end":"2026-10-10T10:18:30.358367Z","steps":["trace[76391352] 'read index received' (duration: 421.769485ms)","trace[76391352] 'applied index is now lower than readState.Index' (duration: 441.481335ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:18:30.358577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.431293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-10T10:18:30.358725Z","caller":"traceutil/trace.go:171","msg":"trace[29463389] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"863.619156ms","start":"2026-10-10T10:18:29.495083Z","end":"2026-10-10T10:18:30.358703Z","steps":["trace[29463389] 'agreement among raft nodes before linearized reading' (duration: 863.372402ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:18:30.358573Z","caller":"traceutil/trace.go:171","msg":"trace[613824819] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"865.455988ms","start":"2026-10-10T10:18:29.493044Z","end":"2026-10-10T10:18:30.3585Z","steps":["trace[613824819] 'process raft request' (duration: 423.891342ms)","trace[613824819] 'compare' (duration: 440.298394ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:18:30.358814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:18:29.49507Z","time spent":"863.730598ms","remote":"127.0.0.1:39506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-10-10T10:18:30.358984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:18:29.493018Z","time spent":"865.848934ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:18:31.233364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.168158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:18:31.233911Z","caller":"traceutil/trace.go:171","msg":"trace[1411484539] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"1.73839776s","start":"2026-10-10T10:18:29.495482Z","end":"2026-10-10T10:18:31.23388Z","steps":["trace[1411484539] 'process raft request' (duration: 1.24857124s)","trace[1411484539] 'compare' (duration: 488.987966ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:18:31.233993Z","caller":"traceutil/trace.go:171","msg":"trace[228690141] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"870.832926ms","start":"2026-10-10T10:18:30.363145Z","end":"2026-10-10T10:18:31.233978Z","steps":["trace[228690141] 'process raft request' (duration: 870.733515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:18:31.234037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:18:29.495464Z","time spent":"1.738526992s","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:18:31.234068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:18:30.363113Z","time spent":"870.919778ms","remote":"127.0.0.1:39604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:18:31.234133Z","caller":"traceutil/trace.go:171","msg":"trace[1932847273] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"875.677ms","start":"2026-10-10T10:18:30.358414Z","end":"2026-10-10T10:18:31.234091Z","steps":["trace[1932847273] 'read index received' (duration: 385.617417ms)","trace[1932847273] 'applied index is now lower than readState.Index' (duration: 490.055823ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:18:31.236898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293219459s","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-10-10T10:18:31.236992Z","caller":"traceutil/trace.go:171","msg":"trace[2120270544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:291; }","duration":"1.2933284s","start":"2026-10-10T10:18:29.943624Z","end":"2026-10-10T10:18:31.236952Z","steps":["trace[2120270544] 'agreement among raft nodes before linearized reading' (duration: 1.290697715s)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:18:31.237052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:18:29.943542Z","time spent":"1.293498004s","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-10T10:18:31.237201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.888112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-10-10T10:18:31.237279Z","caller":"traceutil/trace.go:171","msg":"trace[1715222923] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:291; }","duration":"705.015486ms","start":"2026-10-10T10:18:30.532239Z","end":"2026-10-10T10:18:31.237255Z","steps":["trace[1715222923] 'agreement among raft nodes before linearized reading' (duration: 703.521819ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:18:31.237335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:18:30.532218Z","time spent":"705.096726ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-10-10T10:18:31.238216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.45849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-10T10:18:31.238327Z","caller":"traceutil/trace.go:171","msg":"trace[1827249706] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:291; }","duration":"475.608253ms","start":"2026-10-10T10:18:30.762674Z","end":"2026-10-10T10:18:31.238283Z","steps":["trace[1827249706] 'agreement among raft nodes before linearized reading' (duration: 473.220282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:18:31.238372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:18:30.762648Z","time spent":"475.712685ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-10T10:19:05.883672Z","caller":"traceutil/trace.go:171","msg":"trace[1319852469] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"502.324112ms","start":"2026-10-10T10:19:05.38132Z","end":"2026-10-10T10:19:05.883644Z","steps":["trace[1319852469] 'process raft request' (duration: 502.19304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.883907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.381279Z","time spent":"502.558095ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:19:05.89247Z","caller":"traceutil/trace.go:171","msg":"trace[580571512] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"503.918388ms","start":"2026-10-10T10:19:05.388509Z","end":"2026-10-10T10:19:05.892428Z","steps":["trace[580571512] 'process raft request' (duration: 503.571773ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:05.892495Z","caller":"traceutil/trace.go:171","msg":"trace[578015096] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"501.926745ms","start":"2026-10-10T10:19:05.390535Z","end":"2026-10-10T10:19:05.892461Z","steps":["trace[578015096] 'process raft request' (duration: 501.782733ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:05.892663Z","caller":"traceutil/trace.go:171","msg":"trace[1077876408] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"484.172019ms","start":"2026-10-10T10:19:05.408427Z","end":"2026-10-10T10:19:05.892599Z","steps":["trace[1077876408] 'process raft request' (duration: 483.937945ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:05.89282Z","caller":"traceutil/trace.go:171","msg":"trace[1658388715] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"484.227971ms","start":"2026-10-10T10:19:05.408533Z","end":"2026-10-10T10:19:05.892761Z","steps":["trace[1658388715] 'process raft request' (duration: 483.918476ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:05.892873Z","caller":"traceutil/trace.go:171","msg":"trace[1481559069] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"484.20215ms","start":"2026-10-10T10:19:05.408657Z","end":"2026-10-10T10:19:05.892859Z","steps":["trace[1481559069] 'process raft request' (duration: 483.973596ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:05.89376Z","caller":"traceutil/trace.go:171","msg":"trace[841174786] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"464.620172ms","start":"2026-10-10T10:19:05.428525Z","end":"2026-10-10T10:19:05.893145Z","steps":["trace[841174786] 'process raft request' (duration: 464.394569ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:05.893858Z","caller":"traceutil/trace.go:171","msg":"trace[1272998355] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"456.687117ms","start":"2026-10-10T10:19:05.436495Z","end":"2026-10-10T10:19:05.893183Z","steps":["trace[1272998355] 'process raft request' (duration: 456.515634ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.893945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.408649Z","time spent":"485.168817ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:05.894049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.436474Z","time spent":"457.44613ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:05.892976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.408397Z","time spent":"484.396834ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:05.892665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.388485Z","time spent":"504.107961ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:05.892927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.390522Z","time spent":"502.319831ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:05.894702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.428503Z","time spent":"465.312145ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:05.892995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.408527Z","time spent":"484.388194ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:19:05.893045Z","caller":"traceutil/trace.go:171","msg":"trace[1930182312] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"483.865923ms","start":"2026-10-10T10:19:05.409161Z","end":"2026-10-10T10:19:05.893027Z","steps":["trace[1930182312] 'process raft request' (duration: 483.544258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.896247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.40914Z","time spent":"487.05462ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:19:05.893075Z","caller":"traceutil/trace.go:171","msg":"trace[1903134566] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"482.842266ms","start":"2026-10-10T10:19:05.410213Z","end":"2026-10-10T10:19:05.893055Z","steps":["trace[1903134566] 'process raft request' (duration: 482.604312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.896805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.410158Z","time spent":"486.600372ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:19:05.897981Z","caller":"traceutil/trace.go:171","msg":"trace[1559517062] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"438.240401ms","start":"2026-10-10T10:19:05.459712Z","end":"2026-10-10T10:19:05.897952Z","steps":["trace[1559517062] 'process raft request' (duration: 433.430297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.899812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.459682Z","time spent":"440.092632ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:19:05.906793Z","caller":"traceutil/trace.go:171","msg":"trace[1405372183] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:520; }","duration":"429.415879ms","start":"2026-10-10T10:19:05.470225Z","end":"2026-10-10T10:19:05.899641Z","steps":["trace[1405372183] 'read index received' (duration: 413.323252ms)","trace[1405372183] 'applied index is now lower than readState.Index' (duration: 16.084426ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:05.899685Z","caller":"traceutil/trace.go:171","msg":"trace[1843033134] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"301.704204ms","start":"2026-10-10T10:19:05.597866Z","end":"2026-10-10T10:19:05.89957Z","steps":["trace[1843033134] 'process raft request' (duration: 301.41097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.906998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.597844Z","time spent":"309.06614ms","remote":"127.0.0.1:39434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:05.90704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.805485ms","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-10-10T10:19:05.907088Z","caller":"traceutil/trace.go:171","msg":"trace[600122081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"436.897127ms","start":"2026-10-10T10:19:05.470173Z","end":"2026-10-10T10:19:05.90707Z","steps":["trace[600122081] 'agreement among raft nodes before linearized reading' (duration: 429.550581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.907126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.470152Z","time spent":"436.964108ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-10T10:19:05.907164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.115039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-10T10:19:05.907187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.230267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:19:05.907222Z","caller":"traceutil/trace.go:171","msg":"trace[512817543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"413.22388ms","start":"2026-10-10T10:19:05.493982Z","end":"2026-10-10T10:19:05.907206Z","steps":["trace[512817543] 'agreement among raft nodes before linearized reading' (duration: 413.066547ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:05.907233Z","caller":"traceutil/trace.go:171","msg":"trace[1310816566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"233.311708ms","start":"2026-10-10T10:19:05.673901Z","end":"2026-10-10T10:19:05.907213Z","steps":["trace[1310816566] 'agreement among raft nodes before linearized reading' (duration: 233.229147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.907256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.493962Z","time spent":"413.286382ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-10T10:19:05.907534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.478139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.167\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-10T10:19:05.907719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.964918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-10T10:19:05.907759Z","caller":"traceutil/trace.go:171","msg":"trace[767652910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"412.029259ms","start":"2026-10-10T10:19:05.495719Z","end":"2026-10-10T10:19:05.907748Z","steps":["trace[767652910] 'agreement among raft nodes before linearized reading' (duration: 411.948618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.907786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.495706Z","time spent":"412.07258ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-10T10:19:05.907981Z","caller":"traceutil/trace.go:171","msg":"trace[283852741] range","detail":"{range_begin:/registry/masterleases/162.253.55.167; range_end:; response_count:1; response_revision:517; }","duration":"337.599761ms","start":"2026-10-10T10:19:05.569957Z","end":"2026-10-10T10:19:05.907557Z","steps":["trace[283852741] 'agreement among raft nodes before linearized reading' (duration: 337.491209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:05.908037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:05.56994Z","time spent":"338.083308ms","remote":"127.0.0.1:39416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.167\" "} {"level":"warn","ts":"2026-10-10T10:19:09.1227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.72647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:19:09.122821Z","caller":"traceutil/trace.go:171","msg":"trace[2056362913] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"668.786028ms","start":"2026-10-10T10:19:08.454014Z","end":"2026-10-10T10:19:09.1228Z","steps":["trace[2056362913] 'process raft request' (duration: 312.890165ms)","trace[2056362913] 'compare' (duration: 355.326854ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:19:09.123039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:08.453987Z","time spent":"669.020162ms","remote":"127.0.0.1:39604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:19:09.345827Z","caller":"traceutil/trace.go:171","msg":"trace[1695968922] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"786.214983ms","start":"2026-10-10T10:19:08.559563Z","end":"2026-10-10T10:19:09.345778Z","steps":["trace[1695968922] 'read index received' (duration: 207.359702ms)","trace[1695968922] 'applied index is now lower than readState.Index' (duration: 578.853701ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:09.345885Z","caller":"traceutil/trace.go:171","msg":"trace[1704612529] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"891.770638ms","start":"2026-10-10T10:19:08.454081Z","end":"2026-10-10T10:19:09.345852Z","steps":["trace[1704612529] 'process raft request' (duration: 891.109027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:09.346046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:08.454054Z","time spent":"891.91832ms","remote":"127.0.0.1:39496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:09.34626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.661792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-10T10:19:09.346392Z","caller":"traceutil/trace.go:171","msg":"trace[1873820246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"786.859875ms","start":"2026-10-10T10:19:08.559504Z","end":"2026-10-10T10:19:09.346364Z","steps":["trace[1873820246] 'agreement among raft nodes before linearized reading' (duration: 786.452957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:09.346448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:08.559486Z","time spent":"786.952236ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-10T10:19:09.36073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.907345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:19:09.36086Z","caller":"traceutil/trace.go:171","msg":"trace[16035127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"688.062598ms","start":"2026-10-10T10:19:08.672741Z","end":"2026-10-10T10:19:09.360804Z","steps":["trace[16035127] 'agreement among raft nodes before linearized reading' (duration: 687.01871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:09.360944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:08.672731Z","time spent":"688.16678ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-10T10:19:09.360716Z","caller":"traceutil/trace.go:171","msg":"trace[1160120913] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"514.584698ms","start":"2026-10-10T10:19:08.845244Z","end":"2026-10-10T10:19:09.359829Z","steps":["trace[1160120913] 'process raft request' (duration: 514.012068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:09.361429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:08.845212Z","time spent":"516.108654ms","remote":"127.0.0.1:39482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-10T10:19:11.04252Z","caller":"traceutil/trace.go:171","msg":"trace[602616275] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"111.859111ms","start":"2026-10-10T10:19:10.930589Z","end":"2026-10-10T10:19:11.042448Z","steps":["trace[602616275] 'process raft request' (duration: 108.91196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:12.090321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.566931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-10T10:19:12.090413Z","caller":"traceutil/trace.go:171","msg":"trace[1422750749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"106.719723ms","start":"2026-10-10T10:19:11.983672Z","end":"2026-10-10T10:19:12.090392Z","steps":["trace[1422750749] 'range keys from in-memory index tree' (duration: 106.313635ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:12.429748Z","caller":"traceutil/trace.go:171","msg":"trace[1690690651] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"333.375804ms","start":"2026-10-10T10:19:12.096329Z","end":"2026-10-10T10:19:12.429704Z","steps":["trace[1690690651] 'process raft request' (duration: 333.13334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:12.430002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:12.096302Z","time spent":"333.552036ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:19:12.430087Z","caller":"traceutil/trace.go:171","msg":"trace[1001083461] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"331.910418ms","start":"2026-10-10T10:19:12.098088Z","end":"2026-10-10T10:19:12.429998Z","steps":["trace[1001083461] 'process raft request' (duration: 331.565473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:12.430361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:12.098076Z","time spent":"332.130132ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:19:12.450494Z","caller":"traceutil/trace.go:171","msg":"trace[831023167] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"267.579544ms","start":"2026-10-10T10:19:12.182827Z","end":"2026-10-10T10:19:12.450406Z","steps":["trace[831023167] 'process raft request' (duration: 259.81025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:14.200875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145600438s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:19:14.201001Z","caller":"traceutil/trace.go:171","msg":"trace[1682332412] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"819.083522ms","start":"2026-10-10T10:19:13.381898Z","end":"2026-10-10T10:19:14.200981Z","steps":["trace[1682332412] 'read index received' (duration: 39.071µs)","trace[1682332412] 'applied index is now lower than readState.Index' (duration: 819.043061ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:14.201077Z","caller":"traceutil/trace.go:171","msg":"trace[1105274908] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.160478443s","start":"2026-10-10T10:19:13.04056Z","end":"2026-10-10T10:19:14.201038Z","steps":["trace[1105274908] 'process raft request' (duration: 14.618751ms)","trace[1105274908] 'compare' (duration: 1.145368264s)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:19:14.201204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:13.040534Z","time spent":"1.160607075s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-10T10:19:14.201278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.345686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-10T10:19:14.20131Z","caller":"traceutil/trace.go:171","msg":"trace[1095029539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"819.450128ms","start":"2026-10-10T10:19:13.381848Z","end":"2026-10-10T10:19:14.201298Z","steps":["trace[1095029539] 'agreement among raft nodes before linearized reading' (duration: 819.230204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:14.201343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:13.381824Z","time spent":"819.511409ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-10T10:19:14.201756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.949025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8ww7r\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-10T10:19:14.201808Z","caller":"traceutil/trace.go:171","msg":"trace[716123220] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8ww7r; range_end:; response_count:1; response_revision:614; }","duration":"524.043937ms","start":"2026-10-10T10:19:13.677749Z","end":"2026-10-10T10:19:14.201793Z","steps":["trace[716123220] 'agreement among raft nodes before linearized reading' (duration: 523.723851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:14.201843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:13.677726Z","time spent":"524.109938ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-8ww7r\" "} {"level":"info","ts":"2026-10-10T10:19:14.707095Z","caller":"traceutil/trace.go:171","msg":"trace[620369467] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"501.360246ms","start":"2026-10-10T10:19:14.205707Z","end":"2026-10-10T10:19:14.707067Z","steps":["trace[620369467] 'process raft request' (duration: 411.384662ms)","trace[620369467] 'compare' (duration: 89.804591ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:19:14.707283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:14.205684Z","time spent":"501.49201ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:19:14.707662Z","caller":"traceutil/trace.go:171","msg":"trace[881284853] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"501.711423ms","start":"2026-10-10T10:19:14.205924Z","end":"2026-10-10T10:19:14.707636Z","steps":["trace[881284853] 'process raft request' (duration: 501.105862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:14.707836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:14.205914Z","time spent":"501.828894ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:19:14.707866Z","caller":"traceutil/trace.go:171","msg":"trace[178889425] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"495.168961ms","start":"2026-10-10T10:19:14.212664Z","end":"2026-10-10T10:19:14.707833Z","steps":["trace[178889425] 'read index received' (duration: 404.418753ms)","trace[178889425] 'applied index is now lower than readState.Index' (duration: 90.749248ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:19:14.707894Z","caller":"traceutil/trace.go:171","msg":"trace[590007487] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"486.267548ms","start":"2026-10-10T10:19:14.221605Z","end":"2026-10-10T10:19:14.707872Z","steps":["trace[590007487] 'process raft request' (duration: 485.727769ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:19:14.707969Z","caller":"traceutil/trace.go:171","msg":"trace[1339626472] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"136.647135ms","start":"2026-10-10T10:19:14.571307Z","end":"2026-10-10T10:19:14.707955Z","steps":["trace[1339626472] 'process raft request' (duration: 136.424491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:14.707997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.317303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-10T10:19:14.707999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:14.22159Z","time spent":"486.344789ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:19:14.708037Z","caller":"traceutil/trace.go:171","msg":"trace[1255068797] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"495.384274ms","start":"2026-10-10T10:19:14.212637Z","end":"2026-10-10T10:19:14.708021Z","steps":["trace[1255068797] 'agreement among raft nodes before linearized reading' (duration: 495.320543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:14.708074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:14.212608Z","time spent":"495.458506ms","remote":"127.0.0.1:39632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-10-10T10:19:14.708195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.587266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-10T10:19:14.708234Z","caller":"traceutil/trace.go:171","msg":"trace[434181186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"273.627487ms","start":"2026-10-10T10:19:14.434596Z","end":"2026-10-10T10:19:14.708224Z","steps":["trace[434181186] 'agreement among raft nodes before linearized reading' (duration: 273.481335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:14.708463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.968495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-10T10:19:14.70853Z","caller":"traceutil/trace.go:171","msg":"trace[312073182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"273.058788ms","start":"2026-10-10T10:19:14.435452Z","end":"2026-10-10T10:19:14.708511Z","steps":["trace[312073182] 'agreement among raft nodes before linearized reading' (duration: 272.879714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:31.299983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1933347321712279402,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-10T10:19:31.606167Z","caller":"traceutil/trace.go:171","msg":"trace[1435808190] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"813.208235ms","start":"2026-10-10T10:19:30.792899Z","end":"2026-10-10T10:19:31.606107Z","steps":["trace[1435808190] 'process raft request' (duration: 812.998801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:31.606375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:30.792885Z","time spent":"813.381527ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:19:31.613975Z","caller":"traceutil/trace.go:171","msg":"trace[1660733018] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:804; }","duration":"814.937724ms","start":"2026-10-10T10:19:30.799009Z","end":"2026-10-10T10:19:31.613946Z","steps":["trace[1660733018] 'read index received' (duration: 806.915627ms)","trace[1660733018] 'applied index is now lower than readState.Index' (duration: 8.020527ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:19:31.614275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.066922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-10T10:19:31.614315Z","caller":"traceutil/trace.go:171","msg":"trace[1169212860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"813.140123ms","start":"2026-10-10T10:19:30.801163Z","end":"2026-10-10T10:19:31.614303Z","steps":["trace[1169212860] 'agreement among raft nodes before linearized reading' (duration: 812.94426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:31.614349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:30.801157Z","time spent":"813.184974ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-10T10:19:31.614622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.567904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-10T10:19:31.614736Z","caller":"traceutil/trace.go:171","msg":"trace[1310612926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"815.705387ms","start":"2026-10-10T10:19:30.798998Z","end":"2026-10-10T10:19:31.614703Z","steps":["trace[1310612926] 'agreement among raft nodes before linearized reading' (duration: 815.069846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:31.614796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.490131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-10T10:19:31.614976Z","caller":"traceutil/trace.go:171","msg":"trace[193911559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"802.813616ms","start":"2026-10-10T10:19:30.812143Z","end":"2026-10-10T10:19:31.614956Z","steps":["trace[193911559] 'agreement among raft nodes before linearized reading' (duration: 802.265717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:31.615255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:30.812116Z","time spent":"803.088811ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-10T10:19:31.615457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.640879ms","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-10-10T10:19:31.615528Z","caller":"traceutil/trace.go:171","msg":"trace[1274942060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"779.72204ms","start":"2026-10-10T10:19:30.835779Z","end":"2026-10-10T10:19:31.615501Z","steps":["trace[1274942060] 'agreement among raft nodes before linearized reading' (duration: 778.556059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:19:31.615564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:30.835769Z","time spent":"779.788522ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-10T10:19:31.61486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:19:30.798993Z","time spent":"815.847909ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-10T10:19:45.143887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.087541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:19:45.143997Z","caller":"traceutil/trace.go:171","msg":"trace[255906026] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"179.552647ms","start":"2026-10-10T10:19:44.964424Z","end":"2026-10-10T10:19:45.143976Z","steps":["trace[255906026] 'process raft request' (duration: 39.302223ms)","trace[255906026] 'compare' (duration: 139.985359ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:20:02.091385Z","caller":"traceutil/trace.go:171","msg":"trace[970998878] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"293.63937ms","start":"2026-10-10T10:20:01.79772Z","end":"2026-10-10T10:20:02.091359Z","steps":["trace[970998878] 'read index received' (duration: 293.421166ms)","trace[970998878] 'applied index is now lower than readState.Index' (duration: 217.404µs)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:02.091845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.102568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-10T10:20:02.091845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.953102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-10T10:20:02.091905Z","caller":"traceutil/trace.go:171","msg":"trace[1321827969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1184; }","duration":"294.174919ms","start":"2026-10-10T10:20:01.797711Z","end":"2026-10-10T10:20:02.091886Z","steps":["trace[1321827969] 'agreement among raft nodes before linearized reading' (duration: 293.992166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:02.0919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.904342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-10T10:20:02.091941Z","caller":"traceutil/trace.go:171","msg":"trace[1258480886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"288.062675ms","start":"2026-10-10T10:20:01.803843Z","end":"2026-10-10T10:20:02.091905Z","steps":["trace[1258480886] 'agreement among raft nodes before linearized reading' (duration: 287.864361ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:02.091954Z","caller":"traceutil/trace.go:171","msg":"trace[280168710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"287.964413ms","start":"2026-10-10T10:20:01.803975Z","end":"2026-10-10T10:20:02.09194Z","steps":["trace[280168710] 'agreement among raft nodes before linearized reading' (duration: 287.801ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:02.092151Z","caller":"traceutil/trace.go:171","msg":"trace[2136819173] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"295.770056ms","start":"2026-10-10T10:20:01.796363Z","end":"2026-10-10T10:20:02.092133Z","steps":["trace[2136819173] 'process raft request' (duration: 294.84528ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:02.458244Z","caller":"traceutil/trace.go:171","msg":"trace[1226631307] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"361.344569ms","start":"2026-10-10T10:20:02.096861Z","end":"2026-10-10T10:20:02.458206Z","steps":["trace[1226631307] 'process raft request' (duration: 332.819461ms)","trace[1226631307] 'compare' (duration: 27.976849ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:02.458689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:02.096796Z","time spent":"361.535942ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:20:02.463255Z","caller":"traceutil/trace.go:171","msg":"trace[1525087557] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"365.588442ms","start":"2026-10-10T10:20:02.097612Z","end":"2026-10-10T10:20:02.463201Z","steps":["trace[1525087557] 'process raft request' (duration: 365.337108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:02.46363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:02.097583Z","time spent":"365.950398ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:20:02.463627Z","caller":"traceutil/trace.go:171","msg":"trace[549095759] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"364.774698ms","start":"2026-10-10T10:20:02.098811Z","end":"2026-10-10T10:20:02.463586Z","steps":["trace[549095759] 'process raft request' (duration: 364.34238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:02.464138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:02.098798Z","time spent":"365.204804ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:20:02.464353Z","caller":"traceutil/trace.go:171","msg":"trace[2079218280] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"324.356776ms","start":"2026-10-10T10:20:02.139919Z","end":"2026-10-10T10:20:02.464275Z","steps":["trace[2079218280] 'process raft request' (duration: 323.515431ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:02.464538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:02.139902Z","time spent":"324.514558ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:20:18.639105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.668666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-10T10:20:18.639185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.722305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-10T10:20:18.639227Z","caller":"traceutil/trace.go:171","msg":"trace[633586347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"113.795198ms","start":"2026-10-10T10:20:18.525374Z","end":"2026-10-10T10:20:18.63917Z","steps":["trace[633586347] 'range keys from in-memory index tree' (duration: 113.542354ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:18.639256Z","caller":"traceutil/trace.go:171","msg":"trace[829425968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"100.815646ms","start":"2026-10-10T10:20:18.538424Z","end":"2026-10-10T10:20:18.639239Z","steps":["trace[829425968] 'range keys from in-memory index tree' (duration: 100.605283ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:25.988892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.60893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1823"} {"level":"info","ts":"2026-10-10T10:20:25.988981Z","caller":"traceutil/trace.go:171","msg":"trace[88754091] range","detail":"{range_begin:/registry/secrets/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1446; }","duration":"125.741923ms","start":"2026-10-10T10:20:25.86322Z","end":"2026-10-10T10:20:25.988961Z","steps":["trace[88754091] 'range keys from in-memory index tree' (duration: 125.455158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:26.218461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.16942ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:20:26.218586Z","caller":"traceutil/trace.go:171","msg":"trace[891565860] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1449; }","duration":"119.306892ms","start":"2026-10-10T10:20:26.099262Z","end":"2026-10-10T10:20:26.218569Z","steps":["trace[891565860] 'range keys from in-memory index tree' (duration: 119.15945ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:26.226648Z","caller":"traceutil/trace.go:171","msg":"trace[551905974] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"161.334113ms","start":"2026-10-10T10:20:26.065278Z","end":"2026-10-10T10:20:26.226612Z","steps":["trace[551905974] 'process raft request' (duration: 161.15506ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:28.434995Z","caller":"traceutil/trace.go:171","msg":"trace[1058218811] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"194.951558ms","start":"2026-10-10T10:20:28.240019Z","end":"2026-10-10T10:20:28.434971Z","steps":["trace[1058218811] 'process raft request' (duration: 194.762045ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:28.830745Z","caller":"traceutil/trace.go:171","msg":"trace[834473359] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"125.889926ms","start":"2026-10-10T10:20:28.704833Z","end":"2026-10-10T10:20:28.830723Z","steps":["trace[834473359] 'process raft request' (duration: 125.56441ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:28.831742Z","caller":"traceutil/trace.go:171","msg":"trace[449594168] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"125.898346ms","start":"2026-10-10T10:20:28.70583Z","end":"2026-10-10T10:20:28.831728Z","steps":["trace[449594168] 'process raft request' (duration: 125.688713ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:28.831646Z","caller":"traceutil/trace.go:171","msg":"trace[1978213617] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"125.785954ms","start":"2026-10-10T10:20:28.705792Z","end":"2026-10-10T10:20:28.831578Z","steps":["trace[1978213617] 'process raft request' (duration: 125.619961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.456405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1933347321712281571,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-10T10:20:53.562348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.48156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:20:53.562476Z","caller":"traceutil/trace.go:171","msg":"trace[320345936] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"905.752247ms","start":"2026-10-10T10:20:52.656697Z","end":"2026-10-10T10:20:53.56245Z","steps":["trace[320345936] 'process raft request' (duration: 238.991972ms)","trace[320345936] 'compare' (duration: 665.94467ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:53.562566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:52.65668Z","time spent":"905.842279ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:20:53.578485Z","caller":"traceutil/trace.go:171","msg":"trace[1557524528] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"632.183082ms","start":"2026-10-10T10:20:52.946285Z","end":"2026-10-10T10:20:53.578468Z","steps":["trace[1557524528] 'process raft request' (duration: 632.05022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.57858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:52.946256Z","time spent":"632.278694ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:20:53.578688Z","caller":"traceutil/trace.go:171","msg":"trace[894826269] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"622.906824ms","start":"2026-10-10T10:20:52.955759Z","end":"2026-10-10T10:20:53.578666Z","steps":["trace[894826269] 'read index received' (duration: 622.515377ms)","trace[894826269] 'applied index is now lower than readState.Index' (duration: 373.216µs)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:53.578918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.353605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-10-10T10:20:53.578976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.735908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-10T10:20:53.579026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.267739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-10T10:20:53.579046Z","caller":"traceutil/trace.go:171","msg":"trace[351058351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1866; }","duration":"599.8284ms","start":"2026-10-10T10:20:52.979198Z","end":"2026-10-10T10:20:53.579026Z","steps":["trace[351058351] 'agreement among raft nodes before linearized reading' (duration: 599.679717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.579035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.655908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"warn","ts":"2026-10-10T10:20:53.57907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.102797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-10T10:20:53.579092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.485131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-10T10:20:53.579099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:52.979183Z","time spent":"599.903062ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-10T10:20:53.579123Z","caller":"traceutil/trace.go:171","msg":"trace[128632592] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1866; }","duration":"526.180729ms","start":"2026-10-10T10:20:53.052926Z","end":"2026-10-10T10:20:53.579106Z","steps":["trace[128632592] 'agreement among raft nodes before linearized reading' (duration: 526.100848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.579181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:53.052913Z","time spent":"526.25148ms","remote":"127.0.0.1:52444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-10T10:20:53.579176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.911954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-baaf6845-7e20-4ebd-aa38-94ce052742d6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-10T10:20:53.579229Z","caller":"traceutil/trace.go:171","msg":"trace[567969002] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-baaf6845-7e20-4ebd-aa38-94ce052742d6; range_end:; response_count:1; response_revision:1866; }","duration":"105.983315ms","start":"2026-10-10T10:20:53.473233Z","end":"2026-10-10T10:20:53.579216Z","steps":["trace[567969002] 'agreement among raft nodes before linearized reading' (duration: 105.857533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.579234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.096243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-10T10:20:53.579284Z","caller":"traceutil/trace.go:171","msg":"trace[410267349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1866; }","duration":"618.168314ms","start":"2026-10-10T10:20:52.961104Z","end":"2026-10-10T10:20:53.579273Z","steps":["trace[410267349] 'agreement among raft nodes before linearized reading' (duration: 618.053162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.579324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.749792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-baaf6845-7e20-4ebd-aa38-94ce052742d6\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-10T10:20:53.579333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:52.961094Z","time spent":"618.227214ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-10T10:20:53.579069Z","caller":"traceutil/trace.go:171","msg":"trace[100760263] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1866; }","duration":"224.342631ms","start":"2026-10-10T10:20:53.354713Z","end":"2026-10-10T10:20:53.579056Z","steps":["trace[100760263] 'agreement among raft nodes before linearized reading' (duration: 224.26274ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:53.579359Z","caller":"traceutil/trace.go:171","msg":"trace[167623605] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-baaf6845-7e20-4ebd-aa38-94ce052742d6; range_end:; response_count:1; response_revision:1866; }","duration":"365.817313ms","start":"2026-10-10T10:20:53.213532Z","end":"2026-10-10T10:20:53.57935Z","steps":["trace[167623605] 'agreement among raft nodes before linearized reading' (duration: 365.723621ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:53.578982Z","caller":"traceutil/trace.go:171","msg":"trace[1680506898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1866; }","duration":"622.438847ms","start":"2026-10-10T10:20:52.956528Z","end":"2026-10-10T10:20:53.578966Z","steps":["trace[1680506898] 'agreement among raft nodes before linearized reading' (duration: 622.269354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.579238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.098712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-10T10:20:53.579514Z","caller":"traceutil/trace.go:171","msg":"trace[726077547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1866; }","duration":"344.389426ms","start":"2026-10-10T10:20:53.235104Z","end":"2026-10-10T10:20:53.579493Z","steps":["trace[726077547] 'agreement among raft nodes before linearized reading' (duration: 344.048241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.579578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:53.23509Z","time spent":"344.467597ms","remote":"127.0.0.1:44090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-10T10:20:53.579125Z","caller":"traceutil/trace.go:171","msg":"trace[295314342] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1866; }","duration":"376.75731ms","start":"2026-10-10T10:20:53.202342Z","end":"2026-10-10T10:20:53.579099Z","steps":["trace[295314342] 'agreement among raft nodes before linearized reading' (duration: 376.506786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.579714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:53.202301Z","time spent":"377.402601ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-10T10:20:53.579134Z","caller":"traceutil/trace.go:171","msg":"trace[1356164614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1866; }","duration":"610.543673ms","start":"2026-10-10T10:20:52.968578Z","end":"2026-10-10T10:20:53.579122Z","steps":["trace[1356164614] 'agreement among raft nodes before linearized reading' (duration: 610.430991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.579897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:52.968568Z","time spent":"611.319996ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-10T10:20:53.578931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.161409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-10T10:20:53.580054Z","caller":"traceutil/trace.go:171","msg":"trace[848326011] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1866; }","duration":"624.319578ms","start":"2026-10-10T10:20:52.955722Z","end":"2026-10-10T10:20:53.580042Z","steps":["trace[848326011] 'agreement among raft nodes before linearized reading' (duration: 623.053677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:20:53.580145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:52.955706Z","time spent":"624.428371ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-10T10:20:53.579395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:53.213521Z","time spent":"365.865363ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-baaf6845-7e20-4ebd-aa38-94ce052742d6\" "} {"level":"warn","ts":"2026-10-10T10:20:53.579451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:52.956517Z","time spent":"622.917014ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-10T10:20:53.833466Z","caller":"traceutil/trace.go:171","msg":"trace[1429402151] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"247.70239ms","start":"2026-10-10T10:20:53.585728Z","end":"2026-10-10T10:20:53.83343Z","steps":["trace[1429402151] 'process raft request' (duration: 247.639999ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:53.83351Z","caller":"traceutil/trace.go:171","msg":"trace[118675465] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"247.943655ms","start":"2026-10-10T10:20:53.585529Z","end":"2026-10-10T10:20:53.833473Z","steps":["trace[118675465] 'process raft request' (duration: 247.67109ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:53.833698Z","caller":"traceutil/trace.go:171","msg":"trace[1566129642] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"247.959505ms","start":"2026-10-10T10:20:53.585713Z","end":"2026-10-10T10:20:53.833673Z","steps":["trace[1566129642] 'process raft request' (duration: 247.616829ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:53.83395Z","caller":"traceutil/trace.go:171","msg":"trace[2078229188] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"248.131708ms","start":"2026-10-10T10:20:53.585806Z","end":"2026-10-10T10:20:53.833938Z","steps":["trace[2078229188] 'process raft request' (duration: 247.593398ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:53.834088Z","caller":"traceutil/trace.go:171","msg":"trace[438673834] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"247.889524ms","start":"2026-10-10T10:20:53.586185Z","end":"2026-10-10T10:20:53.834074Z","steps":["trace[438673834] 'process raft request' (duration: 247.427196ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:53.834137Z","caller":"traceutil/trace.go:171","msg":"trace[1412450812] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1937; }","duration":"161.560236ms","start":"2026-10-10T10:20:53.672567Z","end":"2026-10-10T10:20:53.834127Z","steps":["trace[1412450812] 'read index received' (duration: 160.582799ms)","trace[1412450812] 'applied index is now lower than readState.Index' (duration: 976.697µs)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:53.834217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.635087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:20:53.834252Z","caller":"traceutil/trace.go:171","msg":"trace[1754118723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"161.711519ms","start":"2026-10-10T10:20:53.67253Z","end":"2026-10-10T10:20:53.834242Z","steps":["trace[1754118723] 'agreement among raft nodes before linearized reading' (duration: 161.636957ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:53.834386Z","caller":"traceutil/trace.go:171","msg":"trace[915469672] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"246.613482ms","start":"2026-10-10T10:20:53.587763Z","end":"2026-10-10T10:20:53.834377Z","steps":["trace[915469672] 'process raft request' (duration: 246.277646ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:20:54.048387Z","caller":"traceutil/trace.go:171","msg":"trace[385990265] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"176.674724ms","start":"2026-10-10T10:20:53.871686Z","end":"2026-10-10T10:20:54.04836Z","steps":["trace[385990265] 'process raft request' (duration: 130.89219ms)","trace[385990265] 'compare' (duration: 45.230285ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:54.654956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.810104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:20:54.65515Z","caller":"traceutil/trace.go:171","msg":"trace[608752922] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"317.430454ms","start":"2026-10-10T10:20:54.337699Z","end":"2026-10-10T10:20:54.655129Z","steps":["trace[608752922] 'process raft request' (duration: 104.370996ms)","trace[608752922] 'compare' (duration: 212.698092ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:20:54.655239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:20:54.337634Z","time spent":"317.571006ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:21:06.491021Z","caller":"traceutil/trace.go:171","msg":"trace[1295946101] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"123.361182ms","start":"2026-10-10T10:21:06.367619Z","end":"2026-10-10T10:21:06.49098Z","steps":["trace[1295946101] 'read index received' (duration: 123.086226ms)","trace[1295946101] 'applied index is now lower than readState.Index' (duration: 273.446µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:21:06.491083Z","caller":"traceutil/trace.go:171","msg":"trace[985580794] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"407.841453ms","start":"2026-10-10T10:21:06.083199Z","end":"2026-10-10T10:21:06.49104Z","steps":["trace[985580794] 'process raft request' (duration: 407.579459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:06.491238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:06.083173Z","time spent":"407.987175ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:21:06.491347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.698917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11979"} {"level":"info","ts":"2026-10-10T10:21:06.491398Z","caller":"traceutil/trace.go:171","msg":"trace[2096584676] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1976; }","duration":"123.828469ms","start":"2026-10-10T10:21:06.367553Z","end":"2026-10-10T10:21:06.491381Z","steps":["trace[2096584676] 'agreement among raft nodes before linearized reading' (duration: 123.572545ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:21:06.872371Z","caller":"traceutil/trace.go:171","msg":"trace[1834164357] linearizableReadLoop","detail":"{readStateIndex:2052; appliedIndex:2050; }","duration":"132.02996ms","start":"2026-10-10T10:21:06.740305Z","end":"2026-10-10T10:21:06.872335Z","steps":["trace[1834164357] 'read index received' (duration: 80.672811ms)","trace[1834164357] 'applied index is now lower than readState.Index' (duration: 51.355719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:21:06.872543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.218453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-10T10:21:06.872609Z","caller":"traceutil/trace.go:171","msg":"trace[1062225857] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1978; }","duration":"132.325054ms","start":"2026-10-10T10:21:06.740266Z","end":"2026-10-10T10:21:06.872592Z","steps":["trace[1062225857] 'agreement among raft nodes before linearized reading' (duration: 132.172482ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:21:06.872691Z","caller":"traceutil/trace.go:171","msg":"trace[1144278019] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"145.305397ms","start":"2026-10-10T10:21:06.727353Z","end":"2026-10-10T10:21:06.872658Z","steps":["trace[1144278019] 'process raft request' (duration: 144.862349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:38.090751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.012256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:21:38.090902Z","caller":"traceutil/trace.go:171","msg":"trace[1019567065] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"211.934976ms","start":"2026-10-10T10:21:37.878945Z","end":"2026-10-10T10:21:38.09088Z","steps":["trace[1019567065] 'read index received' (duration: 21.639945ms)","trace[1019567065] 'applied index is now lower than readState.Index' (duration: 190.293711ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:21:38.090934Z","caller":"traceutil/trace.go:171","msg":"trace[1978917348] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"257.303101ms","start":"2026-10-10T10:21:37.833606Z","end":"2026-10-10T10:21:38.090909Z","steps":["trace[1978917348] 'process raft request' (duration: 67.017651ms)","trace[1978917348] 'compare' (duration: 189.776243ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:21:38.09111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.16886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-10T10:21:38.09114Z","caller":"traceutil/trace.go:171","msg":"trace[268365227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2156; }","duration":"212.235451ms","start":"2026-10-10T10:21:37.878895Z","end":"2026-10-10T10:21:38.09113Z","steps":["trace[268365227] 'agreement among raft nodes before linearized reading' (duration: 212.071558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:39.172332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.79578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-10T10:21:39.172407Z","caller":"traceutil/trace.go:171","msg":"trace[1707494084] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2161; }","duration":"972.920722ms","start":"2026-10-10T10:21:38.199473Z","end":"2026-10-10T10:21:39.172393Z","steps":["trace[1707494084] 'count revisions from in-memory index tree' (duration: 972.684039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:39.172433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:38.199458Z","time spent":"972.967994ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-10-10T10:21:39.175079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1933347321712282281,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-10T10:21:39.31688Z","caller":"traceutil/trace.go:171","msg":"trace[328745460] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2242; }","duration":"642.165427ms","start":"2026-10-10T10:21:38.67468Z","end":"2026-10-10T10:21:39.316846Z","steps":["trace[328745460] 'read index received' (duration: 641.872442ms)","trace[328745460] 'applied index is now lower than readState.Index' (duration: 292.075µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:21:39.317278Z","caller":"traceutil/trace.go:171","msg":"trace[1886174398] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"650.529182ms","start":"2026-10-10T10:21:38.666731Z","end":"2026-10-10T10:21:39.31726Z","steps":["trace[1886174398] 'process raft request' (duration: 649.915691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:39.317442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:38.666697Z","time spent":"650.623662ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:21:39.317657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.083298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:21:39.317717Z","caller":"traceutil/trace.go:171","msg":"trace[4100275] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2162; }","duration":"547.174469ms","start":"2026-10-10T10:21:38.770525Z","end":"2026-10-10T10:21:39.3177Z","steps":["trace[4100275] 'agreement among raft nodes before linearized reading' (duration: 547.074028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:39.317752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:38.7705Z","time spent":"547.24394ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-10-10T10:21:39.317892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.367595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-10T10:21:39.317936Z","caller":"traceutil/trace.go:171","msg":"trace[1601658697] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2162; }","duration":"190.480097ms","start":"2026-10-10T10:21:39.127443Z","end":"2026-10-10T10:21:39.317923Z","steps":["trace[1601658697] 'agreement among raft nodes before linearized reading' (duration: 190.339485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:39.317984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.053356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-10T10:21:39.318018Z","caller":"traceutil/trace.go:171","msg":"trace[959232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2162; }","duration":"258.129658ms","start":"2026-10-10T10:21:39.05988Z","end":"2026-10-10T10:21:39.318009Z","steps":["trace[959232] 'agreement among raft nodes before linearized reading' (duration: 258.031616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:39.318104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.439787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:21:39.318138Z","caller":"traceutil/trace.go:171","msg":"trace[1838356695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2162; }","duration":"643.510638ms","start":"2026-10-10T10:21:38.674619Z","end":"2026-10-10T10:21:39.318129Z","steps":["trace[1838356695] 'agreement among raft nodes before linearized reading' (duration: 643.456847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:39.318309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:38.674597Z","time spent":"643.700582ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-10T10:21:39.318189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.680738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-10T10:21:39.318489Z","caller":"traceutil/trace.go:171","msg":"trace[392281898] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2162; }","duration":"461.003332ms","start":"2026-10-10T10:21:38.857474Z","end":"2026-10-10T10:21:39.318477Z","steps":["trace[392281898] 'agreement among raft nodes before linearized reading' (duration: 460.656336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:39.318524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:38.857462Z","time spent":"461.051613ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-10T10:21:40.111672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.02199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:21:40.111757Z","caller":"traceutil/trace.go:171","msg":"trace[1044523997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2164; }","duration":"437.171142ms","start":"2026-10-10T10:21:39.674564Z","end":"2026-10-10T10:21:40.111735Z","steps":["trace[1044523997] 'range keys from in-memory index tree' (duration: 436.904328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:21:40.111798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:39.674533Z","time spent":"437.255473ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-10T10:21:40.112322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.689788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:21:40.112479Z","caller":"traceutil/trace.go:171","msg":"trace[247313241] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"368.569845ms","start":"2026-10-10T10:21:39.743874Z","end":"2026-10-10T10:21:40.112444Z","steps":["trace[247313241] 'process raft request' (duration: 134.654433ms)","trace[247313241] 'compare' (duration: 232.829864ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:21:40.112548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:21:39.743855Z","time spent":"368.662006ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:21:40.140758Z","caller":"traceutil/trace.go:171","msg":"trace[2010889539] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"295.09951ms","start":"2026-10-10T10:21:39.845629Z","end":"2026-10-10T10:21:40.140728Z","steps":["trace[2010889539] 'process raft request' (duration: 294.958118ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:23.900533Z","caller":"traceutil/trace.go:171","msg":"trace[82237768] linearizableReadLoop","detail":"{readStateIndex:2600; appliedIndex:2599; }","duration":"321.015617ms","start":"2026-10-10T10:22:23.579489Z","end":"2026-10-10T10:22:23.900505Z","steps":["trace[82237768] 'read index received' (duration: 320.798013ms)","trace[82237768] 'applied index is now lower than readState.Index' (duration: 215.684µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:23.900611Z","caller":"traceutil/trace.go:171","msg":"trace[180439709] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"427.339217ms","start":"2026-10-10T10:22:23.473222Z","end":"2026-10-10T10:22:23.900561Z","steps":["trace[180439709] 'process raft request' (duration: 427.098674ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:23.900765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.080727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-10T10:22:23.900845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.337661ms","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-10-10T10:22:23.900849Z","caller":"traceutil/trace.go:171","msg":"trace[938265782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2509; }","duration":"227.215299ms","start":"2026-10-10T10:22:23.673613Z","end":"2026-10-10T10:22:23.900828Z","steps":["trace[938265782] 'agreement among raft nodes before linearized reading' (duration: 227.092577ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:23.90088Z","caller":"traceutil/trace.go:171","msg":"trace[1035180483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2509; }","duration":"321.432862ms","start":"2026-10-10T10:22:23.579436Z","end":"2026-10-10T10:22:23.900869Z","steps":["trace[1035180483] 'agreement among raft nodes before linearized reading' (duration: 321.248169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:23.900881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:23.473192Z","time spent":"427.54559ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:22:23.900916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:23.579419Z","time spent":"321.488114ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-10T10:22:50.654566Z","caller":"traceutil/trace.go:171","msg":"trace[1358684099] linearizableReadLoop","detail":"{readStateIndex:2756; appliedIndex:2755; }","duration":"177.431892ms","start":"2026-10-10T10:22:50.477109Z","end":"2026-10-10T10:22:50.654541Z","steps":["trace[1358684099] 'read index received' (duration: 153.871086ms)","trace[1358684099] 'applied index is now lower than readState.Index' (duration: 23.559706ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:50.65468Z","caller":"traceutil/trace.go:171","msg":"trace[1315837510] transaction","detail":"{read_only:false; response_revision:2659; number_of_response:1; }","duration":"210.499335ms","start":"2026-10-10T10:22:50.444165Z","end":"2026-10-10T10:22:50.654665Z","steps":["trace[1315837510] 'process raft request' (duration: 186.860738ms)","trace[1315837510] 'compare' (duration: 23.061208ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:22:50.654793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.666217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-10T10:22:50.65485Z","caller":"traceutil/trace.go:171","msg":"trace[2127699796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2659; }","duration":"177.762558ms","start":"2026-10-10T10:22:50.477072Z","end":"2026-10-10T10:22:50.654835Z","steps":["trace[2127699796] 'agreement among raft nodes before linearized reading' (duration: 177.604985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:50.730653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.517408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-10T10:22:50.730729Z","caller":"traceutil/trace.go:171","msg":"trace[1828219754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2659; }","duration":"179.64616ms","start":"2026-10-10T10:22:50.551065Z","end":"2026-10-10T10:22:50.730711Z","steps":["trace[1828219754] 'agreement among raft nodes before linearized reading' (duration: 179.471057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:50.730796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.131069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-10T10:22:50.730854Z","caller":"traceutil/trace.go:171","msg":"trace[1900282904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2659; }","duration":"185.22407ms","start":"2026-10-10T10:22:50.545616Z","end":"2026-10-10T10:22:50.73084Z","steps":["trace[1900282904] 'agreement among raft nodes before linearized reading' (duration: 184.985946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:50.731133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.350842ms","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-10-10T10:22:50.731172Z","caller":"traceutil/trace.go:171","msg":"trace[512042637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2660; }","duration":"188.431223ms","start":"2026-10-10T10:22:50.542731Z","end":"2026-10-10T10:22:50.731162Z","steps":["trace[512042637] 'agreement among raft nodes before linearized reading' (duration: 188.322551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:50.731339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.975312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-10T10:22:50.731375Z","caller":"traceutil/trace.go:171","msg":"trace[1871825382] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2660; }","duration":"189.008952ms","start":"2026-10-10T10:22:50.542354Z","end":"2026-10-10T10:22:50.731363Z","steps":["trace[1871825382] 'agreement among raft nodes before linearized reading' (duration: 188.923291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:50.888966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.069881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:22:50.889075Z","caller":"traceutil/trace.go:171","msg":"trace[115736994] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"151.914284ms","start":"2026-10-10T10:22:50.737132Z","end":"2026-10-10T10:22:50.889046Z","steps":["trace[115736994] 'process raft request' (duration: 17.66ms)","trace[115736994] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:536; } (duration: 133.679274ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:50.891267Z","caller":"traceutil/trace.go:171","msg":"trace[758754293] transaction","detail":"{read_only:false; response_revision:2663; number_of_response:1; }","duration":"152.967061ms","start":"2026-10-10T10:22:50.738282Z","end":"2026-10-10T10:22:50.891249Z","steps":["trace[758754293] 'process raft request' (duration: 152.824599ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:50.891351Z","caller":"traceutil/trace.go:171","msg":"trace[1962911404] transaction","detail":"{read_only:false; response_revision:2664; number_of_response:1; }","duration":"149.771508ms","start":"2026-10-10T10:22:50.741558Z","end":"2026-10-10T10:22:50.891329Z","steps":["trace[1962911404] 'process raft request' (duration: 149.609846ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:50.89125Z","caller":"traceutil/trace.go:171","msg":"trace[1330653634] linearizableReadLoop","detail":"{readStateIndex:2762; appliedIndex:2758; }","duration":"141.30204ms","start":"2026-10-10T10:22:50.749922Z","end":"2026-10-10T10:22:50.891224Z","steps":["trace[1330653634] 'read index received' (duration: 4.893481ms)","trace[1330653634] 'applied index is now lower than readState.Index' (duration: 136.407069ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:50.891301Z","caller":"traceutil/trace.go:171","msg":"trace[1335178814] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"153.641691ms","start":"2026-10-10T10:22:50.737637Z","end":"2026-10-10T10:22:50.891279Z","steps":["trace[1335178814] 'process raft request' (duration: 153.346637ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:50.891713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.767878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-10T10:22:50.891775Z","caller":"traceutil/trace.go:171","msg":"trace[1009442361] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2664; }","duration":"141.90466ms","start":"2026-10-10T10:22:50.749855Z","end":"2026-10-10T10:22:50.89176Z","steps":["trace[1009442361] 'agreement among raft nodes before linearized reading' (duration: 141.598115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:56.520794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.407188ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:22:56.52094Z","caller":"traceutil/trace.go:171","msg":"trace[1177378876] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2689; }","duration":"421.565561ms","start":"2026-10-10T10:22:56.099356Z","end":"2026-10-10T10:22:56.520922Z","steps":["trace[1177378876] 'range keys from in-memory index tree' (duration: 421.390228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:56.521014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.938897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-10T10:22:56.521016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.445191ms","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-10-10T10:22:56.521091Z","caller":"traceutil/trace.go:171","msg":"trace[817258371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2689; }","duration":"436.060009ms","start":"2026-10-10T10:22:56.085008Z","end":"2026-10-10T10:22:56.521068Z","steps":["trace[817258371] 'range keys from in-memory index tree' (duration: 435.782915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:56.52113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:56.08499Z","time spent":"436.13163ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-10T10:22:56.521134Z","caller":"traceutil/trace.go:171","msg":"trace[359717734] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2689; }","duration":"426.664344ms","start":"2026-10-10T10:22:56.09445Z","end":"2026-10-10T10:22:56.521114Z","steps":["trace[359717734] 'range keys from in-memory index tree' (duration: 426.38533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:56.521207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:56.094439Z","time spent":"426.756606ms","remote":"127.0.0.1:39836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-10T10:22:56.700511Z","caller":"traceutil/trace.go:171","msg":"trace[1686988657] linearizableReadLoop","detail":"{readStateIndex:2789; appliedIndex:2788; }","duration":"175.098449ms","start":"2026-10-10T10:22:56.525395Z","end":"2026-10-10T10:22:56.700494Z","steps":["trace[1686988657] 'read index received' (duration: 174.997567ms)","trace[1686988657] 'applied index is now lower than readState.Index' (duration: 99.842µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:56.700548Z","caller":"traceutil/trace.go:171","msg":"trace[513216741] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"224.230287ms","start":"2026-10-10T10:22:56.476289Z","end":"2026-10-10T10:22:56.700519Z","steps":["trace[513216741] 'process raft request' (duration: 224.080144ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:56.700678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.236892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:22:56.70072Z","caller":"traceutil/trace.go:171","msg":"trace[1664685006] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2690; }","duration":"175.358913ms","start":"2026-10-10T10:22:56.52535Z","end":"2026-10-10T10:22:56.700709Z","steps":["trace[1664685006] 'agreement among raft nodes before linearized reading' (duration: 175.232891ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:56.710327Z","caller":"traceutil/trace.go:171","msg":"trace[1474517389] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"184.481782ms","start":"2026-10-10T10:22:56.525808Z","end":"2026-10-10T10:22:56.71029Z","steps":["trace[1474517389] 'process raft request' (duration: 184.183458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:56.710411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.977902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18dd23f3bfe3f4bd\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-10-10T10:22:56.710449Z","caller":"traceutil/trace.go:171","msg":"trace[1535481467] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18dd23f3bfe3f4bd; range_end:; response_count:1; response_revision:2692; }","duration":"140.058153ms","start":"2026-10-10T10:22:56.57038Z","end":"2026-10-10T10:22:56.710438Z","steps":["trace[1535481467] 'agreement among raft nodes before linearized reading' (duration: 139.939081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:57.231483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.429136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:22:57.231579Z","caller":"traceutil/trace.go:171","msg":"trace[130531481] linearizableReadLoop","detail":"{readStateIndex:2792; appliedIndex:2791; }","duration":"505.3066ms","start":"2026-10-10T10:22:56.726255Z","end":"2026-10-10T10:22:57.231561Z","steps":["trace[130531481] 'read index received' (duration: 6.581889ms)","trace[130531481] 'applied index is now lower than readState.Index' (duration: 498.723681ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:22:57.231657Z","caller":"traceutil/trace.go:171","msg":"trace[139426753] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"517.065322ms","start":"2026-10-10T10:22:56.714581Z","end":"2026-10-10T10:22:57.231646Z","steps":["trace[139426753] 'process raft request' (duration: 18.379302ms)","trace[139426753] 'compare' (duration: 498.241283ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:22:57.232142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.872699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"warn","ts":"2026-10-10T10:22:57.232217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.875825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-10T10:22:57.232281Z","caller":"traceutil/trace.go:171","msg":"trace[120786374] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2693; }","duration":"506.042741ms","start":"2026-10-10T10:22:56.72621Z","end":"2026-10-10T10:22:57.232252Z","steps":["trace[120786374] 'agreement among raft nodes before linearized reading' (duration: 505.730616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:57.23203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.480159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-10T10:22:57.232325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:56.72619Z","time spent":"506.122883ms","remote":"127.0.0.1:44090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-10T10:22:57.232334Z","caller":"traceutil/trace.go:171","msg":"trace[1961643697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2693; }","duration":"298.832825ms","start":"2026-10-10T10:22:56.93349Z","end":"2026-10-10T10:22:57.232323Z","steps":["trace[1961643697] 'agreement among raft nodes before linearized reading' (duration: 298.413848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:57.232049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.018541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-10T10:22:57.232111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.363147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-10T10:22:57.232577Z","caller":"traceutil/trace.go:171","msg":"trace[57433696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2693; }","duration":"475.902716ms","start":"2026-10-10T10:22:56.756663Z","end":"2026-10-10T10:22:57.232566Z","steps":["trace[57433696] 'agreement among raft nodes before linearized reading' (duration: 475.244065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:57.232619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:56.756646Z","time spent":"475.959417ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-10T10:22:57.232272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:56.71456Z","time spent":"517.678802ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:22:57.23212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.569259ms","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-10-10T10:22:57.23252Z","caller":"traceutil/trace.go:171","msg":"trace[1997389930] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2693; }","duration":"315.543178ms","start":"2026-10-10T10:22:56.916953Z","end":"2026-10-10T10:22:57.232496Z","steps":["trace[1997389930] 'agreement among raft nodes before linearized reading' (duration: 314.99399ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:57.232754Z","caller":"traceutil/trace.go:171","msg":"trace[2043774278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2693; }","duration":"294.29007ms","start":"2026-10-10T10:22:56.938453Z","end":"2026-10-10T10:22:57.232744Z","steps":["trace[2043774278] 'agreement among raft nodes before linearized reading' (duration: 293.491467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:57.23278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:56.916943Z","time spent":"315.819354ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-10T10:22:57.232169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.808266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-10T10:22:57.233091Z","caller":"traceutil/trace.go:171","msg":"trace[1140185736] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2693; }","duration":"318.720602ms","start":"2026-10-10T10:22:56.914354Z","end":"2026-10-10T10:22:57.233074Z","steps":["trace[1140185736] 'agreement among raft nodes before linearized reading' (duration: 317.709565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:57.233144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:56.914247Z","time spent":"318.885004ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-10T10:22:57.232279Z","caller":"traceutil/trace.go:171","msg":"trace[189759979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2693; }","duration":"298.959967ms","start":"2026-10-10T10:22:56.933299Z","end":"2026-10-10T10:22:57.232259Z","steps":["trace[189759979] 'agreement among raft nodes before linearized reading' (duration: 298.826705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:22:57.233475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:22:56.933284Z","time spent":"300.152156ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-10T10:22:57.378946Z","caller":"traceutil/trace.go:171","msg":"trace[300526262] transaction","detail":"{read_only:false; response_revision:2695; number_of_response:1; }","duration":"140.734254ms","start":"2026-10-10T10:22:57.238193Z","end":"2026-10-10T10:22:57.378927Z","steps":["trace[300526262] 'process raft request' (duration: 140.383328ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:57.379371Z","caller":"traceutil/trace.go:171","msg":"trace[681235609] transaction","detail":"{read_only:false; response_revision:2696; number_of_response:1; }","duration":"140.236306ms","start":"2026-10-10T10:22:57.239094Z","end":"2026-10-10T10:22:57.37933Z","steps":["trace[681235609] 'process raft request' (duration: 139.805219ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:57.379736Z","caller":"traceutil/trace.go:171","msg":"trace[1480562056] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2793; }","duration":"105.613157ms","start":"2026-10-10T10:22:57.274112Z","end":"2026-10-10T10:22:57.379726Z","steps":["trace[1480562056] 'read index received' (duration: 104.406577ms)","trace[1480562056] 'applied index is now lower than readState.Index' (duration: 1.20587ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:22:57.379918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.79028ms","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-10-10T10:22:57.379944Z","caller":"traceutil/trace.go:171","msg":"trace[706650450] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2698; }","duration":"105.856112ms","start":"2026-10-10T10:22:57.274082Z","end":"2026-10-10T10:22:57.379938Z","steps":["trace[706650450] 'agreement among raft nodes before linearized reading' (duration: 105.681419ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:57.380121Z","caller":"traceutil/trace.go:171","msg":"trace[1797817816] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"138.240033ms","start":"2026-10-10T10:22:57.241872Z","end":"2026-10-10T10:22:57.380112Z","steps":["trace[1797817816] 'process raft request' (duration: 137.827487ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:22:57.380119Z","caller":"traceutil/trace.go:171","msg":"trace[851811604] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"139.19363ms","start":"2026-10-10T10:22:57.240871Z","end":"2026-10-10T10:22:57.380064Z","steps":["trace[851811604] 'process raft request' (duration: 138.776693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:23:02.600742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.927886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-10T10:23:02.60086Z","caller":"traceutil/trace.go:171","msg":"trace[1061230971] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2719; }","duration":"431.111829ms","start":"2026-10-10T10:23:02.169724Z","end":"2026-10-10T10:23:02.600836Z","steps":["trace[1061230971] 'count revisions from in-memory index tree' (duration: 430.812005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:23:02.600917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:23:02.169703Z","time spent":"431.197131ms","remote":"127.0.0.1:39558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":21,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-10-10T10:23:02.600925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.983427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-10T10:23:02.600982Z","caller":"traceutil/trace.go:171","msg":"trace[895476357] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2719; }","duration":"360.095949ms","start":"2026-10-10T10:23:02.240867Z","end":"2026-10-10T10:23:02.600963Z","steps":["trace[895476357] 'range keys from in-memory index tree' (duration: 359.794765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:23:02.601027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:23:02.240848Z","time spent":"360.168441ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-10T10:27:16.819582Z","caller":"traceutil/trace.go:171","msg":"trace[1584704201] linearizableReadLoop","detail":"{readStateIndex:4410; appliedIndex:4408; }","duration":"191.127893ms","start":"2026-10-10T10:27:16.62843Z","end":"2026-10-10T10:27:16.819558Z","steps":["trace[1584704201] 'read index received' (duration: 38.294367ms)","trace[1584704201] 'applied index is now lower than readState.Index' (duration: 152.832186ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:16.819618Z","caller":"traceutil/trace.go:171","msg":"trace[1477297230] transaction","detail":"{read_only:false; response_revision:4240; number_of_response:1; }","duration":"191.617251ms","start":"2026-10-10T10:27:16.627972Z","end":"2026-10-10T10:27:16.819589Z","steps":["trace[1477297230] 'process raft request' (duration: 189.221061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:16.819935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.20223ms","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-10-10T10:27:16.820027Z","caller":"traceutil/trace.go:171","msg":"trace[408007545] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4240; }","duration":"189.346372ms","start":"2026-10-10T10:27:16.630648Z","end":"2026-10-10T10:27:16.819995Z","steps":["trace[408007545] 'agreement among raft nodes before linearized reading' (duration: 189.081568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:16.820126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.643011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-10T10:27:16.820177Z","caller":"traceutil/trace.go:171","msg":"trace[1120478719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4240; }","duration":"191.769764ms","start":"2026-10-10T10:27:16.628393Z","end":"2026-10-10T10:27:16.820163Z","steps":["trace[1120478719] 'agreement among raft nodes before linearized reading' (duration: 191.280226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:16.820269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.894875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:16.820346Z","caller":"traceutil/trace.go:171","msg":"trace[794158498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4240; }","duration":"147.031616ms","start":"2026-10-10T10:27:16.673301Z","end":"2026-10-10T10:27:16.820333Z","steps":["trace[794158498] 'agreement among raft nodes before linearized reading' (duration: 146.880684ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:25.509058Z","caller":"traceutil/trace.go:171","msg":"trace[769672094] linearizableReadLoop","detail":"{readStateIndex:4862; appliedIndex:4860; }","duration":"367.636401ms","start":"2026-10-10T10:27:25.141397Z","end":"2026-10-10T10:27:25.509033Z","steps":["trace[769672094] 'read index received' (duration: 338.262964ms)","trace[769672094] 'applied index is now lower than readState.Index' (duration: 29.372397ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:25.509409Z","caller":"traceutil/trace.go:171","msg":"trace[825949942] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"376.55305ms","start":"2026-10-10T10:27:25.132829Z","end":"2026-10-10T10:27:25.509382Z","steps":["trace[825949942] 'process raft request' (duration: 346.605644ms)","trace[825949942] 'compare' (duration: 29.204493ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:25.509465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.046167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-10T10:27:25.509506Z","caller":"traceutil/trace.go:171","msg":"trace[1853454433] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4687; }","duration":"368.121988ms","start":"2026-10-10T10:27:25.141372Z","end":"2026-10-10T10:27:25.509494Z","steps":["trace[1853454433] 'agreement among raft nodes before linearized reading' (duration: 367.965026ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:25.509491Z","caller":"traceutil/trace.go:171","msg":"trace[1359283453] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"370.269524ms","start":"2026-10-10T10:27:25.139192Z","end":"2026-10-10T10:27:25.509462Z","steps":["trace[1359283453] 'process raft request' (duration: 369.682034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:25.50954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:25.141365Z","time spent":"368.166199ms","remote":"127.0.0.1:39482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1366,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-10-10T10:27:25.509558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:25.132814Z","time spent":"376.664963ms","remote":"127.0.0.1:39836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5430,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:25.509632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:25.139184Z","time spent":"370.367065ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:25.50978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.367152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-10-10T10:27:25.509816Z","caller":"traceutil/trace.go:171","msg":"trace[1845790635] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4687; }","duration":"368.422043ms","start":"2026-10-10T10:27:25.141385Z","end":"2026-10-10T10:27:25.509807Z","steps":["trace[1845790635] 'agreement among raft nodes before linearized reading' (duration: 368.326902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:25.50984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:25.141373Z","time spent":"368.461944ms","remote":"127.0.0.1:39472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4760,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-10-10T10:27:25.509998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.38263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-10-10T10:27:25.510058Z","caller":"traceutil/trace.go:171","msg":"trace[1414311547] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4687; }","duration":"164.481582ms","start":"2026-10-10T10:27:25.345565Z","end":"2026-10-10T10:27:25.510046Z","steps":["trace[1414311547] 'agreement among raft nodes before linearized reading' (duration: 164.33061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:25.511171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.024623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954238"} {"level":"info","ts":"2026-10-10T10:27:25.511272Z","caller":"traceutil/trace.go:171","msg":"trace[267506444] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4687; }","duration":"314.167415ms","start":"2026-10-10T10:27:25.197085Z","end":"2026-10-10T10:27:25.511253Z","steps":["trace[267506444] 'agreement among raft nodes before linearized reading' (duration: 312.812862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:25.511318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:25.197064Z","time spent":"314.242577ms","remote":"127.0.0.1:39496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":46,"response size":1954261,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-10-10T10:27:30.630465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.617356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:27:30.630952Z","caller":"traceutil/trace.go:171","msg":"trace[1555428851] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4822; }","duration":"305.960048ms","start":"2026-10-10T10:27:30.32497Z","end":"2026-10-10T10:27:30.63093Z","steps":["trace[1555428851] 'process raft request' (duration: 305.813015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:30.631132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:30.324951Z","time spent":"306.074279ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":110,"response count":0,"response size":2067,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:27:30.631452Z","caller":"traceutil/trace.go:171","msg":"trace[775432531] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"308.964738ms","start":"2026-10-10T10:27:30.322134Z","end":"2026-10-10T10:27:30.631099Z","steps":["trace[775432531] 'process raft request' (duration: 192.60932ms)","trace[775432531] 'compare' (duration: 115.415482ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:30.631873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:30.322119Z","time spent":"309.441146ms","remote":"127.0.0.1:40126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2018,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:27:30.63164Z","caller":"traceutil/trace.go:171","msg":"trace[2088381299] linearizableReadLoop","detail":"{readStateIndex:5002; appliedIndex:5001; }","duration":"307.713816ms","start":"2026-10-10T10:27:30.323796Z","end":"2026-10-10T10:27:30.63151Z","steps":["trace[2088381299] 'read index received' (duration: 190.950601ms)","trace[2088381299] 'applied index is now lower than readState.Index' (duration: 116.760095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:30.632573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.666885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-10T10:27:30.632625Z","caller":"traceutil/trace.go:171","msg":"trace[727975077] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4822; }","duration":"197.762836ms","start":"2026-10-10T10:27:30.434844Z","end":"2026-10-10T10:27:30.632607Z","steps":["trace[727975077] 'agreement among raft nodes before linearized reading' (duration: 197.665014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:30.631865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.046522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-10T10:27:30.634002Z","caller":"traceutil/trace.go:171","msg":"trace[206506660] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4822; }","duration":"310.23252ms","start":"2026-10-10T10:27:30.323744Z","end":"2026-10-10T10:27:30.633976Z","steps":["trace[206506660] 'agreement among raft nodes before linearized reading' (duration: 307.962261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:30.634258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:30.323719Z","time spent":"310.518054ms","remote":"127.0.0.1:39588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1308,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-10-10T10:27:30.634755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.790294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-10-10T10:27:30.634809Z","caller":"traceutil/trace.go:171","msg":"trace[519945871] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4822; }","duration":"149.896976ms","start":"2026-10-10T10:27:30.484898Z","end":"2026-10-10T10:27:30.634795Z","steps":["trace[519945871] 'agreement among raft nodes before linearized reading' (duration: 147.613947ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:30.822546Z","caller":"traceutil/trace.go:171","msg":"trace[721029080] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"154.088007ms","start":"2026-10-10T10:27:30.668431Z","end":"2026-10-10T10:27:30.822519Z","steps":["trace[721029080] 'read index received' (duration: 153.491236ms)","trace[721029080] 'applied index is now lower than readState.Index' (duration: 595.381µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:30.822744Z","caller":"traceutil/trace.go:171","msg":"trace[2105916594] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"167.748569ms","start":"2026-10-10T10:27:30.654961Z","end":"2026-10-10T10:27:30.82271Z","steps":["trace[2105916594] 'process raft request' (duration: 166.942905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:30.822893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.426082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4849"} {"level":"info","ts":"2026-10-10T10:27:30.82296Z","caller":"traceutil/trace.go:171","msg":"trace[904948177] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4823; }","duration":"154.552165ms","start":"2026-10-10T10:27:30.668395Z","end":"2026-10-10T10:27:30.822947Z","steps":["trace[904948177] 'agreement among raft nodes before linearized reading' (duration: 154.367972ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:30.852823Z","caller":"traceutil/trace.go:171","msg":"trace[155455803] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"109.865418ms","start":"2026-10-10T10:27:30.742929Z","end":"2026-10-10T10:27:30.852795Z","steps":["trace[155455803] 'process raft request' (duration: 109.720426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:30.853092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.080974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-gmnn9\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-10-10T10:27:30.853191Z","caller":"traceutil/trace.go:171","msg":"trace[915972131] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-gmnn9; range_end:; response_count:1; response_revision:4825; }","duration":"139.216456ms","start":"2026-10-10T10:27:30.713964Z","end":"2026-10-10T10:27:30.85318Z","steps":["trace[915972131] 'agreement among raft nodes before linearized reading' (duration: 139.084384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:30.852875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.442512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-10T10:27:30.853308Z","caller":"traceutil/trace.go:171","msg":"trace[1197312008] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4825; }","duration":"180.910441ms","start":"2026-10-10T10:27:30.67238Z","end":"2026-10-10T10:27:30.85329Z","steps":["trace[1197312008] 'agreement among raft nodes before linearized reading' (duration: 180.417982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:30.853461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.148731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:30.853518Z","caller":"traceutil/trace.go:171","msg":"trace[1412323109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4825; }","duration":"179.238713ms","start":"2026-10-10T10:27:30.674262Z","end":"2026-10-10T10:27:30.853501Z","steps":["trace[1412323109] 'agreement among raft nodes before linearized reading' (duration: 179.10407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:30.853564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.642257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-10T10:27:30.853593Z","caller":"traceutil/trace.go:171","msg":"trace[1203126360] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4825; }","duration":"157.693249ms","start":"2026-10-10T10:27:30.695892Z","end":"2026-10-10T10:27:30.853585Z","steps":["trace[1203126360] 'agreement among raft nodes before linearized reading' (duration: 157.617198ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:30.853477Z","caller":"traceutil/trace.go:171","msg":"trace[117941679] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"103.165595ms","start":"2026-10-10T10:27:30.750259Z","end":"2026-10-10T10:27:30.853425Z","steps":["trace[117941679] 'process raft request' (duration: 102.478833ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:31.072115Z","caller":"traceutil/trace.go:171","msg":"trace[684216565] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5006; }","duration":"207.567512ms","start":"2026-10-10T10:27:30.864479Z","end":"2026-10-10T10:27:31.072046Z","steps":["trace[684216565] 'read index received' (duration: 202.771371ms)","trace[684216565] 'applied index is now lower than readState.Index' (duration: 4.795061ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:31.072714Z","caller":"traceutil/trace.go:171","msg":"trace[1896350116] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"208.428886ms","start":"2026-10-10T10:27:30.864268Z","end":"2026-10-10T10:27:31.072697Z","steps":["trace[1896350116] 'process raft request' (duration: 203.001055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:31.072767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.262704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-10T10:27:31.073016Z","caller":"traceutil/trace.go:171","msg":"trace[276687312] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4826; }","duration":"208.520399ms","start":"2026-10-10T10:27:30.864452Z","end":"2026-10-10T10:27:31.072972Z","steps":["trace[276687312] 'agreement among raft nodes before linearized reading' (duration: 208.106591ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:31.076509Z","caller":"traceutil/trace.go:171","msg":"trace[763905328] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"211.881565ms","start":"2026-10-10T10:27:30.86461Z","end":"2026-10-10T10:27:31.076491Z","steps":["trace[763905328] 'process raft request' (duration: 211.682342ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:31.076647Z","caller":"traceutil/trace.go:171","msg":"trace[1728073371] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"211.864065ms","start":"2026-10-10T10:27:30.864715Z","end":"2026-10-10T10:27:31.076579Z","steps":["trace[1728073371] 'process raft request' (duration: 211.681482ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:31.076535Z","caller":"traceutil/trace.go:171","msg":"trace[30893902] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"204.308917ms","start":"2026-10-10T10:27:30.87221Z","end":"2026-10-10T10:27:31.076519Z","steps":["trace[30893902] 'process raft request' (duration: 204.242566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:31.08632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.049532ms","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-10-10T10:27:31.086386Z","caller":"traceutil/trace.go:171","msg":"trace[935073901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4829; }","duration":"162.146134ms","start":"2026-10-10T10:27:30.924227Z","end":"2026-10-10T10:27:31.086373Z","steps":["trace[935073901] 'agreement among raft nodes before linearized reading' (duration: 161.978061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:31.086638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.393318ms","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-10-10T10:27:31.086671Z","caller":"traceutil/trace.go:171","msg":"trace[1143453339] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4829; }","duration":"162.451959ms","start":"2026-10-10T10:27:30.92421Z","end":"2026-10-10T10:27:31.086662Z","steps":["trace[1143453339] 'agreement among raft nodes before linearized reading' (duration: 162.360307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:31.086801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.20734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:31.086832Z","caller":"traceutil/trace.go:171","msg":"trace[160023338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4829; }","duration":"222.28239ms","start":"2026-10-10T10:27:30.864541Z","end":"2026-10-10T10:27:31.086824Z","steps":["trace[160023338] 'agreement among raft nodes before linearized reading' (duration: 222.23695ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:31.087011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.235494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-10-10T10:27:31.08714Z","caller":"traceutil/trace.go:171","msg":"trace[2014150309] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4829; }","duration":"192.408776ms","start":"2026-10-10T10:27:30.894715Z","end":"2026-10-10T10:27:31.087124Z","steps":["trace[2014150309] 'agreement among raft nodes before linearized reading' (duration: 192.047699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:31.087497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.193813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-10T10:27:31.087851Z","caller":"traceutil/trace.go:171","msg":"trace[8023700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4829; }","duration":"169.567159ms","start":"2026-10-10T10:27:30.918271Z","end":"2026-10-10T10:27:31.087838Z","steps":["trace[8023700] 'agreement among raft nodes before linearized reading' (duration: 169.147772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:31.087666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.807718ms","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-10-10T10:27:31.088256Z","caller":"traceutil/trace.go:171","msg":"trace[1977314501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4829; }","duration":"185.419258ms","start":"2026-10-10T10:27:30.902815Z","end":"2026-10-10T10:27:31.088234Z","steps":["trace[1977314501] 'agreement among raft nodes before linearized reading' (duration: 184.701526ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:31.087719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.001707ms","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-10-10T10:27:31.088465Z","caller":"traceutil/trace.go:171","msg":"trace[1353291907] 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:4829; }","duration":"173.784071ms","start":"2026-10-10T10:27:30.914662Z","end":"2026-10-10T10:27:31.088446Z","steps":["trace[1353291907] 'agreement among raft nodes before linearized reading' (duration: 172.978217ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:31.829478Z","caller":"traceutil/trace.go:171","msg":"trace[1796871189] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"195.199254ms","start":"2026-10-10T10:27:31.634245Z","end":"2026-10-10T10:27:31.829444Z","steps":["trace[1796871189] 'process raft request' (duration: 195.013171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.027222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.934785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:27:38.027909Z","caller":"traceutil/trace.go:171","msg":"trace[891001845] linearizableReadLoop","detail":"{readStateIndex:5129; appliedIndex:5128; }","duration":"740.785277ms","start":"2026-10-10T10:27:37.287098Z","end":"2026-10-10T10:27:38.027883Z","steps":["trace[891001845] 'read index received' (duration: 430.088548ms)","trace[891001845] 'applied index is now lower than readState.Index' (duration: 310.694959ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:38.028066Z","caller":"traceutil/trace.go:171","msg":"trace[1110793335] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"761.313305ms","start":"2026-10-10T10:27:37.266731Z","end":"2026-10-10T10:27:38.028044Z","steps":["trace[1110793335] 'process raft request' (duration: 450.410124ms)","trace[1110793335] 'compare' (duration: 309.721561ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:38.028255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.266711Z","time spent":"761.483758ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:38.028308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.193264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:38.028368Z","caller":"traceutil/trace.go:171","msg":"trace[1608308926] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:4945; }","duration":"741.301875ms","start":"2026-10-10T10:27:37.287049Z","end":"2026-10-10T10:27:38.028351Z","steps":["trace[1608308926] 'agreement among raft nodes before linearized reading' (duration: 741.175983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.028409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.287008Z","time spent":"741.391697ms","remote":"127.0.0.1:52518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-10-10T10:27:38.034846Z","caller":"traceutil/trace.go:171","msg":"trace[883966073] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"468.607401ms","start":"2026-10-10T10:27:37.566209Z","end":"2026-10-10T10:27:38.034816Z","steps":["trace[883966073] 'process raft request' (duration: 468.475169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.035428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.566184Z","time spent":"468.742113ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:27:38.300818Z","caller":"traceutil/trace.go:171","msg":"trace[891744931] linearizableReadLoop","detail":"{readStateIndex:5133; appliedIndex:5130; }","duration":"272.802517ms","start":"2026-10-10T10:27:38.027991Z","end":"2026-10-10T10:27:38.300794Z","steps":["trace[891744931] 'read index received' (duration: 7.324153ms)","trace[891744931] 'applied index is now lower than readState.Index' (duration: 265.477254ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:38.300937Z","caller":"traceutil/trace.go:171","msg":"trace[928606333] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"445.275967ms","start":"2026-10-10T10:27:37.855637Z","end":"2026-10-10T10:27:38.300913Z","steps":["trace[928606333] 'process raft request' (duration: 445.108604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.300996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.149823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:38.301032Z","caller":"traceutil/trace.go:171","msg":"trace[1264140520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4949; }","duration":"618.251435ms","start":"2026-10-10T10:27:37.68277Z","end":"2026-10-10T10:27:38.301021Z","steps":["trace[1264140520] 'agreement among raft nodes before linearized reading' (duration: 618.148203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.301066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.682747Z","time spent":"618.311185ms","remote":"127.0.0.1:39398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-10T10:27:38.301323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.279234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:38.301359Z","caller":"traceutil/trace.go:171","msg":"trace[1689587098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4949; }","duration":"995.344226ms","start":"2026-10-10T10:27:37.306006Z","end":"2026-10-10T10:27:38.30135Z","steps":["trace[1689587098] 'agreement among raft nodes before linearized reading' (duration: 995.293285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.301322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.898279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-10T10:27:38.301382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.305993Z","time spent":"995.383436ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-10T10:27:38.3014Z","caller":"traceutil/trace.go:171","msg":"trace[1705972245] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4949; }","duration":"816.049321ms","start":"2026-10-10T10:27:37.485334Z","end":"2026-10-10T10:27:38.301383Z","steps":["trace[1705972245] 'agreement among raft nodes before linearized reading' (duration: 815.890359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.301477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.4853Z","time spent":"816.163124ms","remote":"127.0.0.1:44960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-10-10T10:27:38.301677Z","caller":"traceutil/trace.go:171","msg":"trace[494332050] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"475.474187ms","start":"2026-10-10T10:27:37.826166Z","end":"2026-10-10T10:27:38.30164Z","steps":["trace[494332050] 'process raft request' (duration: 455.666421ms)","trace[494332050] 'compare' (duration: 18.708168ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:38.301843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.826121Z","time spent":"475.63074ms","remote":"127.0.0.1:39570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:27:38.302167Z","caller":"traceutil/trace.go:171","msg":"trace[828938751] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"448.618144ms","start":"2026-10-10T10:27:37.853523Z","end":"2026-10-10T10:27:38.302142Z","steps":["trace[828938751] 'process raft request' (duration: 447.162529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.302242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.853494Z","time spent":"448.707235ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:38.303941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.855626Z","time spent":"445.371618ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:38.304333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.676196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ffe6750-e716-4b85-ac15-142ed670dbc9\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-10T10:27:38.304403Z","caller":"traceutil/trace.go:171","msg":"trace[1712565894] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ffe6750-e716-4b85-ac15-142ed670dbc9; range_end:; response_count:1; response_revision:4949; }","duration":"808.806488ms","start":"2026-10-10T10:27:37.495582Z","end":"2026-10-10T10:27:38.304389Z","steps":["trace[1712565894] 'agreement among raft nodes before linearized reading' (duration: 805.732536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.304434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:37.49555Z","time spent":"808.875079ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ffe6750-e716-4b85-ac15-142ed670dbc9\" "} {"level":"info","ts":"2026-10-10T10:27:38.346625Z","caller":"traceutil/trace.go:171","msg":"trace[927480176] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"311.360941ms","start":"2026-10-10T10:27:38.035245Z","end":"2026-10-10T10:27:38.346606Z","steps":["trace[927480176] 'process raft request' (duration: 311.180587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.346731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:38.035222Z","time spent":"311.458132ms","remote":"127.0.0.1:39648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1407,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:38.346894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.618027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5fb4bc5665-t7qjs\" ","response":"range_response_count:1 size:11845"} {"level":"info","ts":"2026-10-10T10:27:38.346967Z","caller":"traceutil/trace.go:171","msg":"trace[604610324] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5fb4bc5665-t7qjs; range_end:; response_count:1; response_revision:4951; }","duration":"310.724859ms","start":"2026-10-10T10:27:38.036226Z","end":"2026-10-10T10:27:38.346951Z","steps":["trace[604610324] 'agreement among raft nodes before linearized reading' (duration: 310.531316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.346998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:38.036214Z","time spent":"310.77647ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":11868,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5fb4bc5665-t7qjs\" "} {"level":"info","ts":"2026-10-10T10:27:38.347003Z","caller":"traceutil/trace.go:171","msg":"trace[1615062734] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"311.727917ms","start":"2026-10-10T10:27:38.035261Z","end":"2026-10-10T10:27:38.346989Z","steps":["trace[1615062734] 'process raft request' (duration: 311.2847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.347069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:38.035241Z","time spent":"311.794558ms","remote":"127.0.0.1:39558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":991,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:38.347386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.522617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5fb4bc5665-t7qjs.18dd2439c01fde90\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-10-10T10:27:38.347466Z","caller":"traceutil/trace.go:171","msg":"trace[1389334190] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5fb4bc5665-t7qjs.18dd2439c01fde90; range_end:; response_count:1; response_revision:4951; }","duration":"118.645498ms","start":"2026-10-10T10:27:38.228799Z","end":"2026-10-10T10:27:38.347445Z","steps":["trace[1389334190] 'agreement among raft nodes before linearized reading' (duration: 118.399324ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:38.456561Z","caller":"traceutil/trace.go:171","msg":"trace[210251637] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"104.05193ms","start":"2026-10-10T10:27:38.352481Z","end":"2026-10-10T10:27:38.456533Z","steps":["trace[210251637] 'process raft request' (duration: 95.477895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.876073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.502951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:27:38.876232Z","caller":"traceutil/trace.go:171","msg":"trace[2118968218] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5138; }","duration":"326.618468ms","start":"2026-10-10T10:27:38.549593Z","end":"2026-10-10T10:27:38.876211Z","steps":["trace[2118968218] 'read index received' (duration: 10.764601ms)","trace[2118968218] 'applied index is now lower than readState.Index' (duration: 315.852067ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:38.876483Z","caller":"traceutil/trace.go:171","msg":"trace[1708947829] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"332.479777ms","start":"2026-10-10T10:27:38.543989Z","end":"2026-10-10T10:27:38.876469Z","steps":["trace[1708947829] 'process raft request' (duration: 16.468028ms)","trace[1708947829] 'compare' (duration: 315.376819ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:38.876556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:38.543969Z","time spent":"332.552388ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:38.877223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.933888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-10T10:27:38.877251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.635035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"warn","ts":"2026-10-10T10:27:38.877389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.456527ms","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-10-10T10:27:38.877317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.235823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-10T10:27:38.877528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.306758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:38.877561Z","caller":"traceutil/trace.go:171","msg":"trace[717793336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4955; }","duration":"204.358269ms","start":"2026-10-10T10:27:38.673195Z","end":"2026-10-10T10:27:38.877553Z","steps":["trace[717793336] 'agreement among raft nodes before linearized reading' (duration: 204.033784ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:38.87757Z","caller":"traceutil/trace.go:171","msg":"trace[2039775102] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4955; }","duration":"150.516518ms","start":"2026-10-10T10:27:38.727038Z","end":"2026-10-10T10:27:38.877554Z","steps":["trace[2039775102] 'agreement among raft nodes before linearized reading' (duration: 150.216384ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:38.877373Z","caller":"traceutil/trace.go:171","msg":"trace[2120155863] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4955; }","duration":"280.155311ms","start":"2026-10-10T10:27:38.597193Z","end":"2026-10-10T10:27:38.877349Z","steps":["trace[2120155863] 'agreement among raft nodes before linearized reading' (duration: 279.890166ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:38.877443Z","caller":"traceutil/trace.go:171","msg":"trace[806948364] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4955; }","duration":"238.547578ms","start":"2026-10-10T10:27:38.638879Z","end":"2026-10-10T10:27:38.877427Z","steps":["trace[806948364] 'agreement among raft nodes before linearized reading' (duration: 238.461137ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:38.877462Z","caller":"traceutil/trace.go:171","msg":"trace[638497506] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4955; }","duration":"327.867499ms","start":"2026-10-10T10:27:38.549539Z","end":"2026-10-10T10:27:38.877407Z","steps":["trace[638497506] 'agreement among raft nodes before linearized reading' (duration: 327.489532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:38.877999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:38.549517Z","time spent":"328.469319ms","remote":"127.0.0.1:39840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9660,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-10-10T10:27:39.039864Z","caller":"traceutil/trace.go:171","msg":"trace[816101965] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"154.431624ms","start":"2026-10-10T10:27:38.885406Z","end":"2026-10-10T10:27:39.039837Z","steps":["trace[816101965] 'process raft request' (duration: 154.21206ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:39.039841Z","caller":"traceutil/trace.go:171","msg":"trace[1083344673] linearizableReadLoop","detail":"{readStateIndex:5140; appliedIndex:5139; }","duration":"153.997336ms","start":"2026-10-10T10:27:38.885803Z","end":"2026-10-10T10:27:39.039801Z","steps":["trace[1083344673] 'read index received' (duration: 153.878254ms)","trace[1083344673] 'applied index is now lower than readState.Index' (duration: 117.742µs)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:39.040296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.467434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-10T10:27:39.040344Z","caller":"traceutil/trace.go:171","msg":"trace[35308209] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4956; }","duration":"154.563135ms","start":"2026-10-10T10:27:38.885769Z","end":"2026-10-10T10:27:39.040332Z","steps":["trace[35308209] 'agreement among raft nodes before linearized reading' (duration: 154.25183ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:39.061775Z","caller":"traceutil/trace.go:171","msg":"trace[1291776143] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"163.921424ms","start":"2026-10-10T10:27:38.897824Z","end":"2026-10-10T10:27:39.061745Z","steps":["trace[1291776143] 'process raft request' (duration: 163.68531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:39.062268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.683929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-10-10T10:27:39.062324Z","caller":"traceutil/trace.go:171","msg":"trace[1360579780] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4958; }","duration":"160.766931ms","start":"2026-10-10T10:27:38.901543Z","end":"2026-10-10T10:27:39.06231Z","steps":["trace[1360579780] 'agreement among raft nodes before linearized reading' (duration: 160.621449ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:39.461732Z","caller":"traceutil/trace.go:171","msg":"trace[1996863496] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"287.689149ms","start":"2026-10-10T10:27:39.173999Z","end":"2026-10-10T10:27:39.461689Z","steps":["trace[1996863496] 'process raft request' (duration: 276.57036ms)","trace[1996863496] 'compare' (duration: 10.689352ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:39.461883Z","caller":"traceutil/trace.go:171","msg":"trace[1064226258] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5143; }","duration":"286.0078ms","start":"2026-10-10T10:27:39.175849Z","end":"2026-10-10T10:27:39.461857Z","steps":["trace[1064226258] 'read index received' (duration: 274.75843ms)","trace[1064226258] 'applied index is now lower than readState.Index' (duration: 11.24708ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:39.462222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.247714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-10T10:27:39.46227Z","caller":"traceutil/trace.go:171","msg":"trace[698611135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4961; }","duration":"286.358576ms","start":"2026-10-10T10:27:39.175901Z","end":"2026-10-10T10:27:39.462259Z","steps":["trace[698611135] 'agreement among raft nodes before linearized reading' (duration: 286.198313ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:39.462368Z","caller":"traceutil/trace.go:171","msg":"trace[2115338081] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"288.334109ms","start":"2026-10-10T10:27:39.174014Z","end":"2026-10-10T10:27:39.462348Z","steps":["trace[2115338081] 'process raft request' (duration: 287.624008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:39.462548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.211531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-10T10:27:39.462598Z","caller":"traceutil/trace.go:171","msg":"trace[1604227479] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4961; }","duration":"248.281733ms","start":"2026-10-10T10:27:39.214305Z","end":"2026-10-10T10:27:39.462587Z","steps":["trace[1604227479] 'agreement among raft nodes before linearized reading' (duration: 248.173331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:39.462762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.320975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-10T10:27:39.462879Z","caller":"traceutil/trace.go:171","msg":"trace[871109381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4961; }","duration":"287.055358ms","start":"2026-10-10T10:27:39.175798Z","end":"2026-10-10T10:27:39.462853Z","steps":["trace[871109381] 'agreement among raft nodes before linearized reading' (duration: 286.219494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:39.463323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.875187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ffe6750-e716-4b85-ac15-142ed670dbc9\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-10-10T10:27:39.463393Z","caller":"traceutil/trace.go:171","msg":"trace[191467916] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ffe6750-e716-4b85-ac15-142ed670dbc9; range_end:; response_count:1; response_revision:4961; }","duration":"152.994109ms","start":"2026-10-10T10:27:39.310381Z","end":"2026-10-10T10:27:39.463375Z","steps":["trace[191467916] 'agreement among raft nodes before linearized reading' (duration: 152.047364ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:41.580315Z","caller":"traceutil/trace.go:171","msg":"trace[282995916] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5178; }","duration":"114.351706ms","start":"2026-10-10T10:27:41.465937Z","end":"2026-10-10T10:27:41.580289Z","steps":["trace[282995916] 'read index received' (duration: 114.010811ms)","trace[282995916] 'applied index is now lower than readState.Index' (duration: 339.675µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:41.580379Z","caller":"traceutil/trace.go:171","msg":"trace[1442378542] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"299.723953ms","start":"2026-10-10T10:27:41.280626Z","end":"2026-10-10T10:27:41.58035Z","steps":["trace[1442378542] 'process raft request' (duration: 299.417888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:41.580582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.627201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-10T10:27:41.580655Z","caller":"traceutil/trace.go:171","msg":"trace[688552883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4994; }","duration":"114.715322ms","start":"2026-10-10T10:27:41.46592Z","end":"2026-10-10T10:27:41.580635Z","steps":["trace[688552883] 'agreement among raft nodes before linearized reading' (duration: 114.463968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:41.580788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.355246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-10T10:27:41.580862Z","caller":"traceutil/trace.go:171","msg":"trace[1472041720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4994; }","duration":"104.502669ms","start":"2026-10-10T10:27:41.476341Z","end":"2026-10-10T10:27:41.580843Z","steps":["trace[1472041720] 'agreement among raft nodes before linearized reading' (duration: 104.309065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:41.581098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.541763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ffe6750-e716-4b85-ac15-142ed670dbc9\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-10-10T10:27:41.581171Z","caller":"traceutil/trace.go:171","msg":"trace[1726410381] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ffe6750-e716-4b85-ac15-142ed670dbc9; range_end:; response_count:1; response_revision:4994; }","duration":"106.632504ms","start":"2026-10-10T10:27:41.474526Z","end":"2026-10-10T10:27:41.581158Z","steps":["trace[1726410381] 'agreement among raft nodes before linearized reading' (duration: 106.480222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:41.581595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.743749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-10T10:27:41.581655Z","caller":"traceutil/trace.go:171","msg":"trace[684242275] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4994; }","duration":"102.8339ms","start":"2026-10-10T10:27:41.478805Z","end":"2026-10-10T10:27:41.581638Z","steps":["trace[684242275] 'agreement among raft nodes before linearized reading' (duration: 102.530315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:41.580912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:41.280586Z","time spent":"300.046948ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9754,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-10T10:27:41.582298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.792613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-10T10:27:41.582343Z","caller":"traceutil/trace.go:171","msg":"trace[1319372552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4994; }","duration":"114.859394ms","start":"2026-10-10T10:27:41.467471Z","end":"2026-10-10T10:27:41.582331Z","steps":["trace[1319372552] 'agreement among raft nodes before linearized reading' (duration: 113.127364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:41.583254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.874415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-10T10:27:41.583653Z","caller":"traceutil/trace.go:171","msg":"trace[2048310327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4994; }","duration":"105.349383ms","start":"2026-10-10T10:27:41.478277Z","end":"2026-10-10T10:27:41.583626Z","steps":["trace[2048310327] 'agreement among raft nodes before linearized reading' (duration: 102.330302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.144424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1933347321712289592,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-10T10:27:52.381153Z","caller":"traceutil/trace.go:171","msg":"trace[484828924] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"737.592328ms","start":"2026-10-10T10:27:51.643536Z","end":"2026-10-10T10:27:52.381128Z","steps":["trace[484828924] 'process raft request' (duration: 737.417775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.381322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:51.643515Z","time spent":"737.728621ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:27:52.422373Z","caller":"traceutil/trace.go:171","msg":"trace[28441811] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"340.22589ms","start":"2026-10-10T10:27:52.082118Z","end":"2026-10-10T10:27:52.422344Z","steps":["trace[28441811] 'process raft request' (duration: 339.999147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.422528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:52.082097Z","time spent":"340.349822ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:27:52.42277Z","caller":"traceutil/trace.go:171","msg":"trace[1960681680] linearizableReadLoop","detail":"{readStateIndex:5309; appliedIndex:5307; }","duration":"779.040719ms","start":"2026-10-10T10:27:51.643705Z","end":"2026-10-10T10:27:52.422745Z","steps":["trace[1960681680] 'read index received' (duration: 737.263692ms)","trace[1960681680] 'applied index is now lower than readState.Index' (duration: 41.775957ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:27:52.423133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.439208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:52.423188Z","caller":"traceutil/trace.go:171","msg":"trace[1096425936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5122; }","duration":"749.49405ms","start":"2026-10-10T10:27:51.67368Z","end":"2026-10-10T10:27:52.423174Z","steps":["trace[1096425936] 'agreement among raft nodes before linearized reading' (duration: 749.401058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.423217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:51.673651Z","time spent":"749.55972ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-10T10:27:52.423375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.856113ms","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-10-10T10:27:52.424355Z","caller":"traceutil/trace.go:171","msg":"trace[498273845] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5122; }","duration":"767.86032ms","start":"2026-10-10T10:27:51.656471Z","end":"2026-10-10T10:27:52.424332Z","steps":["trace[498273845] 'agreement among raft nodes before linearized reading' (duration: 766.651799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.424468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:51.656465Z","time spent":"767.985942ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-10T10:27:52.423374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.1129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:27:52.42474Z","caller":"traceutil/trace.go:171","msg":"trace[1518654787] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5122; }","duration":"399.525274ms","start":"2026-10-10T10:27:52.025188Z","end":"2026-10-10T10:27:52.424713Z","steps":["trace[1518654787] 'agreement among raft nodes before linearized reading' (duration: 398.09744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.424803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:52.025174Z","time spent":"399.607415ms","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-10-10T10:27:52.423178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.442846ms","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-10-10T10:27:52.424962Z","caller":"traceutil/trace.go:171","msg":"trace[401652964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5122; }","duration":"781.245017ms","start":"2026-10-10T10:27:51.643668Z","end":"2026-10-10T10:27:52.424913Z","steps":["trace[401652964] 'agreement among raft nodes before linearized reading' (duration: 779.241012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.425115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:51.643656Z","time spent":"781.406859ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-10T10:27:52.423514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.527757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-10T10:27:52.425395Z","caller":"traceutil/trace.go:171","msg":"trace[823788885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5122; }","duration":"775.49592ms","start":"2026-10-10T10:27:51.649885Z","end":"2026-10-10T10:27:52.425381Z","steps":["trace[823788885] 'agreement among raft nodes before linearized reading' (duration: 773.17278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.425428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:51.649874Z","time spent":"775.54336ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-10T10:27:52.423459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.476815ms","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-10-10T10:27:52.425604Z","caller":"traceutil/trace.go:171","msg":"trace[1840759854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5122; }","duration":"769.667601ms","start":"2026-10-10T10:27:51.655925Z","end":"2026-10-10T10:27:52.425593Z","steps":["trace[1840759854] 'agreement among raft nodes before linearized reading' (duration: 767.339791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:52.42564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:27:51.655916Z","time spent":"769.709783ms","remote":"127.0.0.1:39642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-10T10:27:52.424189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.740643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-10T10:27:52.425782Z","caller":"traceutil/trace.go:171","msg":"trace[695289134] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5122; }","duration":"145.950391ms","start":"2026-10-10T10:27:52.279818Z","end":"2026-10-10T10:27:52.425768Z","steps":["trace[695289134] 'agreement among raft nodes before linearized reading' (duration: 143.269655ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:52.550018Z","caller":"traceutil/trace.go:171","msg":"trace[1191929841] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"118.13929ms","start":"2026-10-10T10:27:52.431856Z","end":"2026-10-10T10:27:52.549995Z","steps":["trace[1191929841] 'process raft request' (duration: 117.970357ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:52.550315Z","caller":"traceutil/trace.go:171","msg":"trace[906242519] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"118.346674ms","start":"2026-10-10T10:27:52.431957Z","end":"2026-10-10T10:27:52.550303Z","steps":["trace[906242519] 'process raft request' (duration: 117.997578ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:52.550493Z","caller":"traceutil/trace.go:171","msg":"trace[1987445293] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"118.521516ms","start":"2026-10-10T10:27:52.431947Z","end":"2026-10-10T10:27:52.550469Z","steps":["trace[1987445293] 'process raft request' (duration: 118.246952ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:54.116765Z","caller":"traceutil/trace.go:171","msg":"trace[1078666291] linearizableReadLoop","detail":"{readStateIndex:5317; appliedIndex:5316; }","duration":"224.875638ms","start":"2026-10-10T10:27:53.891854Z","end":"2026-10-10T10:27:54.116729Z","steps":["trace[1078666291] 'read index received' (duration: 224.556633ms)","trace[1078666291] 'applied index is now lower than readState.Index' (duration: 317.665µs)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:27:54.116938Z","caller":"traceutil/trace.go:171","msg":"trace[260279519] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"291.988994ms","start":"2026-10-10T10:27:53.824867Z","end":"2026-10-10T10:27:54.116856Z","steps":["trace[260279519] 'process raft request' (duration: 291.642459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:27:54.11698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.102112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-10T10:27:54.117108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.37195ms","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-10-10T10:27:54.117193Z","caller":"traceutil/trace.go:171","msg":"trace[1897854478] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5128; }","duration":"149.659993ms","start":"2026-10-10T10:27:53.967518Z","end":"2026-10-10T10:27:54.117178Z","steps":["trace[1897854478] 'agreement among raft nodes before linearized reading' (duration: 149.377789ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:27:54.117206Z","caller":"traceutil/trace.go:171","msg":"trace[766392107] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5128; }","duration":"225.380505ms","start":"2026-10-10T10:27:53.891796Z","end":"2026-10-10T10:27:54.117176Z","steps":["trace[766392107] 'agreement among raft nodes before linearized reading' (duration: 225.091451ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:21.630816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2825} {"level":"info","ts":"2026-10-10T10:28:21.721593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2825,"took":"89.633278ms","hash":3490143981,"current-db-size-bytes":52551680,"current-db-size":"53 MB","current-db-size-in-use-bytes":27570176,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-10T10:28:21.72167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3490143981,"revision":2825,"compact-revision":-1} {"level":"warn","ts":"2026-10-10T10:28:35.947412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.742037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-10T10:28:35.947526Z","caller":"traceutil/trace.go:171","msg":"trace[239586627] linearizableReadLoop","detail":"{readStateIndex:5687; appliedIndex:5686; }","duration":"297.033383ms","start":"2026-10-10T10:28:35.650473Z","end":"2026-10-10T10:28:35.947506Z","steps":["trace[239586627] 'read index received' (duration: 150.040412ms)","trace[239586627] 'applied index is now lower than readState.Index' (duration: 146.991631ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:28:35.947712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.242287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:28:35.947744Z","caller":"traceutil/trace.go:171","msg":"trace[1540512732] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5487; }","duration":"297.310748ms","start":"2026-10-10T10:28:35.650422Z","end":"2026-10-10T10:28:35.947733Z","steps":["trace[1540512732] 'agreement among raft nodes before linearized reading' (duration: 297.166635ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:28:35.947584Z","caller":"traceutil/trace.go:171","msg":"trace[1836886292] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"323.211717ms","start":"2026-10-10T10:28:35.624317Z","end":"2026-10-10T10:28:35.947529Z","steps":["trace[1836886292] 'process raft request' (duration: 176.248867ms)","trace[1836886292] 'compare' (duration: 146.601094ms)"],"step_count":2} {"level":"warn","ts":"2026-10-10T10:28:35.948075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.310535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-10T10:28:35.948135Z","caller":"traceutil/trace.go:171","msg":"trace[1750150839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5487; }","duration":"272.407327ms","start":"2026-10-10T10:28:35.675714Z","end":"2026-10-10T10:28:35.948122Z","steps":["trace[1750150839] 'agreement among raft nodes before linearized reading' (duration: 272.331086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:28:35.948244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-10T10:28:35.62429Z","time spent":"323.756297ms","remote":"127.0.0.1:39416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-10T10:28:36.288958Z","caller":"traceutil/trace.go:171","msg":"trace[477318276] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"145.900172ms","start":"2026-10-10T10:28:36.14302Z","end":"2026-10-10T10:28:36.28892Z","steps":["trace[477318276] 'process raft request' (duration: 111.411567ms)","trace[477318276] 'compare' (duration: 34.230251ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:31:32.101829Z","caller":"traceutil/trace.go:171","msg":"trace[650195501] linearizableReadLoop","detail":"{readStateIndex:6963; appliedIndex:6961; }","duration":"104.473777ms","start":"2026-10-10T10:31:31.997332Z","end":"2026-10-10T10:31:32.101806Z","steps":["trace[650195501] 'read index received' (duration: 19.260295ms)","trace[650195501] 'applied index is now lower than readState.Index' (duration: 85.212162ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:31:32.102181Z","caller":"traceutil/trace.go:171","msg":"trace[1978888464] transaction","detail":"{read_only:false; response_revision:6722; number_of_response:1; }","duration":"106.493538ms","start":"2026-10-10T10:31:31.995649Z","end":"2026-10-10T10:31:32.102142Z","steps":["trace[1978888464] 'process raft request' (duration: 106.045616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:31:32.102554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.036965ms","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-10-10T10:31:32.102665Z","caller":"traceutil/trace.go:171","msg":"trace[569093716] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6722; }","duration":"104.178019ms","start":"2026-10-10T10:31:31.998444Z","end":"2026-10-10T10:31:32.102622Z","steps":["trace[569093716] 'agreement among raft nodes before linearized reading' (duration: 103.951393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-10T10:31:32.102982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.620336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-10-10T10:31:32.103079Z","caller":"traceutil/trace.go:171","msg":"trace[867985566] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6722; }","duration":"105.750778ms","start":"2026-10-10T10:31:31.997306Z","end":"2026-10-10T10:31:32.103057Z","steps":["trace[867985566] 'agreement among raft nodes before linearized reading' (duration: 104.754434ms)"],"step_count":1} {"level":"info","ts":"2026-10-10T10:31:54.608193Z","caller":"traceutil/trace.go:171","msg":"trace[704339280] transaction","detail":"{read_only:false; response_revision:6889; number_of_response:1; }","duration":"245.900623ms","start":"2026-10-10T10:31:54.362256Z","end":"2026-10-10T10:31:54.608156Z","steps":["trace[704339280] 'process raft request' (duration: 149.903714ms)","trace[704339280] 'compare' (duration: 95.853215ms)"],"step_count":2} {"level":"info","ts":"2026-10-10T10:33:21.641706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5318} {"level":"info","ts":"2026-10-10T10:33:21.738371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5318,"took":"94.573381ms","hash":1693729692,"current-db-size-bytes":52551680,"current-db-size":"53 MB","current-db-size-in-use-bytes":18403328,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-10T10:33:21.73844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1693729692,"revision":5318,"compact-revision":2825}