{"level":"warn","ts":"2026-08-09T12:54:17.810094Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-09T12:54:17.810192Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-08-09T12:54:17.810262Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-09T12:54:17.810274Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-09T12:54:17.810291Z","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-08-09T12:54:17.810959Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-08-09T12:54:17.811045Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-08-09T12:54:17.81509Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.844122ms"} {"level":"info","ts":"2026-08-09T12:54:17.82258Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-08-09T12:54:17.822669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-08-09T12:54:17.822704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-08-09T12:54:17.82274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-09T12:54:17.822754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-08-09T12:54:17.822788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-08-09T12:54:17.828088Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-09T12:54:17.829586Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-09T12:54:17.830682Z","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-08-09T12:54:17.833306Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-09T12:54:17.833466Z","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-08-09T12:54:17.833546Z","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-08-09T12:54:17.833556Z","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-08-09T12:54:17.833816Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-09T12:54:17.834494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-08-09T12:54:17.834631Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-09T12:54:17.841167Z","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-08-09T12:54:17.841337Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-09T12:54:17.84138Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-09T12:54:17.84171Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-09T12:54:17.841811Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-09T12:54:18.323229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-08-09T12:54:18.323299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-09T12:54:18.323337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-08-09T12:54:18.323358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-08-09T12:54:18.323369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-09T12:54:18.323386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-08-09T12:54:18.323431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-09T12:54:18.365487Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-08-09T12:54:18.365597Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-09T12:54:18.365583Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-09T12:54:18.365514Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-09T12:54:18.365995Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-09T12:54:18.366034Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-09T12:54:18.366527Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-08-09T12:54:18.366657Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-09T12:54:18.366699Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-09T12:54:18.368514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-09T12:54:18.370595Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-08-09T12:54:24.463136Z","caller":"traceutil/trace.go:171","msg":"trace[1712611788] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"114.571311ms","start":"2026-08-09T12:54:24.348534Z","end":"2026-08-09T12:54:24.463106Z","steps":["trace[1712611788] 'process raft request' (duration: 114.480588ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:24.607083Z","caller":"traceutil/trace.go:171","msg":"trace[1898060044] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"257.733957ms","start":"2026-08-09T12:54:24.349319Z","end":"2026-08-09T12:54:24.607053Z","steps":["trace[1898060044] 'process raft request' (duration: 257.549671ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:24.607127Z","caller":"traceutil/trace.go:171","msg":"trace[1919206344] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:310; }","duration":"224.08121ms","start":"2026-08-09T12:54:24.383025Z","end":"2026-08-09T12:54:24.607106Z","steps":["trace[1919206344] 'read index received' (duration: 79.998386ms)","trace[1919206344] 'applied index is now lower than readState.Index' (duration: 144.081644ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:54:24.607297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.192253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-09T12:54:24.607384Z","caller":"traceutil/trace.go:171","msg":"trace[350372865] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:303; }","duration":"224.380919ms","start":"2026-08-09T12:54:24.382989Z","end":"2026-08-09T12:54:24.60737Z","steps":["trace[350372865] 'agreement among raft nodes before linearized reading' (duration: 224.174163ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:24.607236Z","caller":"traceutil/trace.go:171","msg":"trace[1785298135] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"257.752507ms","start":"2026-08-09T12:54:24.349442Z","end":"2026-08-09T12:54:24.607195Z","steps":["trace[1785298135] 'process raft request' (duration: 257.532591ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:24.607371Z","caller":"traceutil/trace.go:171","msg":"trace[752733519] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"244.537377ms","start":"2026-08-09T12:54:24.362788Z","end":"2026-08-09T12:54:24.607325Z","steps":["trace[752733519] 'process raft request' (duration: 244.231148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:24.607607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.122134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-08-09T12:54:24.607691Z","caller":"traceutil/trace.go:171","msg":"trace[486579821] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:303; }","duration":"143.27724ms","start":"2026-08-09T12:54:24.464391Z","end":"2026-08-09T12:54:24.607669Z","steps":["trace[486579821] 'agreement among raft nodes before linearized reading' (duration: 143.065254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:30.168013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.779327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-09T12:54:30.168142Z","caller":"traceutil/trace.go:171","msg":"trace[500828784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:385; }","duration":"214.973183ms","start":"2026-08-09T12:54:29.953146Z","end":"2026-08-09T12:54:30.168119Z","steps":["trace[500828784] 'range keys from in-memory index tree' (duration: 214.586552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:30.168528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.847735ms","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-08-09T12:54:30.16857Z","caller":"traceutil/trace.go:171","msg":"trace[1037300778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:385; }","duration":"307.934957ms","start":"2026-08-09T12:54:29.860626Z","end":"2026-08-09T12:54:30.168561Z","steps":["trace[1037300778] 'range keys from in-memory index tree' (duration: 307.745473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:30.168572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.80752ms","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-08-09T12:54:30.16868Z","caller":"traceutil/trace.go:171","msg":"trace[504801028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:385; }","duration":"320.967265ms","start":"2026-08-09T12:54:29.84769Z","end":"2026-08-09T12:54:30.168658Z","steps":["trace[504801028] 'range keys from in-memory index tree' (duration: 320.700658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:30.168599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:29.860601Z","time spent":"307.989159ms","remote":"127.0.0.1:43730","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-08-09T12:54:30.169239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:29.847664Z","time spent":"321.282403ms","remote":"127.0.0.1:43730","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-08-09T12:54:40.404862Z","caller":"traceutil/trace.go:171","msg":"trace[349043642] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"115.961044ms","start":"2026-08-09T12:54:40.288874Z","end":"2026-08-09T12:54:40.404835Z","steps":["trace[349043642] 'read index received' (duration: 115.753999ms)","trace[349043642] 'applied index is now lower than readState.Index' (duration: 205.695µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:54:40.405142Z","caller":"traceutil/trace.go:171","msg":"trace[451789002] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"128.419398ms","start":"2026-08-09T12:54:40.276711Z","end":"2026-08-09T12:54:40.40513Z","steps":["trace[451789002] 'process raft request' (duration: 127.910556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:40.405394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.500247ms","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-08-09T12:54:40.405439Z","caller":"traceutil/trace.go:171","msg":"trace[12118791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"116.58027ms","start":"2026-08-09T12:54:40.288848Z","end":"2026-08-09T12:54:40.405428Z","steps":["trace[12118791] 'agreement among raft nodes before linearized reading' (duration: 116.493458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:40.405615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.709331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-09T12:54:40.405662Z","caller":"traceutil/trace.go:171","msg":"trace[105083578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"114.771353ms","start":"2026-08-09T12:54:40.290871Z","end":"2026-08-09T12:54:40.405643Z","steps":["trace[105083578] 'agreement among raft nodes before linearized reading' (duration: 114.711782ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:55.23597Z","caller":"traceutil/trace.go:171","msg":"trace[1865736185] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"350.296679ms","start":"2026-08-09T12:54:54.885647Z","end":"2026-08-09T12:54:55.235944Z","steps":["trace[1865736185] 'process raft request' (duration: 350.171806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:55.236745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.885625Z","time spent":"350.516633ms","remote":"127.0.0.1:43524","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-08-09T12:54:55.250381Z","caller":"traceutil/trace.go:171","msg":"trace[1645505926] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"344.623369ms","start":"2026-08-09T12:54:54.905735Z","end":"2026-08-09T12:54:55.250359Z","steps":["trace[1645505926] 'process raft request' (duration: 344.315252ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:55.250427Z","caller":"traceutil/trace.go:171","msg":"trace[526244998] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"194.729131ms","start":"2026-08-09T12:54:55.055678Z","end":"2026-08-09T12:54:55.250407Z","steps":["trace[526244998] 'process raft request' (duration: 194.601258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:55.250502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.905722Z","time spent":"344.719412ms","remote":"127.0.0.1:43524","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-08-09T12:54:55.250596Z","caller":"traceutil/trace.go:171","msg":"trace[315431720] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"360.393321ms","start":"2026-08-09T12:54:54.890195Z","end":"2026-08-09T12:54:55.250589Z","steps":["trace[315431720] 'process raft request' (duration: 359.674465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:55.25063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.89018Z","time spent":"360.435282ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-09T12:54:55.250632Z","caller":"traceutil/trace.go:171","msg":"trace[1837014788] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"353.433966ms","start":"2026-08-09T12:54:54.897185Z","end":"2026-08-09T12:54:55.250619Z","steps":["trace[1837014788] 'process raft request' (duration: 352.810202ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:55.25067Z","caller":"traceutil/trace.go:171","msg":"trace[40917256] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"344.860785ms","start":"2026-08-09T12:54:54.905798Z","end":"2026-08-09T12:54:55.250659Z","steps":["trace[40917256] 'process raft request' (duration: 344.276282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:55.250742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.905651Z","time spent":"345.042958ms","remote":"127.0.0.1:43524","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-08-09T12:54:55.250761Z","caller":"traceutil/trace.go:171","msg":"trace[28876355] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"350.065371ms","start":"2026-08-09T12:54:54.900689Z","end":"2026-08-09T12:54:55.250754Z","steps":["trace[28876355] 'process raft request' (duration: 349.335915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:55.250776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.897158Z","time spent":"353.533078ms","remote":"127.0.0.1:43524","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-08-09T12:54:55.250801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.900677Z","time spent":"350.105192ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-09T12:54:55.250896Z","caller":"traceutil/trace.go:171","msg":"trace[734766590] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"298.299575ms","start":"2026-08-09T12:54:54.952587Z","end":"2026-08-09T12:54:55.250887Z","steps":["trace[734766590] 'process raft request' (duration: 297.64892ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:55.251076Z","caller":"traceutil/trace.go:171","msg":"trace[217595416] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"340.547068ms","start":"2026-08-09T12:54:54.91052Z","end":"2026-08-09T12:54:55.251067Z","steps":["trace[217595416] 'process raft request' (duration: 339.578696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:55.251129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.9105Z","time spent":"340.61049ms","remote":"127.0.0.1:43524","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-08-09T12:54:55.25116Z","caller":"traceutil/trace.go:171","msg":"trace[639457686] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"321.640776ms","start":"2026-08-09T12:54:54.929481Z","end":"2026-08-09T12:54:55.251122Z","steps":["trace[639457686] 'process raft request' (duration: 320.714876ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:55.251179Z","caller":"traceutil/trace.go:171","msg":"trace[1150369489] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"324.173853ms","start":"2026-08-09T12:54:54.926962Z","end":"2026-08-09T12:54:55.251136Z","steps":["trace[1150369489] 'process raft request' (duration: 323.191391ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:55.250915Z","caller":"traceutil/trace.go:171","msg":"trace[699911858] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"360.633217ms","start":"2026-08-09T12:54:54.890263Z","end":"2026-08-09T12:54:55.250896Z","steps":["trace[699911858] 'process raft request' (duration: 359.701127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:55.251286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.929475Z","time spent":"321.752839ms","remote":"127.0.0.1:43524","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-08-09T12:54:55.251339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.890242Z","time spent":"361.039456ms","remote":"127.0.0.1:43524","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-08-09T12:54:55.251602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:54:54.92695Z","time spent":"324.557111ms","remote":"127.0.0.1:43524","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-08-09T12:54:55.780406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.568413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T12:54:55.780512Z","caller":"traceutil/trace.go:171","msg":"trace[1271012009] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"256.654803ms","start":"2026-08-09T12:54:55.523829Z","end":"2026-08-09T12:54:55.780484Z","steps":["trace[1271012009] 'process raft request' (duration: 124.757542ms)","trace[1271012009] 'compare' (duration: 131.383859ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:54:55.824776Z","caller":"traceutil/trace.go:171","msg":"trace[372508329] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"282.667599ms","start":"2026-08-09T12:54:55.542068Z","end":"2026-08-09T12:54:55.824735Z","steps":["trace[372508329] 'process raft request' (duration: 282.367282ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:55.825016Z","caller":"traceutil/trace.go:171","msg":"trace[327668106] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"259.128007ms","start":"2026-08-09T12:54:55.565861Z","end":"2026-08-09T12:54:55.824989Z","steps":["trace[327668106] 'process raft request' (duration: 258.842991ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:54:55.825059Z","caller":"traceutil/trace.go:171","msg":"trace[1169382105] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"161.940795ms","start":"2026-08-09T12:54:55.663002Z","end":"2026-08-09T12:54:55.824943Z","steps":["trace[1169382105] 'read index received' (duration: 161.508296ms)","trace[1169382105] 'applied index is now lower than readState.Index' (duration: 431.729µs)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:54:55.825177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.14925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-09T12:54:55.82529Z","caller":"traceutil/trace.go:171","msg":"trace[1800768065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"162.273072ms","start":"2026-08-09T12:54:55.662973Z","end":"2026-08-09T12:54:55.825246Z","steps":["trace[1800768065] 'agreement among raft nodes before linearized reading' (duration: 162.099078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:54:55.830152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.638905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:54:55.830236Z","caller":"traceutil/trace.go:171","msg":"trace[894535962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"103.723517ms","start":"2026-08-09T12:54:55.72646Z","end":"2026-08-09T12:54:55.830183Z","steps":["trace[894535962] 'agreement among raft nodes before linearized reading' (duration: 103.43775ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:55:01.858097Z","caller":"traceutil/trace.go:171","msg":"trace[796394730] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"455.283676ms","start":"2026-08-09T12:55:01.402789Z","end":"2026-08-09T12:55:01.858073Z","steps":["trace[796394730] 'read index received' (duration: 451.554868ms)","trace[796394730] 'applied index is now lower than readState.Index' (duration: 3.727668ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:55:01.858215Z","caller":"traceutil/trace.go:171","msg":"trace[921461601] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"531.818269ms","start":"2026-08-09T12:55:01.326363Z","end":"2026-08-09T12:55:01.858182Z","steps":["trace[921461601] 'process raft request' (duration: 529.387987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:01.858281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.47159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-09T12:55:01.85835Z","caller":"traceutil/trace.go:171","msg":"trace[1925608893] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:627; }","duration":"455.596213ms","start":"2026-08-09T12:55:01.402738Z","end":"2026-08-09T12:55:01.858334Z","steps":["trace[1925608893] 'agreement among raft nodes before linearized reading' (duration: 455.46447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:01.858389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:55:01.3263Z","time spent":"531.996752ms","remote":"127.0.0.1:43578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:55:01.858454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:55:01.402724Z","time spent":"455.716775ms","remote":"127.0.0.1:43506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-08-09T12:55:01.858572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.879558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:55:01.858648Z","caller":"traceutil/trace.go:171","msg":"trace[828058073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"381.989051ms","start":"2026-08-09T12:55:01.476647Z","end":"2026-08-09T12:55:01.858636Z","steps":["trace[828058073] 'agreement among raft nodes before linearized reading' (duration: 381.830788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:01.858752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:55:01.476625Z","time spent":"382.115383ms","remote":"127.0.0.1:43476","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-08-09T12:55:02.7797Z","caller":"traceutil/trace.go:171","msg":"trace[309676092] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"342.253475ms","start":"2026-08-09T12:55:02.437401Z","end":"2026-08-09T12:55:02.779655Z","steps":["trace[309676092] 'read index received' (duration: 341.077911ms)","trace[309676092] 'applied index is now lower than readState.Index' (duration: 1.174594ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:55:02.779832Z","caller":"traceutil/trace.go:171","msg":"trace[294314508] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"371.511819ms","start":"2026-08-09T12:55:02.408311Z","end":"2026-08-09T12:55:02.779823Z","steps":["trace[294314508] 'process raft request' (duration: 370.471767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:02.779947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:55:02.408282Z","time spent":"371.576349ms","remote":"127.0.0.1:43578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:55:02.780326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.92217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkn7x\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-09T12:55:02.780358Z","caller":"traceutil/trace.go:171","msg":"trace[1992460821] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkn7x; range_end:; response_count:1; response_revision:634; }","duration":"342.98218ms","start":"2026-08-09T12:55:02.437367Z","end":"2026-08-09T12:55:02.780349Z","steps":["trace[1992460821] 'agreement among raft nodes before linearized reading' (duration: 342.855438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:02.780387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:55:02.437351Z","time spent":"343.029462ms","remote":"127.0.0.1:43672","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-wkn7x\" "} {"level":"warn","ts":"2026-08-09T12:55:02.780583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.622803ms","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-08-09T12:55:02.780609Z","caller":"traceutil/trace.go:171","msg":"trace[478849057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"276.683665ms","start":"2026-08-09T12:55:02.503917Z","end":"2026-08-09T12:55:02.780601Z","steps":["trace[478849057] 'agreement among raft nodes before linearized reading' (duration: 276.605053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:02.781108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.726335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-09T12:55:02.781138Z","caller":"traceutil/trace.go:171","msg":"trace[179145599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"264.773185ms","start":"2026-08-09T12:55:02.516354Z","end":"2026-08-09T12:55:02.781127Z","steps":["trace[179145599] 'agreement among raft nodes before linearized reading' (duration: 264.689924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:02.781336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.707901ms","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-08-09T12:55:02.781364Z","caller":"traceutil/trace.go:171","msg":"trace[1108682015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"271.763922ms","start":"2026-08-09T12:55:02.509591Z","end":"2026-08-09T12:55:02.781355Z","steps":["trace[1108682015] 'agreement among raft nodes before linearized reading' (duration: 271.65174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:03.727923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.56935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T12:55:03.728139Z","caller":"traceutil/trace.go:171","msg":"trace[1108634752] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"931.528676ms","start":"2026-08-09T12:55:02.79655Z","end":"2026-08-09T12:55:03.728079Z","steps":["trace[1108634752] 'process raft request' (duration: 634.7164ms)","trace[1108634752] 'compare' (duration: 296.456298ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:55:03.728286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:55:02.796534Z","time spent":"931.716601ms","remote":"127.0.0.1:43672","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-08-09T12:55:03.736786Z","caller":"traceutil/trace.go:171","msg":"trace[1648265405] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"259.131096ms","start":"2026-08-09T12:55:03.477628Z","end":"2026-08-09T12:55:03.736759Z","steps":["trace[1648265405] 'read index received' (duration: 258.959052ms)","trace[1648265405] 'applied index is now lower than readState.Index' (duration: 171.054µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:55:03.736841Z","caller":"traceutil/trace.go:171","msg":"trace[1420981346] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"900.572569ms","start":"2026-08-09T12:55:02.836252Z","end":"2026-08-09T12:55:03.736825Z","steps":["trace[1420981346] 'process raft request' (duration: 900.397096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:03.736954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.309019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:55:03.736991Z","caller":"traceutil/trace.go:171","msg":"trace[1651671564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"259.392711ms","start":"2026-08-09T12:55:03.477588Z","end":"2026-08-09T12:55:03.73698Z","steps":["trace[1651671564] 'agreement among raft nodes before linearized reading' (duration: 259.293799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:03.737205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:55:02.83623Z","time spent":"900.735582ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-09T12:55:19.350684Z","caller":"traceutil/trace.go:171","msg":"trace[2115236033] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"149.611995ms","start":"2026-08-09T12:55:19.200859Z","end":"2026-08-09T12:55:19.350471Z","steps":["trace[2115236033] 'process raft request' (duration: 149.457112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:55:19.728665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.599869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T12:55:19.72879Z","caller":"traceutil/trace.go:171","msg":"trace[1631199101] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"166.876623ms","start":"2026-08-09T12:55:19.561893Z","end":"2026-08-09T12:55:19.72877Z","steps":["trace[1631199101] 'process raft request' (duration: 64.06358ms)","trace[1631199101] 'compare' (duration: 102.071359ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:55:50.578662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.029584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T12:55:50.579456Z","caller":"traceutil/trace.go:171","msg":"trace[806935037] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1208; }","duration":"220.877877ms","start":"2026-08-09T12:55:50.358561Z","end":"2026-08-09T12:55:50.579438Z","steps":["trace[806935037] 'count revisions from in-memory index tree' (duration: 219.941513ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:56:06.384272Z","caller":"traceutil/trace.go:171","msg":"trace[1369897218] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"103.203413ms","start":"2026-08-09T12:56:06.281036Z","end":"2026-08-09T12:56:06.38424Z","steps":["trace[1369897218] 'process raft request' (duration: 102.98858ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:56:11.688697Z","caller":"traceutil/trace.go:171","msg":"trace[2080672534] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"378.12312ms","start":"2026-08-09T12:56:11.310546Z","end":"2026-08-09T12:56:11.688669Z","steps":["trace[2080672534] 'process raft request' (duration: 377.977098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:11.688881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:11.31052Z","time spent":"378.258622ms","remote":"127.0.0.1:43558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":859,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-09T12:56:11.788938Z","caller":"traceutil/trace.go:171","msg":"trace[1993917046] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1501; }","duration":"382.079524ms","start":"2026-08-09T12:56:11.406834Z","end":"2026-08-09T12:56:11.788914Z","steps":["trace[1993917046] 'read index received' (duration: 280.702347ms)","trace[1993917046] 'applied index is now lower than readState.Index' (duration: 101.375977ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:56:11.789051Z","caller":"traceutil/trace.go:171","msg":"trace[521897953] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"477.067053ms","start":"2026-08-09T12:56:11.311974Z","end":"2026-08-09T12:56:11.789041Z","steps":["trace[521897953] 'process raft request' (duration: 474.341285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:11.789163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:11.31194Z","time spent":"477.137185ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:56:11.789603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.771614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-09T12:56:11.789663Z","caller":"traceutil/trace.go:171","msg":"trace[1057403151] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1461; }","duration":"382.877715ms","start":"2026-08-09T12:56:11.40677Z","end":"2026-08-09T12:56:11.789648Z","steps":["trace[1057403151] 'agreement among raft nodes before linearized reading' (duration: 382.658932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:11.789704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:11.406737Z","time spent":"382.956376ms","remote":"127.0.0.1:43506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-08-09T12:56:11.789791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.433841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:56:11.78986Z","caller":"traceutil/trace.go:171","msg":"trace[738227876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1461; }","duration":"313.545373ms","start":"2026-08-09T12:56:11.476295Z","end":"2026-08-09T12:56:11.78984Z","steps":["trace[738227876] 'agreement among raft nodes before linearized reading' (duration: 313.42568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:11.789905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:11.476277Z","time spent":"313.618164ms","remote":"127.0.0.1:43476","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-08-09T12:56:11.790068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.405062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-09T12:56:11.790115Z","caller":"traceutil/trace.go:171","msg":"trace[1052105531] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1461; }","duration":"143.491943ms","start":"2026-08-09T12:56:11.64661Z","end":"2026-08-09T12:56:11.790102Z","steps":["trace[1052105531] 'agreement among raft nodes before linearized reading' (duration: 143.377521ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:40.665218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874655101906177,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-09T12:56:41.052711Z","caller":"traceutil/trace.go:171","msg":"trace[436371877] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"1.029634075s","start":"2026-08-09T12:56:40.023056Z","end":"2026-08-09T12:56:41.05269Z","steps":["trace[436371877] 'process raft request' (duration: 1.029499673s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.052955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:40.023037Z","time spent":"1.029741246s","remote":"127.0.0.1:43730","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-08-09T12:56:41.061503Z","caller":"traceutil/trace.go:171","msg":"trace[1791087732] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"832.573557ms","start":"2026-08-09T12:56:40.228913Z","end":"2026-08-09T12:56:41.061487Z","steps":["trace[1791087732] 'process raft request' (duration: 832.293484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.061648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:40.228893Z","time spent":"832.646928ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:56:41.061796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.776334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-991caff4-f68f-49f0-9063-07d83c7b6d0a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-09T12:56:41.061887Z","caller":"traceutil/trace.go:171","msg":"trace[2055527882] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-991caff4-f68f-49f0-9063-07d83c7b6d0a; range_end:; response_count:1; response_revision:1893; }","duration":"896.900456ms","start":"2026-08-09T12:56:40.164967Z","end":"2026-08-09T12:56:41.061868Z","steps":["trace[2055527882] 'agreement among raft nodes before linearized reading' (duration: 896.672473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.061935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:40.164948Z","time spent":"896.978437ms","remote":"127.0.0.1:43672","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-991caff4-f68f-49f0-9063-07d83c7b6d0a\" "} {"level":"info","ts":"2026-08-09T12:56:41.061992Z","caller":"traceutil/trace.go:171","msg":"trace[2013219901] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1962; }","duration":"896.48158ms","start":"2026-08-09T12:56:40.165014Z","end":"2026-08-09T12:56:41.061495Z","steps":["trace[2013219901] 'read index received' (duration: 887.644439ms)","trace[2013219901] 'applied index is now lower than readState.Index' (duration: 8.836041ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:56:41.062144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.688972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:56:41.062184Z","caller":"traceutil/trace.go:171","msg":"trace[949834578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"585.772084ms","start":"2026-08-09T12:56:40.476402Z","end":"2026-08-09T12:56:41.062174Z","steps":["trace[949834578] 'agreement among raft nodes before linearized reading' (duration: 585.699373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.062215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:40.476382Z","time spent":"585.825334ms","remote":"127.0.0.1:43476","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-08-09T12:56:41.062255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.855771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-09T12:56:41.062279Z","caller":"traceutil/trace.go:171","msg":"trace[1426573235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1893; }","duration":"397.917481ms","start":"2026-08-09T12:56:40.664354Z","end":"2026-08-09T12:56:41.062272Z","steps":["trace[1426573235] 'agreement among raft nodes before linearized reading' (duration: 397.85403ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.062296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:40.664262Z","time spent":"398.030123ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-09T12:56:41.06238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.734848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-09T12:56:41.062395Z","caller":"traceutil/trace.go:171","msg":"trace[48692943] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1893; }","duration":"474.802358ms","start":"2026-08-09T12:56:40.587588Z","end":"2026-08-09T12:56:41.06239Z","steps":["trace[48692943] 'agreement among raft nodes before linearized reading' (duration: 474.751448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.062414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:40.587567Z","time spent":"474.84313ms","remote":"127.0.0.1:42828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-09T12:56:41.331129Z","caller":"traceutil/trace.go:171","msg":"trace[1925328371] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"262.868816ms","start":"2026-08-09T12:56:41.068227Z","end":"2026-08-09T12:56:41.331096Z","steps":["trace[1925328371] 'process raft request' (duration: 244.194091ms)","trace[1925328371] 'compare' (duration: 18.403501ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:56:41.815175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.158785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T12:56:41.815694Z","caller":"traceutil/trace.go:171","msg":"trace[1913726093] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"480.07965ms","start":"2026-08-09T12:56:41.335572Z","end":"2026-08-09T12:56:41.815651Z","steps":["trace[1913726093] 'process raft request' (duration: 135.380278ms)","trace[1913726093] 'compare' (duration: 344.006074ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:56:41.815709Z","caller":"traceutil/trace.go:171","msg":"trace[607476812] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"476.921729ms","start":"2026-08-09T12:56:41.338758Z","end":"2026-08-09T12:56:41.815679Z","steps":["trace[607476812] 'process raft request' (duration: 476.511364ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:56:41.815781Z","caller":"traceutil/trace.go:171","msg":"trace[2056127133] linearizableReadLoop","detail":"{readStateIndex:1972; appliedIndex:1968; }","duration":"407.204766ms","start":"2026-08-09T12:56:41.408487Z","end":"2026-08-09T12:56:41.815692Z","steps":["trace[2056127133] 'read index received' (duration: 62.451973ms)","trace[2056127133] 'applied index is now lower than readState.Index' (duration: 344.752002ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:56:41.81587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.335548Z","time spent":"480.232662ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:56:41.81588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.338743Z","time spent":"477.048401ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:56:41.815839Z","caller":"traceutil/trace.go:171","msg":"trace[635941995] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"447.277179ms","start":"2026-08-09T12:56:41.3685Z","end":"2026-08-09T12:56:41.815777Z","steps":["trace[635941995] 'process raft request' (duration: 447.162177ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:56:41.815728Z","caller":"traceutil/trace.go:171","msg":"trace[62434373] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"476.300322ms","start":"2026-08-09T12:56:41.339413Z","end":"2026-08-09T12:56:41.815713Z","steps":["trace[62434373] 'process raft request' (duration: 476.18912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.816234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.368476Z","time spent":"447.614942ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:56:41.815957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.469839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-09T12:56:41.8163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.339383Z","time spent":"476.847739ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:56:41.816415Z","caller":"traceutil/trace.go:171","msg":"trace[1548217200] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1900; }","duration":"407.969245ms","start":"2026-08-09T12:56:41.408434Z","end":"2026-08-09T12:56:41.816403Z","steps":["trace[1548217200] 'agreement among raft nodes before linearized reading' (duration: 407.378198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.816466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.408417Z","time spent":"408.034217ms","remote":"127.0.0.1:43506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"info","ts":"2026-08-09T12:56:41.843256Z","caller":"traceutil/trace.go:171","msg":"trace[151780580] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"359.294485ms","start":"2026-08-09T12:56:41.483944Z","end":"2026-08-09T12:56:41.843239Z","steps":["trace[151780580] 'process raft request' (duration: 359.186974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.843363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.483933Z","time spent":"359.378107ms","remote":"127.0.0.1:43730","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-08-09T12:56:41.850147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.836658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:56:41.850185Z","caller":"traceutil/trace.go:171","msg":"trace[706931781] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1902; }","duration":"421.90965ms","start":"2026-08-09T12:56:41.428266Z","end":"2026-08-09T12:56:41.850176Z","steps":["trace[706931781] 'agreement among raft nodes before linearized reading' (duration: 421.853509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.850212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.829027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T12:56:41.850209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.428244Z","time spent":"421.95773ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-09T12:56:41.850287Z","caller":"traceutil/trace.go:171","msg":"trace[1772260779] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1902; }","duration":"172.925189ms","start":"2026-08-09T12:56:41.67732Z","end":"2026-08-09T12:56:41.850245Z","steps":["trace[1772260779] 'agreement among raft nodes before linearized reading' (duration: 172.855898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.850314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.136921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:56:41.850355Z","caller":"traceutil/trace.go:171","msg":"trace[1457580074] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1902; }","duration":"232.205032ms","start":"2026-08-09T12:56:41.618136Z","end":"2026-08-09T12:56:41.850341Z","steps":["trace[1457580074] 'agreement among raft nodes before linearized reading' (duration: 232.151091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.850254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.431933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T12:56:41.850369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.903626ms","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":"info","ts":"2026-08-09T12:56:41.850451Z","caller":"traceutil/trace.go:171","msg":"trace[1795192560] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1902; }","duration":"385.029347ms","start":"2026-08-09T12:56:41.465402Z","end":"2026-08-09T12:56:41.850431Z","steps":["trace[1795192560] 'agreement among raft nodes before linearized reading' (duration: 384.883895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.850499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.46539Z","time spent":"385.097789ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-08-09T12:56:41.850409Z","caller":"traceutil/trace.go:171","msg":"trace[781498755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1902; }","duration":"373.622984ms","start":"2026-08-09T12:56:41.476778Z","end":"2026-08-09T12:56:41.850401Z","steps":["trace[781498755] 'agreement among raft nodes before linearized reading' (duration: 373.419772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:41.850618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:56:41.476765Z","time spent":"373.843157ms","remote":"127.0.0.1:43476","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-08-09T12:56:47.225152Z","caller":"traceutil/trace.go:171","msg":"trace[85108528] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"146.597328ms","start":"2026-08-09T12:56:47.078528Z","end":"2026-08-09T12:56:47.225125Z","steps":["trace[85108528] 'process raft request' (duration: 146.351395ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:56:47.227061Z","caller":"traceutil/trace.go:171","msg":"trace[778030035] linearizableReadLoop","detail":"{readStateIndex:2034; appliedIndex:2033; }","duration":"122.389231ms","start":"2026-08-09T12:56:47.104648Z","end":"2026-08-09T12:56:47.227037Z","steps":["trace[778030035] 'read index received' (duration: 120.591818ms)","trace[778030035] 'applied index is now lower than readState.Index' (duration: 1.796233ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:56:47.227146Z","caller":"traceutil/trace.go:171","msg":"trace[2131497709] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"146.694431ms","start":"2026-08-09T12:56:47.080423Z","end":"2026-08-09T12:56:47.227117Z","steps":["trace[2131497709] 'process raft request' (duration: 146.466638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:47.227322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.816261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-09T12:56:47.227324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.657434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-09T12:56:47.227358Z","caller":"traceutil/trace.go:171","msg":"trace[1960354062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1959; }","duration":"103.887581ms","start":"2026-08-09T12:56:47.123462Z","end":"2026-08-09T12:56:47.22735Z","steps":["trace[1960354062] 'agreement among raft nodes before linearized reading' (duration: 103.77576ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:56:47.227374Z","caller":"traceutil/trace.go:171","msg":"trace[1423021242] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1959; }","duration":"122.754194ms","start":"2026-08-09T12:56:47.104602Z","end":"2026-08-09T12:56:47.227356Z","steps":["trace[1423021242] 'agreement among raft nodes before linearized reading' (duration: 122.554882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:56:47.228732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.671525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-09T12:56:47.228772Z","caller":"traceutil/trace.go:171","msg":"trace[610703478] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1959; }","duration":"110.738785ms","start":"2026-08-09T12:56:47.118021Z","end":"2026-08-09T12:56:47.22876Z","steps":["trace[610703478] 'agreement among raft nodes before linearized reading' (duration: 109.196917ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:56:47.503878Z","caller":"traceutil/trace.go:171","msg":"trace[1999916664] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"118.894516ms","start":"2026-08-09T12:56:47.384967Z","end":"2026-08-09T12:56:47.503861Z","steps":["trace[1999916664] 'process raft request' (duration: 118.789295ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:13.509776Z","caller":"traceutil/trace.go:171","msg":"trace[454453618] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"128.471478ms","start":"2026-08-09T12:57:13.381221Z","end":"2026-08-09T12:57:13.509692Z","steps":["trace[454453618] 'process raft request' (duration: 128.252985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:13.92808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.767605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T12:57:13.928189Z","caller":"traceutil/trace.go:171","msg":"trace[205842840] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"415.477742ms","start":"2026-08-09T12:57:13.512687Z","end":"2026-08-09T12:57:13.928164Z","steps":["trace[205842840] 'process raft request' (duration: 199.541685ms)","trace[205842840] 'compare' (duration: 215.621343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:57:13.928241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:13.512667Z","time spent":"415.551604ms","remote":"127.0.0.1:43558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:57:13.942486Z","caller":"traceutil/trace.go:171","msg":"trace[1716212109] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"426.254468ms","start":"2026-08-09T12:57:13.516196Z","end":"2026-08-09T12:57:13.942451Z","steps":["trace[1716212109] 'process raft request' (duration: 425.981745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:13.94271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:13.516182Z","time spent":"426.40535ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:57:13.942948Z","caller":"traceutil/trace.go:171","msg":"trace[787760625] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2204; }","duration":"390.262839ms","start":"2026-08-09T12:57:13.552673Z","end":"2026-08-09T12:57:13.942936Z","steps":["trace[787760625] 'read index received' (duration: 159.644291ms)","trace[787760625] 'applied index is now lower than readState.Index' (duration: 230.617988ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:57:13.942949Z","caller":"traceutil/trace.go:171","msg":"trace[1077231829] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"425.288656ms","start":"2026-08-09T12:57:13.517635Z","end":"2026-08-09T12:57:13.942924Z","steps":["trace[1077231829] 'process raft request' (duration: 424.76657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:13.943087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:13.517617Z","time spent":"425.392658ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:57:13.943276Z","caller":"traceutil/trace.go:171","msg":"trace[202851833] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"323.014516ms","start":"2026-08-09T12:57:13.620247Z","end":"2026-08-09T12:57:13.943262Z","steps":["trace[202851833] 'process raft request' (duration: 322.513549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:13.943344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:13.620215Z","time spent":"323.097177ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:57:13.943502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.664144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-08-09T12:57:13.943589Z","caller":"traceutil/trace.go:171","msg":"trace[119290455] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2128; }","duration":"391.009218ms","start":"2026-08-09T12:57:13.55257Z","end":"2026-08-09T12:57:13.943579Z","steps":["trace[119290455] 'agreement among raft nodes before linearized reading' (duration: 390.41676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:13.944079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:13.552539Z","time spent":"391.529704ms","remote":"127.0.0.1:43672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12409,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-09T12:57:14.139306Z","caller":"traceutil/trace.go:171","msg":"trace[1773328753] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2208; }","duration":"190.571518ms","start":"2026-08-09T12:57:13.948703Z","end":"2026-08-09T12:57:14.139275Z","steps":["trace[1773328753] 'read index received' (duration: 190.106383ms)","trace[1773328753] 'applied index is now lower than readState.Index' (duration: 463.985µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:57:14.139321Z","caller":"traceutil/trace.go:171","msg":"trace[965979819] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"199.860817ms","start":"2026-08-09T12:57:13.939428Z","end":"2026-08-09T12:57:14.139289Z","steps":["trace[965979819] 'process raft request' (duration: 199.342221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:14.13969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.973454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-09T12:57:14.139741Z","caller":"traceutil/trace.go:171","msg":"trace[929355748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2129; }","duration":"191.063975ms","start":"2026-08-09T12:57:13.948666Z","end":"2026-08-09T12:57:14.13973Z","steps":["trace[929355748] 'agreement among raft nodes before linearized reading' (duration: 190.732551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:14.142141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.335157ms","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-08-09T12:57:14.142204Z","caller":"traceutil/trace.go:171","msg":"trace[789908283] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2130; }","duration":"191.462359ms","start":"2026-08-09T12:57:13.950727Z","end":"2026-08-09T12:57:14.142189Z","steps":["trace[789908283] 'agreement among raft nodes before linearized reading' (duration: 191.319357ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:14.142263Z","caller":"traceutil/trace.go:171","msg":"trace[1008466941] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"164.228382ms","start":"2026-08-09T12:57:13.977933Z","end":"2026-08-09T12:57:14.142162Z","steps":["trace[1008466941] 'process raft request' (duration: 163.965598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:14.142319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.627933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-09T12:57:14.142665Z","caller":"traceutil/trace.go:171","msg":"trace[720212310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2130; }","duration":"133.005458ms","start":"2026-08-09T12:57:14.009623Z","end":"2026-08-09T12:57:14.142629Z","steps":["trace[720212310] 'agreement among raft nodes before linearized reading' (duration: 132.479461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:14.142461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.054242ms","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-08-09T12:57:14.142902Z","caller":"traceutil/trace.go:171","msg":"trace[291440384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2130; }","duration":"135.505698ms","start":"2026-08-09T12:57:14.007336Z","end":"2026-08-09T12:57:14.142842Z","steps":["trace[291440384] 'agreement among raft nodes before linearized reading' (duration: 135.062553ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:20.18953Z","caller":"traceutil/trace.go:171","msg":"trace[1709963558] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"202.107228ms","start":"2026-08-09T12:57:19.987393Z","end":"2026-08-09T12:57:20.1895Z","steps":["trace[1709963558] 'process raft request' (duration: 201.836105ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:20.393582Z","caller":"traceutil/trace.go:171","msg":"trace[807173642] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2236; }","duration":"219.75663ms","start":"2026-08-09T12:57:20.173801Z","end":"2026-08-09T12:57:20.393557Z","steps":["trace[807173642] 'read index received' (duration: 15.513249ms)","trace[807173642] 'applied index is now lower than readState.Index' (duration: 204.242541ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:57:20.394026Z","caller":"traceutil/trace.go:171","msg":"trace[106755047] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"374.015526ms","start":"2026-08-09T12:57:20.019963Z","end":"2026-08-09T12:57:20.393979Z","steps":["trace[106755047] 'process raft request' (duration: 373.437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:20.39404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.209375ms","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-08-09T12:57:20.394149Z","caller":"traceutil/trace.go:171","msg":"trace[616404422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2160; }","duration":"220.366607ms","start":"2026-08-09T12:57:20.173754Z","end":"2026-08-09T12:57:20.39412Z","steps":["trace[616404422] 'agreement among raft nodes before linearized reading' (duration: 219.999403ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:20.3942Z","caller":"traceutil/trace.go:171","msg":"trace[1019561561] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"233.253116ms","start":"2026-08-09T12:57:20.160904Z","end":"2026-08-09T12:57:20.394157Z","steps":["trace[1019561561] 'process raft request' (duration: 232.573718ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:20.394371Z","caller":"traceutil/trace.go:171","msg":"trace[1008481632] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"406.616532ms","start":"2026-08-09T12:57:19.987744Z","end":"2026-08-09T12:57:20.394361Z","steps":["trace[1008481632] 'process raft request' (duration: 371.809522ms)","trace[1008481632] 'compare' (duration: 33.624617ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:57:20.394273Z","caller":"traceutil/trace.go:171","msg":"trace[1591686916] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"233.187705ms","start":"2026-08-09T12:57:20.161031Z","end":"2026-08-09T12:57:20.394218Z","steps":["trace[1591686916] 'process raft request' (duration: 232.490577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:20.394384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:20.019932Z","time spent":"374.183178ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:57:20.394529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.658686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-09T12:57:20.394558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:19.987738Z","time spent":"406.689813ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:57:20.394612Z","caller":"traceutil/trace.go:171","msg":"trace[83067105] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2160; }","duration":"217.772087ms","start":"2026-08-09T12:57:20.176821Z","end":"2026-08-09T12:57:20.394593Z","steps":["trace[83067105] 'agreement among raft nodes before linearized reading' (duration: 217.522854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:20.394868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.211484ms","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-08-09T12:57:20.394979Z","caller":"traceutil/trace.go:171","msg":"trace[984040776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2160; }","duration":"219.403747ms","start":"2026-08-09T12:57:20.175541Z","end":"2026-08-09T12:57:20.394944Z","steps":["trace[984040776] 'agreement among raft nodes before linearized reading' (duration: 218.481726ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:20.573515Z","caller":"traceutil/trace.go:171","msg":"trace[1798331874] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"173.953953ms","start":"2026-08-09T12:57:20.399529Z","end":"2026-08-09T12:57:20.573483Z","steps":["trace[1798331874] 'process raft request' (duration: 173.709881ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:20.573776Z","caller":"traceutil/trace.go:171","msg":"trace[584165635] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"174.883263ms","start":"2026-08-09T12:57:20.398871Z","end":"2026-08-09T12:57:20.573754Z","steps":["trace[584165635] 'process raft request' (duration: 123.30855ms)","trace[584165635] 'compare' (duration: 50.931546ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:57:22.496939Z","caller":"traceutil/trace.go:171","msg":"trace[553710710] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2247; }","duration":"268.387301ms","start":"2026-08-09T12:57:22.22853Z","end":"2026-08-09T12:57:22.496917Z","steps":["trace[553710710] 'read index received' (duration: 268.28742ms)","trace[553710710] 'applied index is now lower than readState.Index' (duration: 98.771µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:57:22.496999Z","caller":"traceutil/trace.go:171","msg":"trace[261993757] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"298.630188ms","start":"2026-08-09T12:57:22.19834Z","end":"2026-08-09T12:57:22.49697Z","steps":["trace[261993757] 'process raft request' (duration: 298.425346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:22.497204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.655114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T12:57:22.5261Z","caller":"traceutil/trace.go:171","msg":"trace[488795934] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2166; }","duration":"297.591476ms","start":"2026-08-09T12:57:22.228484Z","end":"2026-08-09T12:57:22.526075Z","steps":["trace[488795934] 'agreement among raft nodes before linearized reading' (duration: 268.579803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:22.99787Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874655101906804,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-09T12:57:23.196179Z","caller":"traceutil/trace.go:171","msg":"trace[557647641] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"795.651789ms","start":"2026-08-09T12:57:22.400505Z","end":"2026-08-09T12:57:23.196157Z","steps":["trace[557647641] 'process raft request' (duration: 795.482186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.196419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.400474Z","time spent":"795.775101ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:57:23.196435Z","caller":"traceutil/trace.go:171","msg":"trace[802455079] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"794.726309ms","start":"2026-08-09T12:57:22.40169Z","end":"2026-08-09T12:57:23.196416Z","steps":["trace[802455079] 'process raft request' (duration: 794.431045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.196529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.401677Z","time spent":"794.806209ms","remote":"127.0.0.1:43730","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-08-09T12:57:23.197696Z","caller":"traceutil/trace.go:171","msg":"trace[1385329720] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2248; }","duration":"700.361625ms","start":"2026-08-09T12:57:22.497308Z","end":"2026-08-09T12:57:23.197669Z","steps":["trace[1385329720] 'read index received' (duration: 698.677865ms)","trace[1385329720] 'applied index is now lower than readState.Index' (duration: 1.68266ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:57:23.198229Z","caller":"traceutil/trace.go:171","msg":"trace[1665058126] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"795.681839ms","start":"2026-08-09T12:57:22.402532Z","end":"2026-08-09T12:57:23.198214Z","steps":["trace[1665058126] 'process raft request' (duration: 793.786608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.198336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.6907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-09T12:57:23.198759Z","caller":"traceutil/trace.go:171","msg":"trace[984685964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2169; }","duration":"797.134605ms","start":"2026-08-09T12:57:22.401596Z","end":"2026-08-09T12:57:23.198731Z","steps":["trace[984685964] 'agreement among raft nodes before linearized reading' (duration: 796.6164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.198363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.865382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T12:57:23.198486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.536773ms","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-08-09T12:57:23.199146Z","caller":"traceutil/trace.go:171","msg":"trace[600114726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2169; }","duration":"722.702021ms","start":"2026-08-09T12:57:22.476395Z","end":"2026-08-09T12:57:23.199097Z","steps":["trace[600114726] 'agreement among raft nodes before linearized reading' (duration: 721.78657ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:23.199216Z","caller":"traceutil/trace.go:171","msg":"trace[998414769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2169; }","duration":"719.287363ms","start":"2026-08-09T12:57:22.479911Z","end":"2026-08-09T12:57:23.199199Z","steps":["trace[998414769] 'agreement among raft nodes before linearized reading' (duration: 718.435552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.199266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.4799Z","time spent":"719.352823ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-09T12:57:23.199248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.476377Z","time spent":"722.850403ms","remote":"127.0.0.1:43460","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-08-09T12:57:23.199111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.40158Z","time spent":"797.49485ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-09T12:57:23.198873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.40251Z","time spent":"796.126984ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:57:23.207369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.562235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-09T12:57:23.207398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.068677ms","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-08-09T12:57:23.207411Z","caller":"traceutil/trace.go:171","msg":"trace[1498184190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2170; }","duration":"630.632795ms","start":"2026-08-09T12:57:22.576769Z","end":"2026-08-09T12:57:23.207402Z","steps":["trace[1498184190] 'agreement among raft nodes before linearized reading' (duration: 630.500023ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:23.207426Z","caller":"traceutil/trace.go:171","msg":"trace[1948903690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2170; }","duration":"629.134578ms","start":"2026-08-09T12:57:22.578284Z","end":"2026-08-09T12:57:23.207418Z","steps":["trace[1948903690] 'agreement among raft nodes before linearized reading' (duration: 629.055507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.207435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.576754Z","time spent":"630.675586ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-09T12:57:23.207446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.578263Z","time spent":"629.179088ms","remote":"127.0.0.1:43730","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-08-09T12:57:23.207547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.918005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-09T12:57:23.20759Z","caller":"traceutil/trace.go:171","msg":"trace[182143659] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2170; }","duration":"560.945005ms","start":"2026-08-09T12:57:22.646621Z","end":"2026-08-09T12:57:23.207566Z","steps":["trace[182143659] 'agreement among raft nodes before linearized reading' (duration: 560.811394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.207653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:22.646569Z","time spent":"561.068507ms","remote":"127.0.0.1:42828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-09T12:57:23.521672Z","caller":"traceutil/trace.go:171","msg":"trace[224727023] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"316.226294ms","start":"2026-08-09T12:57:23.205423Z","end":"2026-08-09T12:57:23.521649Z","steps":["trace[224727023] 'process raft request' (duration: 316.174373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.521826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:23.205416Z","time spent":"316.330246ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:57:23.522002Z","caller":"traceutil/trace.go:171","msg":"trace[1821649515] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"318.198956ms","start":"2026-08-09T12:57:23.203708Z","end":"2026-08-09T12:57:23.521907Z","steps":["trace[1821649515] 'process raft request' (duration: 264.844235ms)","trace[1821649515] 'compare' (duration: 52.783285ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T12:57:23.522289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:23.203687Z","time spent":"318.44851ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T12:57:23.522738Z","caller":"traceutil/trace.go:171","msg":"trace[740461880] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"311.312457ms","start":"2026-08-09T12:57:23.211406Z","end":"2026-08-09T12:57:23.522718Z","steps":["trace[740461880] 'process raft request' (duration: 311.075215ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:57:23.522756Z","caller":"traceutil/trace.go:171","msg":"trace[1190444057] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"311.221677ms","start":"2026-08-09T12:57:23.211507Z","end":"2026-08-09T12:57:23.522729Z","steps":["trace[1190444057] 'process raft request' (duration: 311.117145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:57:23.522824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:23.211383Z","time spent":"311.399968ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:57:23.522925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:57:23.21148Z","time spent":"311.354418ms","remote":"127.0.0.1:43730","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-08-09T12:58:22.996281Z","caller":"traceutil/trace.go:171","msg":"trace[170559044] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"190.564334ms","start":"2026-08-09T12:58:22.805688Z","end":"2026-08-09T12:58:22.996252Z","steps":["trace[170559044] 'process raft request' (duration: 190.407369ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:58:24.046695Z","caller":"traceutil/trace.go:171","msg":"trace[1802954807] linearizableReadLoop","detail":"{readStateIndex:2694; appliedIndex:2693; }","duration":"208.795313ms","start":"2026-08-09T12:58:23.837871Z","end":"2026-08-09T12:58:24.046666Z","steps":["trace[1802954807] 'read index received' (duration: 208.508754ms)","trace[1802954807] 'applied index is now lower than readState.Index' (duration: 285.209µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:58:24.046759Z","caller":"traceutil/trace.go:171","msg":"trace[1377167805] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"213.935653ms","start":"2026-08-09T12:58:23.832794Z","end":"2026-08-09T12:58:24.046729Z","steps":["trace[1377167805] 'process raft request' (duration: 213.679315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:24.047034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.135034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-09T12:58:24.047086Z","caller":"traceutil/trace.go:171","msg":"trace[1894851856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2598; }","duration":"209.235747ms","start":"2026-08-09T12:58:23.837836Z","end":"2026-08-09T12:58:24.047071Z","steps":["trace[1894851856] 'agreement among raft nodes before linearized reading' (duration: 208.995709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:24.047095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.35636ms","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-08-09T12:58:24.04713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.311653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-09T12:58:24.047217Z","caller":"traceutil/trace.go:171","msg":"trace[900930450] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2598; }","duration":"159.430297ms","start":"2026-08-09T12:58:23.887767Z","end":"2026-08-09T12:58:24.047197Z","steps":["trace[900930450] 'agreement among raft nodes before linearized reading' (duration: 159.19712ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:58:24.047166Z","caller":"traceutil/trace.go:171","msg":"trace[1057784377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2598; }","duration":"202.495454ms","start":"2026-08-09T12:58:23.844653Z","end":"2026-08-09T12:58:24.047149Z","steps":["trace[1057784377] 'agreement among raft nodes before linearized reading' (duration: 202.262676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:24.047684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.521392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-09T12:58:24.04773Z","caller":"traceutil/trace.go:171","msg":"trace[892487438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2598; }","duration":"204.582873ms","start":"2026-08-09T12:58:23.843136Z","end":"2026-08-09T12:58:24.047719Z","steps":["trace[892487438] 'agreement among raft nodes before linearized reading' (duration: 203.738916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:24.050502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.138441ms","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-08-09T12:58:24.050646Z","caller":"traceutil/trace.go:171","msg":"trace[1873277045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2598; }","duration":"202.313339ms","start":"2026-08-09T12:58:23.848301Z","end":"2026-08-09T12:58:24.050615Z","steps":["trace[1873277045] 'agreement among raft nodes before linearized reading' (duration: 198.635707ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:58:50.34552Z","caller":"traceutil/trace.go:171","msg":"trace[2017685286] linearizableReadLoop","detail":"{readStateIndex:2847; appliedIndex:2846; }","duration":"162.834788ms","start":"2026-08-09T12:58:50.182662Z","end":"2026-08-09T12:58:50.345497Z","steps":["trace[2017685286] 'read index received' (duration: 162.613581ms)","trace[2017685286] 'applied index is now lower than readState.Index' (duration: 220.167µs)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:58:50.345631Z","caller":"traceutil/trace.go:171","msg":"trace[1024543915] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"197.403955ms","start":"2026-08-09T12:58:50.148201Z","end":"2026-08-09T12:58:50.345605Z","steps":["trace[1024543915] 'process raft request' (duration: 197.105736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:50.345731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.052845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T12:58:50.34579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.369027ms","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-08-09T12:58:50.345808Z","caller":"traceutil/trace.go:171","msg":"trace[1902539159] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2745; }","duration":"163.194328ms","start":"2026-08-09T12:58:50.182597Z","end":"2026-08-09T12:58:50.345791Z","steps":["trace[1902539159] 'agreement among raft nodes before linearized reading' (duration: 163.031084ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:58:50.345831Z","caller":"traceutil/trace.go:171","msg":"trace[1972266118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2745; }","duration":"157.46691ms","start":"2026-08-09T12:58:50.188354Z","end":"2026-08-09T12:58:50.345821Z","steps":["trace[1972266118] 'agreement among raft nodes before linearized reading' (duration: 157.287385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:50.345998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.667449ms","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-08-09T12:58:50.346058Z","caller":"traceutil/trace.go:171","msg":"trace[329688309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2745; }","duration":"153.855355ms","start":"2026-08-09T12:58:50.192189Z","end":"2026-08-09T12:58:50.346045Z","steps":["trace[329688309] 'agreement among raft nodes before linearized reading' (duration: 153.508715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:50.346059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.10704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-09T12:58:50.346097Z","caller":"traceutil/trace.go:171","msg":"trace[303025617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2745; }","duration":"155.188863ms","start":"2026-08-09T12:58:50.190897Z","end":"2026-08-09T12:58:50.346086Z","steps":["trace[303025617] 'agreement among raft nodes before linearized reading' (duration: 155.033078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:50.346243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.813307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-09T12:58:50.347022Z","caller":"traceutil/trace.go:171","msg":"trace[1245167067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2745; }","duration":"148.620371ms","start":"2026-08-09T12:58:50.198385Z","end":"2026-08-09T12:58:50.347005Z","steps":["trace[1245167067] 'agreement among raft nodes before linearized reading' (duration: 147.269421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:56.875549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874655101908237,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-09T12:58:57.079195Z","caller":"traceutil/trace.go:171","msg":"trace[477413567] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"707.044208ms","start":"2026-08-09T12:58:56.372114Z","end":"2026-08-09T12:58:57.079158Z","steps":["trace[477413567] 'process raft request' (duration: 706.760679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.079721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.372084Z","time spent":"707.510211ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:58:57.66209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.666408ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-09T12:58:57.66237Z","caller":"traceutil/trace.go:171","msg":"trace[2090544873] linearizableReadLoop","detail":"{readStateIndex:2880; appliedIndex:2879; }","duration":"1.287191837s","start":"2026-08-09T12:58:56.37516Z","end":"2026-08-09T12:58:57.662352Z","steps":["trace[2090544873] 'read index received' (duration: 704.455354ms)","trace[2090544873] 'applied index is now lower than readState.Index' (duration: 582.735453ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T12:58:57.662427Z","caller":"traceutil/trace.go:171","msg":"trace[214699299] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"734.341352ms","start":"2026-08-09T12:58:56.928048Z","end":"2026-08-09T12:58:57.66239Z","steps":["trace[214699299] 'process raft request' (duration: 734.136936ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:58:57.662612Z","caller":"traceutil/trace.go:171","msg":"trace[317702637] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"241.660962ms","start":"2026-08-09T12:58:57.420938Z","end":"2026-08-09T12:58:57.662599Z","steps":["trace[317702637] 'process raft request' (duration: 241.377054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.662684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.928027Z","time spent":"734.503156ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T12:58:57.662953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287775353s","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-08-09T12:58:57.663105Z","caller":"traceutil/trace.go:171","msg":"trace[1990361131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2778; }","duration":"1.287911457s","start":"2026-08-09T12:58:56.375105Z","end":"2026-08-09T12:58:57.663016Z","steps":["trace[1990361131] 'agreement among raft nodes before linearized reading' (duration: 1.28731843s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.663288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.375084Z","time spent":"1.288097202s","remote":"127.0.0.1:43730","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-08-09T12:58:57.663567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.490867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-08-09T12:58:57.663618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.855307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-08-09T12:58:57.663623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.521388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:58:57.663675Z","caller":"traceutil/trace.go:171","msg":"trace[141189237] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2781; }","duration":"331.94864ms","start":"2026-08-09T12:58:57.331709Z","end":"2026-08-09T12:58:57.663658Z","steps":["trace[141189237] 'agreement among raft nodes before linearized reading' (duration: 331.760724ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:58:57.663699Z","caller":"traceutil/trace.go:171","msg":"trace[1872482016] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2781; }","duration":"907.60333ms","start":"2026-08-09T12:58:56.756074Z","end":"2026-08-09T12:58:57.663677Z","steps":["trace[1872482016] 'agreement among raft nodes before linearized reading' (duration: 907.481597ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T12:58:57.663616Z","caller":"traceutil/trace.go:171","msg":"trace[184274994] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2781; }","duration":"568.571328ms","start":"2026-08-09T12:58:57.095032Z","end":"2026-08-09T12:58:57.663603Z","steps":["trace[184274994] 'agreement among raft nodes before linearized reading' (duration: 568.426344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.663717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:57.331691Z","time spent":"332.016122ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-09T12:58:57.663751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.755909Z","time spent":"907.828847ms","remote":"127.0.0.1:50568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-09T12:58:57.663882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:57.095014Z","time spent":"568.783895ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-09T12:58:57.664048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.795659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T12:58:57.664087Z","caller":"traceutil/trace.go:171","msg":"trace[1063946693] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2781; }","duration":"474.861531ms","start":"2026-08-09T12:58:57.189215Z","end":"2026-08-09T12:58:57.664077Z","steps":["trace[1063946693] 'agreement among raft nodes before linearized reading' (duration: 474.79439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.66412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:57.189199Z","time spent":"474.913662ms","remote":"127.0.0.1:43856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-09T12:58:57.664205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.199751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-09T12:58:57.664265Z","caller":"traceutil/trace.go:171","msg":"trace[1419212517] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2781; }","duration":"538.298693ms","start":"2026-08-09T12:58:57.125952Z","end":"2026-08-09T12:58:57.66425Z","steps":["trace[1419212517] 'agreement among raft nodes before linearized reading' (duration: 538.207211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.664317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:57.12593Z","time spent":"538.372666ms","remote":"127.0.0.1:43660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-09T12:58:57.664523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278776335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-09T12:58:57.664562Z","caller":"traceutil/trace.go:171","msg":"trace[2071156381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2781; }","duration":"1.278866178s","start":"2026-08-09T12:58:56.385687Z","end":"2026-08-09T12:58:57.664553Z","steps":["trace[2071156381] 'agreement among raft nodes before linearized reading' (duration: 1.278767754s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.664594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.385672Z","time spent":"1.278915979s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-09T12:58:57.664232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2782973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-08-09T12:58:57.664682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18728149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T12:58:57.664711Z","caller":"traceutil/trace.go:171","msg":"trace[1246462275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2781; }","duration":"1.187357923s","start":"2026-08-09T12:58:56.477345Z","end":"2026-08-09T12:58:57.664703Z","steps":["trace[1246462275] 'agreement among raft nodes before linearized reading' (duration: 1.187317222s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.664733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.477326Z","time spent":"1.187402854s","remote":"127.0.0.1:43460","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-08-09T12:58:57.664827Z","caller":"traceutil/trace.go:171","msg":"trace[1470179474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2781; }","duration":"1.278839407s","start":"2026-08-09T12:58:56.385857Z","end":"2026-08-09T12:58:57.664696Z","steps":["trace[1470179474] 'agreement among raft nodes before linearized reading' (duration: 1.278118416s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.664933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.385848Z","time spent":"1.279069673s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-09T12:58:57.664849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.15742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-09T12:58:57.665113Z","caller":"traceutil/trace.go:171","msg":"trace[1181820587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2781; }","duration":"469.471848ms","start":"2026-08-09T12:58:57.195631Z","end":"2026-08-09T12:58:57.665103Z","steps":["trace[1181820587] 'agreement among raft nodes before linearized reading' (duration: 467.834181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.665153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:57.195609Z","time spent":"469.534411ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-09T12:58:57.665375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278550509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ca247bcf60c097\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-08-09T12:58:57.665425Z","caller":"traceutil/trace.go:171","msg":"trace[1699165513] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ca247bcf60c097; range_end:; response_count:1; response_revision:2781; }","duration":"1.27860552s","start":"2026-08-09T12:58:56.386806Z","end":"2026-08-09T12:58:57.665412Z","steps":["trace[1699165513] 'agreement among raft nodes before linearized reading' (duration: 1.276713476s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.665458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.386783Z","time spent":"1.278667422s","remote":"127.0.0.1:43558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18ca247bcf60c097\" "} {"level":"warn","ts":"2026-08-09T12:58:57.665742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281129612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-09T12:58:57.665862Z","caller":"traceutil/trace.go:171","msg":"trace[1698967544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2781; }","duration":"1.281244155s","start":"2026-08-09T12:58:56.384579Z","end":"2026-08-09T12:58:57.665823Z","steps":["trace[1698967544] 'agreement among raft nodes before linearized reading' (duration: 1.278868107s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T12:58:57.665976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T12:58:56.384551Z","time spent":"1.28141314s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-09T13:02:39.951729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.750354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-8v274\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-08-09T13:02:39.951827Z","caller":"traceutil/trace.go:171","msg":"trace[1996346483] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-8v274; range_end:; response_count:1; response_revision:4538; }","duration":"192.911576ms","start":"2026-08-09T13:02:39.758894Z","end":"2026-08-09T13:02:39.951805Z","steps":["trace[1996346483] 'range keys from in-memory index tree' (duration: 192.53773ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:39.976734Z","caller":"traceutil/trace.go:171","msg":"trace[1244040866] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"217.382193ms","start":"2026-08-09T13:02:39.759328Z","end":"2026-08-09T13:02:39.97671Z","steps":["trace[1244040866] 'process raft request' (duration: 217.202801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:41.212017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.646389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-09T13:02:41.212096Z","caller":"traceutil/trace.go:171","msg":"trace[1364039273] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4588; }","duration":"332.769852ms","start":"2026-08-09T13:02:40.87931Z","end":"2026-08-09T13:02:41.21208Z","steps":["trace[1364039273] 'range keys from in-memory index tree' (duration: 332.509608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:41.212137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:40.879294Z","time spent":"332.831222ms","remote":"127.0.0.1:43578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":673,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"warn","ts":"2026-08-09T13:02:41.212513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.747526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:02:41.212587Z","caller":"traceutil/trace.go:171","msg":"trace[1548720389] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"328.996479ms","start":"2026-08-09T13:02:40.883574Z","end":"2026-08-09T13:02:41.21257Z","steps":["trace[1548720389] 'process raft request' (duration: 165.109751ms)","trace[1548720389] 'compare' (duration: 163.654925ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:41.212658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:40.883557Z","time spent":"329.068291ms","remote":"127.0.0.1:43680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-09T13:02:41.219859Z","caller":"traceutil/trace.go:171","msg":"trace[1605889612] linearizableReadLoop","detail":"{readStateIndex:4764; appliedIndex:4762; }","duration":"263.117174ms","start":"2026-08-09T13:02:40.956723Z","end":"2026-08-09T13:02:41.21984Z","steps":["trace[1605889612] 'read index received' (duration: 92.004468ms)","trace[1605889612] 'applied index is now lower than readState.Index' (duration: 171.111786ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:41.219945Z","caller":"traceutil/trace.go:171","msg":"trace[1130348827] transaction","detail":"{read_only:false; response_revision:4590; number_of_response:1; }","duration":"271.686133ms","start":"2026-08-09T13:02:40.948248Z","end":"2026-08-09T13:02:41.219935Z","steps":["trace[1130348827] 'process raft request' (duration: 271.484099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:41.220224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.49061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-09T13:02:41.220262Z","caller":"traceutil/trace.go:171","msg":"trace[399358490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4590; }","duration":"263.535311ms","start":"2026-08-09T13:02:40.956718Z","end":"2026-08-09T13:02:41.220253Z","steps":["trace[399358490] 'agreement among raft nodes before linearized reading' (duration: 263.401439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:41.220757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.614207ms","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-08-09T13:02:41.220817Z","caller":"traceutil/trace.go:171","msg":"trace[1438170275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4592; }","duration":"217.714509ms","start":"2026-08-09T13:02:41.003087Z","end":"2026-08-09T13:02:41.220802Z","steps":["trace[1438170275] 'agreement among raft nodes before linearized reading' (duration: 217.555937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:41.221008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.566857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-09T13:02:41.221032Z","caller":"traceutil/trace.go:171","msg":"trace[609435449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4592; }","duration":"178.610257ms","start":"2026-08-09T13:02:41.042416Z","end":"2026-08-09T13:02:41.221027Z","steps":["trace[609435449] 'agreement among raft nodes before linearized reading' (duration: 178.547846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:41.221143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.005929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-09T13:02:41.221166Z","caller":"traceutil/trace.go:171","msg":"trace[1063022504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4592; }","duration":"188.047899ms","start":"2026-08-09T13:02:41.033113Z","end":"2026-08-09T13:02:41.221161Z","steps":["trace[1063022504] 'agreement among raft nodes before linearized reading' (duration: 187.993498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:41.221324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.763796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-09T13:02:41.221357Z","caller":"traceutil/trace.go:171","msg":"trace[792123718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4592; }","duration":"197.809827ms","start":"2026-08-09T13:02:41.023533Z","end":"2026-08-09T13:02:41.221343Z","steps":["trace[792123718] 'agreement among raft nodes before linearized reading' (duration: 197.707235ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:41.806709Z","caller":"traceutil/trace.go:171","msg":"trace[1043904376] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"112.838063ms","start":"2026-08-09T13:02:41.693847Z","end":"2026-08-09T13:02:41.806685Z","steps":["trace[1043904376] 'process raft request' (duration: 111.939749ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:44.919995Z","caller":"traceutil/trace.go:171","msg":"trace[1909737823] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"206.157891ms","start":"2026-08-09T13:02:44.713813Z","end":"2026-08-09T13:02:44.919971Z","steps":["trace[1909737823] 'process raft request' (duration: 206.11377ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:44.920181Z","caller":"traceutil/trace.go:171","msg":"trace[2062089777] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"206.392794ms","start":"2026-08-09T13:02:44.713756Z","end":"2026-08-09T13:02:44.920149Z","steps":["trace[2062089777] 'process raft request' (duration: 164.60096ms)","trace[2062089777] 'compare' (duration: 41.456748ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:47.883541Z","caller":"traceutil/trace.go:171","msg":"trace[1900685142] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4934; }","duration":"354.091351ms","start":"2026-08-09T13:02:47.529424Z","end":"2026-08-09T13:02:47.883515Z","steps":["trace[1900685142] 'read index received' (duration: 328.829935ms)","trace[1900685142] 'applied index is now lower than readState.Index' (duration: 25.260336ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:02:47.883966Z","caller":"traceutil/trace.go:171","msg":"trace[1687137612] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"389.618191ms","start":"2026-08-09T13:02:47.494298Z","end":"2026-08-09T13:02:47.883916Z","steps":["trace[1687137612] 'process raft request' (duration: 364.012839ms)","trace[1687137612] 'compare' (duration: 25.019222ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:02:47.884204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.762033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b7bc3d1-2ca8-4659-88ce-7e194fbf0d4c\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-09T13:02:47.884245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:47.494281Z","time spent":"389.844225ms","remote":"127.0.0.1:43672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-09T13:02:47.8843Z","caller":"traceutil/trace.go:171","msg":"trace[1046215299] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0b7bc3d1-2ca8-4659-88ce-7e194fbf0d4c; range_end:; response_count:1; response_revision:4756; }","duration":"235.945466ms","start":"2026-08-09T13:02:47.648333Z","end":"2026-08-09T13:02:47.884279Z","steps":["trace[1046215299] 'agreement among raft nodes before linearized reading' (duration: 235.635511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:47.884037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.591969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-08-09T13:02:47.885495Z","caller":"traceutil/trace.go:171","msg":"trace[1085412443] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4756; }","duration":"356.055972ms","start":"2026-08-09T13:02:47.529392Z","end":"2026-08-09T13:02:47.885448Z","steps":["trace[1085412443] 'agreement among raft nodes before linearized reading' (duration: 354.476247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:02:47.885646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:02:47.529381Z","time spent":"356.212625ms","remote":"127.0.0.1:43688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":1242,"request content":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-08-09T13:02:48.004746Z","caller":"traceutil/trace.go:171","msg":"trace[617663565] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"107.14294ms","start":"2026-08-09T13:02:47.897554Z","end":"2026-08-09T13:02:48.004697Z","steps":["trace[617663565] 'process raft request' (duration: 100.152827ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:51.766084Z","caller":"traceutil/trace.go:171","msg":"trace[301444354] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"150.800398ms","start":"2026-08-09T13:02:51.615258Z","end":"2026-08-09T13:02:51.766058Z","steps":["trace[301444354] 'process raft request' (duration: 150.645295ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:02:54.43343Z","caller":"traceutil/trace.go:171","msg":"trace[609132998] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"104.86653ms","start":"2026-08-09T13:02:54.328529Z","end":"2026-08-09T13:02:54.433395Z","steps":["trace[609132998] 'process raft request' (duration: 89.83247ms)","trace[609132998] 'compare' (duration: 14.599182ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:03:01.833729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874655101913269,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-09T13:03:02.066029Z","caller":"traceutil/trace.go:171","msg":"trace[351642819] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"741.437874ms","start":"2026-08-09T13:03:01.324562Z","end":"2026-08-09T13:03:02.066Z","steps":["trace[351642819] 'process raft request' (duration: 741.255151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.066185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.324547Z","time spent":"741.554465ms","remote":"127.0.0.1:43730","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-08-09T13:03:02.410307Z","caller":"traceutil/trace.go:171","msg":"trace[430739527] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5111; }","duration":"1.076946495s","start":"2026-08-09T13:03:01.333338Z","end":"2026-08-09T13:03:02.410284Z","steps":["trace[430739527] 'read index received' (duration: 732.522514ms)","trace[430739527] 'applied index is now lower than readState.Index' (duration: 344.422891ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:02.4104Z","caller":"traceutil/trace.go:171","msg":"trace[1740903346] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"1.083986797s","start":"2026-08-09T13:03:01.326339Z","end":"2026-08-09T13:03:02.410326Z","steps":["trace[1740903346] 'process raft request' (duration: 1.083708873s)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:02.410581Z","caller":"traceutil/trace.go:171","msg":"trace[1638975078] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"1.053327983s","start":"2026-08-09T13:03:01.357239Z","end":"2026-08-09T13:03:02.410567Z","steps":["trace[1638975078] 'process raft request' (duration: 1.052986028s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.411059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077692587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-09T13:03:02.411209Z","caller":"traceutil/trace.go:171","msg":"trace[291378237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4929; }","duration":"1.077878029s","start":"2026-08-09T13:03:01.3333Z","end":"2026-08-09T13:03:02.411178Z","steps":["trace[291378237] 'agreement among raft nodes before linearized reading' (duration: 1.077439072s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.411272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.33329Z","time spent":"1.0779711s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-09T13:03:02.411419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.357226Z","time spent":"1.053417614s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:02.412093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.326319Z","time spent":"1.084292702s","remote":"127.0.0.1:43672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11655,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-09T13:03:02.579016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.576531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:03:02.579201Z","caller":"traceutil/trace.go:171","msg":"trace[1802686517] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"440.206792ms","start":"2026-08-09T13:03:02.138959Z","end":"2026-08-09T13:03:02.579165Z","steps":["trace[1802686517] 'process raft request' (duration: 333.365547ms)","trace[1802686517] 'compare' (duration: 106.404049ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:02.579398Z","caller":"traceutil/trace.go:171","msg":"trace[353640765] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"168.979324ms","start":"2026-08-09T13:03:02.410379Z","end":"2026-08-09T13:03:02.579358Z","steps":["trace[353640765] 'read index received' (duration: 61.971957ms)","trace[353640765] 'applied index is now lower than readState.Index' (duration: 106.951557ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:02.57947Z","caller":"traceutil/trace.go:171","msg":"trace[1878682856] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"153.287547ms","start":"2026-08-09T13:03:02.426159Z","end":"2026-08-09T13:03:02.579447Z","steps":["trace[1878682856] 'process raft request' (duration: 153.198325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.579528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:02.138936Z","time spent":"440.411085ms","remote":"127.0.0.1:43730","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-08-09T13:03:02.579782Z","caller":"traceutil/trace.go:171","msg":"trace[2116740329] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"158.831905ms","start":"2026-08-09T13:03:02.420935Z","end":"2026-08-09T13:03:02.579766Z","steps":["trace[2116740329] 'process raft request' (duration: 158.366747ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:02.579869Z","caller":"traceutil/trace.go:171","msg":"trace[153836845] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"159.524236ms","start":"2026-08-09T13:03:02.42031Z","end":"2026-08-09T13:03:02.579835Z","steps":["trace[153836845] 'process raft request' (duration: 158.941516ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:02.57943Z","caller":"traceutil/trace.go:171","msg":"trace[1210176641] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"160.39706ms","start":"2026-08-09T13:03:02.418983Z","end":"2026-08-09T13:03:02.57938Z","steps":["trace[1210176641] 'process raft request' (duration: 160.173976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.580276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.692681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-09T13:03:02.580382Z","caller":"traceutil/trace.go:171","msg":"trace[236377569] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4934; }","duration":"591.847584ms","start":"2026-08-09T13:03:01.988515Z","end":"2026-08-09T13:03:02.580363Z","steps":["trace[236377569] 'agreement among raft nodes before linearized reading' (duration: 591.57801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.580479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.988493Z","time spent":"591.971676ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-09T13:03:02.580595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.281361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-08-09T13:03:02.580644Z","caller":"traceutil/trace.go:171","msg":"trace[2121082251] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4934; }","duration":"903.394733ms","start":"2026-08-09T13:03:01.677234Z","end":"2026-08-09T13:03:02.580628Z","steps":["trace[2121082251] 'agreement among raft nodes before linearized reading' (duration: 903.126469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.580649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104300654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-09T13:03:02.580713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.677214Z","time spent":"903.460925ms","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-09T13:03:02.580334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233043037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-09T13:03:02.581034Z","caller":"traceutil/trace.go:171","msg":"trace[971870183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4934; }","duration":"1.233780108s","start":"2026-08-09T13:03:01.347235Z","end":"2026-08-09T13:03:02.581015Z","steps":["trace[971870183] 'agreement among raft nodes before linearized reading' (duration: 1.232968566s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.581137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.347223Z","time spent":"1.23389055s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-09T13:03:02.581156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150640867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-09T13:03:02.58148Z","caller":"traceutil/trace.go:171","msg":"trace[659573216] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:4934; }","duration":"1.151007722s","start":"2026-08-09T13:03:01.430459Z","end":"2026-08-09T13:03:02.581467Z","steps":["trace[659573216] 'agreement among raft nodes before linearized reading' (duration: 1.150569955s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.581537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.430435Z","time spent":"1.151086014s","remote":"127.0.0.1:43506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-08-09T13:03:02.581186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233959502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-09T13:03:02.581844Z","caller":"traceutil/trace.go:171","msg":"trace[701345669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4934; }","duration":"1.234640382s","start":"2026-08-09T13:03:01.347189Z","end":"2026-08-09T13:03:02.581829Z","steps":["trace[701345669] 'agreement among raft nodes before linearized reading' (duration: 1.233886s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.581926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.347174Z","time spent":"1.234735473s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-09T13:03:02.581355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243912939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-08-09T13:03:02.582112Z","caller":"traceutil/trace.go:171","msg":"trace[60392855] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4934; }","duration":"1.24465774s","start":"2026-08-09T13:03:01.337413Z","end":"2026-08-09T13:03:02.582071Z","steps":["trace[60392855] 'agreement among raft nodes before linearized reading' (duration: 1.243804507s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.582193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.337405Z","time spent":"1.244768342s","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-09T13:03:02.580998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246482139s","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-08-09T13:03:02.582466Z","caller":"traceutil/trace.go:171","msg":"trace[1305490852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4934; }","duration":"1.247990234s","start":"2026-08-09T13:03:01.33445Z","end":"2026-08-09T13:03:02.58244Z","steps":["trace[1305490852] 'agreement among raft nodes before linearized reading' (duration: 1.246268026s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.582972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.334443Z","time spent":"1.248508222s","remote":"127.0.0.1:43730","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-08-09T13:03:02.580694Z","caller":"traceutil/trace.go:171","msg":"trace[1721375257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4934; }","duration":"1.104379736s","start":"2026-08-09T13:03:01.476301Z","end":"2026-08-09T13:03:02.580681Z","steps":["trace[1721375257] 'agreement among raft nodes before linearized reading' (duration: 1.104304805s)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:02.583407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-09T13:03:01.476285Z","time spent":"1.107107479s","remote":"127.0.0.1:43476","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-08-09T13:03:02.892947Z","caller":"traceutil/trace.go:171","msg":"trace[1175593721] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"223.634766ms","start":"2026-08-09T13:03:02.669276Z","end":"2026-08-09T13:03:02.892911Z","steps":["trace[1175593721] 'process raft request' (duration: 166.1041ms)","trace[1175593721] 'compare' (duration: 57.281593ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:02.892954Z","caller":"traceutil/trace.go:171","msg":"trace[1132943321] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5125; }","duration":"221.327131ms","start":"2026-08-09T13:03:02.671595Z","end":"2026-08-09T13:03:02.892922Z","steps":["trace[1132943321] 'read index received' (duration: 163.708922ms)","trace[1132943321] 'applied index is now lower than readState.Index' (duration: 57.615969ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:03:02.893321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.699307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-08-09T13:03:02.89338Z","caller":"traceutil/trace.go:171","msg":"trace[388035574] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4941; }","duration":"221.782778ms","start":"2026-08-09T13:03:02.671583Z","end":"2026-08-09T13:03:02.893366Z","steps":["trace[388035574] 'agreement among raft nodes before linearized reading' (duration: 221.442962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:03.208708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.917007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:03:03.209237Z","caller":"traceutil/trace.go:171","msg":"trace[435120808] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"211.659784ms","start":"2026-08-09T13:03:02.99755Z","end":"2026-08-09T13:03:03.20921Z","steps":["trace[435120808] 'process raft request' (duration: 107.140628ms)","trace[435120808] 'compare' (duration: 103.103114ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:03:03.209306Z","caller":"traceutil/trace.go:171","msg":"trace[541380177] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"172.330854ms","start":"2026-08-09T13:03:03.03696Z","end":"2026-08-09T13:03:03.209291Z","steps":["trace[541380177] 'process raft request' (duration: 172.269293ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:03:03.209555Z","caller":"traceutil/trace.go:171","msg":"trace[629810538] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"207.353346ms","start":"2026-08-09T13:03:03.002179Z","end":"2026-08-09T13:03:03.209532Z","steps":["trace[629810538] 'process raft request' (duration: 206.645475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:03:36.706542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.540077ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-09T13:04:18.405024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2893} {"level":"info","ts":"2026-08-09T13:04:18.485611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2893,"took":"79.059279ms","hash":2604722727,"current-db-size-bytes":53338112,"current-db-size":"53 MB","current-db-size-in-use-bytes":29114368,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-09T13:04:18.485688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2604722727,"revision":2893,"compact-revision":-1} {"level":"warn","ts":"2026-08-09T13:06:43.957509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.761083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-09T13:06:43.957599Z","caller":"traceutil/trace.go:171","msg":"trace[1263762299] linearizableReadLoop","detail":"{readStateIndex:6787; appliedIndex:6786; }","duration":"247.962397ms","start":"2026-08-09T13:06:43.709621Z","end":"2026-08-09T13:06:43.957584Z","steps":["trace[1263762299] 'read index received' (duration: 96.011257ms)","trace[1263762299] 'applied index is now lower than readState.Index' (duration: 151.950129ms)"],"step_count":2} {"level":"info","ts":"2026-08-09T13:06:43.957694Z","caller":"traceutil/trace.go:171","msg":"trace[1033152348] transaction","detail":"{read_only:false; response_revision:6549; number_of_response:1; }","duration":"272.245325ms","start":"2026-08-09T13:06:43.68544Z","end":"2026-08-09T13:06:43.957685Z","steps":["trace[1033152348] 'process raft request' (duration: 120.228943ms)","trace[1033152348] 'compare' (duration: 151.6436ms)"],"step_count":2} {"level":"warn","ts":"2026-08-09T13:06:43.957816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.124624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-09T13:06:43.95792Z","caller":"traceutil/trace.go:171","msg":"trace[1036410491] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:6549; }","duration":"225.244248ms","start":"2026-08-09T13:06:43.732656Z","end":"2026-08-09T13:06:43.957901Z","steps":["trace[1036410491] 'agreement among raft nodes before linearized reading' (duration: 225.085323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:06:43.958201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.531317ms","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-08-09T13:06:43.958224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.491053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-08-09T13:06:43.958281Z","caller":"traceutil/trace.go:171","msg":"trace[699135123] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6549; }","duration":"204.566066ms","start":"2026-08-09T13:06:43.753698Z","end":"2026-08-09T13:06:43.958264Z","steps":["trace[699135123] 'agreement among raft nodes before linearized reading' (duration: 204.410331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:06:43.958317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.268202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-09T13:06:43.958349Z","caller":"traceutil/trace.go:171","msg":"trace[1201410014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6549; }","duration":"217.323724ms","start":"2026-08-09T13:06:43.741017Z","end":"2026-08-09T13:06:43.958341Z","steps":["trace[1201410014] 'agreement among raft nodes before linearized reading' (duration: 217.243891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:06:43.958492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.876786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-09T13:06:43.958523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.71198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-09T13:06:43.958534Z","caller":"traceutil/trace.go:171","msg":"trace[330800147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6549; }","duration":"248.954588ms","start":"2026-08-09T13:06:43.709569Z","end":"2026-08-09T13:06:43.958523Z","steps":["trace[330800147] 'agreement among raft nodes before linearized reading' (duration: 248.857065ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:06:43.958552Z","caller":"traceutil/trace.go:171","msg":"trace[1672845006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6549; }","duration":"219.771832ms","start":"2026-08-09T13:06:43.738771Z","end":"2026-08-09T13:06:43.958543Z","steps":["trace[1672845006] 'agreement among raft nodes before linearized reading' (duration: 219.69582ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:06:43.958242Z","caller":"traceutil/trace.go:171","msg":"trace[1143785462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6549; }","duration":"215.589589ms","start":"2026-08-09T13:06:43.74264Z","end":"2026-08-09T13:06:43.958229Z","steps":["trace[1143785462] 'agreement among raft nodes before linearized reading' (duration: 215.470725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-09T13:06:43.958999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.920563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4857"} {"level":"info","ts":"2026-08-09T13:06:43.959086Z","caller":"traceutil/trace.go:171","msg":"trace[1317906866] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6549; }","duration":"169.081318ms","start":"2026-08-09T13:06:43.789985Z","end":"2026-08-09T13:06:43.959066Z","steps":["trace[1317906866] 'agreement among raft nodes before linearized reading' (duration: 168.83209ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:06:45.467985Z","caller":"traceutil/trace.go:171","msg":"trace[1378793363] transaction","detail":"{read_only:false; response_revision:6556; number_of_response:1; }","duration":"132.131487ms","start":"2026-08-09T13:06:45.335831Z","end":"2026-08-09T13:06:45.467962Z","steps":["trace[1378793363] 'process raft request' (duration: 131.973892ms)"],"step_count":1} {"level":"info","ts":"2026-08-09T13:09:18.413035Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5561} {"level":"info","ts":"2026-08-09T13:09:18.530765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5561,"took":"115.762285ms","hash":4027908887,"current-db-size-bytes":53338112,"current-db-size":"53 MB","current-db-size-in-use-bytes":18382848,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-09T13:09:18.530826Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4027908887,"revision":5561,"compact-revision":2893}