{"level":"warn","ts":"2026-05-09T12:49:54.334819Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:49:54.334905Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.48:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.48:2380","--initial-cluster=instance=https://199.19.213.48:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.48:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.48:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T12:49:54.334962Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:49:54.33497Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-05-09T12:49:54.334986Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T12:49:54.33557Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"]} {"level":"info","ts":"2026-05-09T12:49:54.335636Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.48:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T12:49:54.339888Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.061198ms"} {"level":"info","ts":"2026-05-09T12:49:54.350076Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7317567213c7319d","cluster-id":"d22ed1e1757a8527"} {"level":"info","ts":"2026-05-09T12:49:54.350159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T12:49:54.35018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 0"} {"level":"info","ts":"2026-05-09T12:49:54.350193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7317567213c7319d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T12:49:54.350204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 1"} {"level":"info","ts":"2026-05-09T12:49:54.350243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"warn","ts":"2026-05-09T12:49:54.361427Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T12:49:54.362707Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T12:49:54.363908Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T12:49:54.366362Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7317567213c7319d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T12:49:54.366947Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7317567213c7319d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T12:49:54.367104Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T12:49:54.36716Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T12:49:54.367201Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T12:49:54.367496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"info","ts":"2026-05-09T12:49:54.370043Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","added-peer-id":"7317567213c7319d","added-peer-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-05-09T12:49:54.37221Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T12:49:54.372404Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-05-09T12:49:54.372427Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-05-09T12:49:54.373606Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7317567213c7319d","initial-advertise-peer-urls":["https://199.19.213.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T12:49:54.373638Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T12:49:54.551817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T12:49:54.55188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T12:49:54.55192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgPreVoteResp from 7317567213c7319d at term 1"} {"level":"info","ts":"2026-05-09T12:49:54.551947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became candidate at term 2"} {"level":"info","ts":"2026-05-09T12:49:54.551964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgVoteResp from 7317567213c7319d at term 2"} {"level":"info","ts":"2026-05-09T12:49:54.551987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became leader at term 2"} {"level":"info","ts":"2026-05-09T12:49:54.552007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7317567213c7319d elected leader 7317567213c7319d at term 2"} {"level":"info","ts":"2026-05-09T12:49:54.552941Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7317567213c7319d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.48:2379]}","request-path":"/0/members/7317567213c7319d/attributes","cluster-id":"d22ed1e1757a8527","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T12:49:54.552989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:49:54.553352Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:49:54.553637Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T12:49:54.55367Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T12:49:54.554218Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:49:54.554376Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:49:54.557622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T12:49:54.557802Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:49:54.553413Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:49:54.560898Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.48:2379"} {"level":"info","ts":"2026-05-09T12:50:00.581559Z","caller":"traceutil/trace.go:171","msg":"trace[1075290075] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:285; }","duration":"372.077413ms","start":"2026-05-09T12:50:00.209455Z","end":"2026-05-09T12:50:00.581532Z","steps":["trace[1075290075] 'read index received' (duration: 29.694594ms)","trace[1075290075] 'applied index is now lower than readState.Index' (duration: 342.381659ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:00.581633Z","caller":"traceutil/trace.go:171","msg":"trace[334457371] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"374.639314ms","start":"2026-05-09T12:50:00.206964Z","end":"2026-05-09T12:50:00.581603Z","steps":["trace[334457371] 'process raft request' (duration: 374.43451ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:00.581744Z","caller":"traceutil/trace.go:171","msg":"trace[644155287] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"373.820031ms","start":"2026-05-09T12:50:00.207805Z","end":"2026-05-09T12:50:00.581625Z","steps":["trace[644155287] 'process raft request' (duration: 373.694539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:00.581796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.300017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-09T12:50:00.581868Z","caller":"traceutil/trace.go:171","msg":"trace[765706845] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:277; }","duration":"372.4407ms","start":"2026-05-09T12:50:00.209407Z","end":"2026-05-09T12:50:00.581848Z","steps":["trace[765706845] 'agreement among raft nodes before linearized reading' (duration: 372.266376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:00.581921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:00.209382Z","time spent":"372.52722ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-05-09T12:50:00.582261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:00.206952Z","time spent":"374.753977ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T12:50:00.582375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:00.207794Z","time spent":"373.990474ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T12:50:00.811274Z","caller":"traceutil/trace.go:171","msg":"trace[64378661] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"570.138755ms","start":"2026-05-09T12:50:00.241109Z","end":"2026-05-09T12:50:00.811248Z","steps":["trace[64378661] 'process raft request' (duration: 569.981712ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:00.811299Z","caller":"traceutil/trace.go:171","msg":"trace[1166251063] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:289; }","duration":"229.622248ms","start":"2026-05-09T12:50:00.581648Z","end":"2026-05-09T12:50:00.81127Z","steps":["trace[1166251063] 'read index received' (duration: 229.612558ms)","trace[1166251063] 'applied index is now lower than readState.Index' (duration: 7.58µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:00.811489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.58329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-05-09T12:50:00.811543Z","caller":"traceutil/trace.go:171","msg":"trace[1674878090] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:278; }","duration":"571.65963ms","start":"2026-05-09T12:50:00.239868Z","end":"2026-05-09T12:50:00.811527Z","steps":["trace[1674878090] 'agreement among raft nodes before linearized reading' (duration: 571.557709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:00.81159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:00.239858Z","time spent":"571.720052ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3997,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-09T12:50:00.811486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.218064ms","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-05-09T12:50:00.81163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.267366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4660"} {"level":"info","ts":"2026-05-09T12:50:00.811658Z","caller":"traceutil/trace.go:171","msg":"trace[1021585634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:278; }","duration":"443.451957ms","start":"2026-05-09T12:50:00.36819Z","end":"2026-05-09T12:50:00.811642Z","steps":["trace[1021585634] 'agreement among raft nodes before linearized reading' (duration: 443.162283ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:00.811705Z","caller":"traceutil/trace.go:171","msg":"trace[1754981480] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:278; }","duration":"228.387598ms","start":"2026-05-09T12:50:00.583297Z","end":"2026-05-09T12:50:00.811685Z","steps":["trace[1754981480] 'agreement among raft nodes before linearized reading' (duration: 228.232074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:00.811712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:00.368168Z","time spent":"443.52769ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T12:50:00.81178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:00.241088Z","time spent":"570.276168ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:00.811914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.180382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-05-09T12:50:00.811992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.460398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-05-09T12:50:00.812094Z","caller":"traceutil/trace.go:171","msg":"trace[1200259880] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:278; }","duration":"228.60705ms","start":"2026-05-09T12:50:00.58346Z","end":"2026-05-09T12:50:00.812067Z","steps":["trace[1200259880] 'agreement among raft nodes before linearized reading' (duration: 228.382247ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:00.812123Z","caller":"traceutil/trace.go:171","msg":"trace[1067604009] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:278; }","duration":"226.247062ms","start":"2026-05-09T12:50:00.585715Z","end":"2026-05-09T12:50:00.811962Z","steps":["trace[1067604009] 'agreement among raft nodes before linearized reading' (duration: 226.047669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:01.050833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.481547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:50:01.050939Z","caller":"traceutil/trace.go:171","msg":"trace[864178763] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:289; }","duration":"233.353764ms","start":"2026-05-09T12:50:00.817571Z","end":"2026-05-09T12:50:01.050925Z","steps":["trace[864178763] 'read index received' (duration: 43.51051ms)","trace[864178763] 'applied index is now lower than readState.Index' (duration: 189.842194ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:50:01.051053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.477855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T12:50:01.051078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.934067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-09T12:50:01.051094Z","caller":"traceutil/trace.go:171","msg":"trace[1564585068] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:279; }","duration":"233.549327ms","start":"2026-05-09T12:50:00.817534Z","end":"2026-05-09T12:50:01.051084Z","steps":["trace[1564585068] 'agreement among raft nodes before linearized reading' (duration: 233.485255ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:01.051007Z","caller":"traceutil/trace.go:171","msg":"trace[100495149] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"233.665979ms","start":"2026-05-09T12:50:00.817303Z","end":"2026-05-09T12:50:01.050969Z","steps":["trace[100495149] 'process raft request' (duration: 43.841786ms)","trace[100495149] 'compare' (duration: 189.346205ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:01.051119Z","caller":"traceutil/trace.go:171","msg":"trace[683385964] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:279; }","duration":"233.001018ms","start":"2026-05-09T12:50:00.818108Z","end":"2026-05-09T12:50:01.051109Z","steps":["trace[683385964] 'agreement among raft nodes before linearized reading' (duration: 232.934356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:01.051152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.54847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-05-09T12:50:01.051417Z","caller":"traceutil/trace.go:171","msg":"trace[1284894997] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:279; }","duration":"232.816595ms","start":"2026-05-09T12:50:00.818589Z","end":"2026-05-09T12:50:01.051405Z","steps":["trace[1284894997] 'agreement among raft nodes before linearized reading' (duration: 232.54427ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:22.095917Z","caller":"traceutil/trace.go:171","msg":"trace[2054337029] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"133.908459ms","start":"2026-05-09T12:50:21.961988Z","end":"2026-05-09T12:50:22.095897Z","steps":["trace[2054337029] 'process raft request' (duration: 133.804578ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:25.706203Z","caller":"traceutil/trace.go:171","msg":"trace[1201438941] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"110.54697ms","start":"2026-05-09T12:50:25.595625Z","end":"2026-05-09T12:50:25.706172Z","steps":["trace[1201438941] 'process raft request' (duration: 110.360738ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:32.497219Z","caller":"traceutil/trace.go:171","msg":"trace[1078565333] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"213.446448ms","start":"2026-05-09T12:50:32.283748Z","end":"2026-05-09T12:50:32.497194Z","steps":["trace[1078565333] 'read index received' (duration: 213.281166ms)","trace[1078565333] 'applied index is now lower than readState.Index' (duration: 164.232µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:32.497279Z","caller":"traceutil/trace.go:171","msg":"trace[2007586804] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"358.958269ms","start":"2026-05-09T12:50:32.138289Z","end":"2026-05-09T12:50:32.497247Z","steps":["trace[2007586804] 'process raft request' (duration: 358.762506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:32.497461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.693801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T12:50:32.497503Z","caller":"traceutil/trace.go:171","msg":"trace[202978693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"213.780163ms","start":"2026-05-09T12:50:32.283713Z","end":"2026-05-09T12:50:32.497493Z","steps":["trace[202978693] 'agreement among raft nodes before linearized reading' (duration: 213.62243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:32.497541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:50:32.13824Z","time spent":"359.215053ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:50:32.498253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.125249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-88cfz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-09T12:50:32.498334Z","caller":"traceutil/trace.go:171","msg":"trace[700622022] range","detail":"{range_begin:/registry/pods/kube-system/cilium-88cfz; range_end:; response_count:1; response_revision:590; }","duration":"209.239559ms","start":"2026-05-09T12:50:32.289069Z","end":"2026-05-09T12:50:32.498308Z","steps":["trace[700622022] 'agreement among raft nodes before linearized reading' (duration: 209.101407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:32.498649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.434082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T12:50:32.498686Z","caller":"traceutil/trace.go:171","msg":"trace[852004860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"214.511324ms","start":"2026-05-09T12:50:32.284164Z","end":"2026-05-09T12:50:32.498675Z","steps":["trace[852004860] 'agreement among raft nodes before linearized reading' (duration: 214.403092ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:32.69904Z","caller":"traceutil/trace.go:171","msg":"trace[1983803578] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"197.313023ms","start":"2026-05-09T12:50:32.501689Z","end":"2026-05-09T12:50:32.699002Z","steps":["trace[1983803578] 'process raft request' (duration: 164.543686ms)","trace[1983803578] 'compare' (duration: 32.268079ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:50:32.699171Z","caller":"traceutil/trace.go:171","msg":"trace[452204426] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"197.182431ms","start":"2026-05-09T12:50:32.501897Z","end":"2026-05-09T12:50:32.69908Z","steps":["trace[452204426] 'process raft request' (duration: 196.858696ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:32.699112Z","caller":"traceutil/trace.go:171","msg":"trace[2363201] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"187.334324ms","start":"2026-05-09T12:50:32.511766Z","end":"2026-05-09T12:50:32.6991Z","steps":["trace[2363201] 'process raft request' (duration: 187.02165ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:47.689757Z","caller":"traceutil/trace.go:171","msg":"trace[903549901] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"292.930168ms","start":"2026-05-09T12:50:47.396806Z","end":"2026-05-09T12:50:47.689736Z","steps":["trace[903549901] 'process raft request' (duration: 284.18418ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:47.727098Z","caller":"traceutil/trace.go:171","msg":"trace[45519679] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"221.699461ms","start":"2026-05-09T12:50:47.505371Z","end":"2026-05-09T12:50:47.727071Z","steps":["trace[45519679] 'process raft request' (duration: 221.417017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:50:47.727432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.141048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.48\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-09T12:50:47.727503Z","caller":"traceutil/trace.go:171","msg":"trace[1898836462] range","detail":"{range_begin:/registry/masterleases/199.19.213.48; range_end:; response_count:1; response_revision:747; }","duration":"173.26207ms","start":"2026-05-09T12:50:47.554214Z","end":"2026-05-09T12:50:47.727476Z","steps":["trace[1898836462] 'agreement among raft nodes before linearized reading' (duration: 173.094907ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:50:47.727678Z","caller":"traceutil/trace.go:171","msg":"trace[1887342207] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:772; }","duration":"172.649201ms","start":"2026-05-09T12:50:47.554285Z","end":"2026-05-09T12:50:47.726934Z","steps":["trace[1887342207] 'read index received' (duration: 126.912316ms)","trace[1887342207] 'applied index is now lower than readState.Index' (duration: 45.735375ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:02.46159Z","caller":"traceutil/trace.go:171","msg":"trace[1108399937] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"310.990965ms","start":"2026-05-09T12:52:02.150568Z","end":"2026-05-09T12:52:02.461559Z","steps":["trace[1108399937] 'read index received' (duration: 222.030919ms)","trace[1108399937] 'applied index is now lower than readState.Index' (duration: 88.958486ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:02.461709Z","caller":"traceutil/trace.go:171","msg":"trace[268142584] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"452.172541ms","start":"2026-05-09T12:52:02.00951Z","end":"2026-05-09T12:52:02.461683Z","steps":["trace[268142584] 'process raft request' (duration: 363.01808ms)","trace[268142584] 'compare' (duration: 88.880714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:52:02.46186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:02.009484Z","time spent":"452.277734ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:52:02.461902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.247092ms","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-05-09T12:52:02.461983Z","caller":"traceutil/trace.go:171","msg":"trace[1339501204] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1801; }","duration":"311.442998ms","start":"2026-05-09T12:52:02.150521Z","end":"2026-05-09T12:52:02.461964Z","steps":["trace[1339501204] 'agreement among raft nodes before linearized reading' (duration: 311.157489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:02.462028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:02.150503Z","time spent":"311.510669ms","remote":"127.0.0.1:58524","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-05-09T12:52:03.005123Z","caller":"traceutil/trace.go:171","msg":"trace[52774236] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1873; }","duration":"270.041957ms","start":"2026-05-09T12:52:02.735063Z","end":"2026-05-09T12:52:03.005105Z","steps":["trace[52774236] 'read index received' (duration: 268.849703ms)","trace[52774236] 'applied index is now lower than readState.Index' (duration: 1.191424ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:03.00515Z","caller":"traceutil/trace.go:171","msg":"trace[1763650115] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"295.723276ms","start":"2026-05-09T12:52:02.709391Z","end":"2026-05-09T12:52:03.005115Z","steps":["trace[1763650115] 'process raft request' (duration: 294.508122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:03.00537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.662183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:52:03.005487Z","caller":"traceutil/trace.go:171","msg":"trace[1347492804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1804; }","duration":"210.804606ms","start":"2026-05-09T12:52:02.794642Z","end":"2026-05-09T12:52:03.005446Z","steps":["trace[1347492804] 'agreement among raft nodes before linearized reading' (duration: 210.667222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:03.006084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.325396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cb4f6248-2a3c-481c-9d53-4d9c2173739b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-09T12:52:03.006123Z","caller":"traceutil/trace.go:171","msg":"trace[1705171875] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cb4f6248-2a3c-481c-9d53-4d9c2173739b; range_end:; response_count:1; response_revision:1804; }","duration":"271.111238ms","start":"2026-05-09T12:52:02.735002Z","end":"2026-05-09T12:52:03.006113Z","steps":["trace[1705171875] 'agreement among raft nodes before linearized reading' (duration: 270.189743ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:11.350097Z","caller":"traceutil/trace.go:171","msg":"trace[1807903224] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"149.909341ms","start":"2026-05-09T12:52:11.200161Z","end":"2026-05-09T12:52:11.35007Z","steps":["trace[1807903224] 'process raft request' (duration: 149.747897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:11.374275Z","caller":"traceutil/trace.go:171","msg":"trace[259615075] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"172.821401ms","start":"2026-05-09T12:52:11.201423Z","end":"2026-05-09T12:52:11.374244Z","steps":["trace[259615075] 'process raft request' (duration: 171.461714ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:16.302046Z","caller":"traceutil/trace.go:171","msg":"trace[1375690492] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"352.423017ms","start":"2026-05-09T12:52:15.949592Z","end":"2026-05-09T12:52:16.302015Z","steps":["trace[1375690492] 'process raft request' (duration: 352.15166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:16.302257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:15.949569Z","time spent":"352.55715ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:52:37.872683Z","caller":"traceutil/trace.go:171","msg":"trace[1048496821] linearizableReadLoop","detail":"{readStateIndex:2132; appliedIndex:2131; }","duration":"104.279166ms","start":"2026-05-09T12:52:37.768378Z","end":"2026-05-09T12:52:37.872657Z","steps":["trace[1048496821] 'read index received' (duration: 57.410296ms)","trace[1048496821] 'applied index is now lower than readState.Index' (duration: 46.86791ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:37.872825Z","caller":"traceutil/trace.go:171","msg":"trace[417489611] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"104.717308ms","start":"2026-05-09T12:52:37.768095Z","end":"2026-05-09T12:52:37.872812Z","steps":["trace[417489611] 'process raft request' (duration: 57.755995ms)","trace[417489611] 'compare' (duration: 46.697056ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:52:37.873029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.630885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-09T12:52:37.873066Z","caller":"traceutil/trace.go:171","msg":"trace[1140883070] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2053; }","duration":"104.704697ms","start":"2026-05-09T12:52:37.768353Z","end":"2026-05-09T12:52:37.873058Z","steps":["trace[1140883070] 'agreement among raft nodes before linearized reading' (duration: 104.572674ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:39.01279Z","caller":"traceutil/trace.go:171","msg":"trace[271878559] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"168.109974ms","start":"2026-05-09T12:52:38.844645Z","end":"2026-05-09T12:52:39.012755Z","steps":["trace[271878559] 'process raft request' (duration: 167.775396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:39.31327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.668613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:52:39.313458Z","caller":"traceutil/trace.go:171","msg":"trace[1524047317] linearizableReadLoop","detail":"{readStateIndex:2137; appliedIndex:2135; }","duration":"185.85135ms","start":"2026-05-09T12:52:39.127594Z","end":"2026-05-09T12:52:39.313446Z","steps":["trace[1524047317] 'read index received' (duration: 49.945391ms)","trace[1524047317] 'applied index is now lower than readState.Index' (duration: 135.905219ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:52:39.313682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.069435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T12:52:39.313723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.515792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-09T12:52:39.313742Z","caller":"traceutil/trace.go:171","msg":"trace[1457122009] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2058; }","duration":"186.171338ms","start":"2026-05-09T12:52:39.127554Z","end":"2026-05-09T12:52:39.313725Z","steps":["trace[1457122009] 'agreement among raft nodes before linearized reading' (duration: 186.032045ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:39.313748Z","caller":"traceutil/trace.go:171","msg":"trace[612418326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2058; }","duration":"148.572452ms","start":"2026-05-09T12:52:39.165169Z","end":"2026-05-09T12:52:39.313742Z","steps":["trace[612418326] 'agreement among raft nodes before linearized reading' (duration: 148.48173ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:52:39.313809Z","caller":"traceutil/trace.go:171","msg":"trace[761139329] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"297.482305ms","start":"2026-05-09T12:52:39.016299Z","end":"2026-05-09T12:52:39.313782Z","steps":["trace[761139329] 'process raft request' (duration: 161.226848ms)","trace[761139329] 'compare' (duration: 135.427967ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:39.313856Z","caller":"traceutil/trace.go:171","msg":"trace[1771244611] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"189.73831ms","start":"2026-05-09T12:52:39.124087Z","end":"2026-05-09T12:52:39.313825Z","steps":["trace[1771244611] 'process raft request' (duration: 189.27878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:39.31395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.519222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T12:52:39.313973Z","caller":"traceutil/trace.go:171","msg":"trace[1600528754] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2058; }","duration":"142.542022ms","start":"2026-05-09T12:52:39.171423Z","end":"2026-05-09T12:52:39.313965Z","steps":["trace[1600528754] 'agreement among raft nodes before linearized reading' (duration: 142.398369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:39.725976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.747413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:52:39.726238Z","caller":"traceutil/trace.go:171","msg":"trace[180155423] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2137; }","duration":"216.793348ms","start":"2026-05-09T12:52:39.50941Z","end":"2026-05-09T12:52:39.726204Z","steps":["trace[180155423] 'read index received' (duration: 22.747108ms)","trace[180155423] 'applied index is now lower than readState.Index' (duration: 194.04488ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:39.726287Z","caller":"traceutil/trace.go:171","msg":"trace[1940931246] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"407.974862ms","start":"2026-05-09T12:52:39.318248Z","end":"2026-05-09T12:52:39.726222Z","steps":["trace[1940931246] 'process raft request' (duration: 213.83709ms)","trace[1940931246] 'compare' (duration: 193.561518ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:52:39.726323Z","caller":"traceutil/trace.go:171","msg":"trace[1953494612] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"217.825143ms","start":"2026-05-09T12:52:39.508469Z","end":"2026-05-09T12:52:39.726294Z","steps":["trace[1953494612] 'process raft request' (duration: 217.632738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:39.72655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.269294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-09T12:52:39.726576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.684288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-09T12:52:39.726669Z","caller":"traceutil/trace.go:171","msg":"trace[823506009] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2060; }","duration":"195.791951ms","start":"2026-05-09T12:52:39.530862Z","end":"2026-05-09T12:52:39.726653Z","steps":["trace[823506009] 'agreement among raft nodes before linearized reading' (duration: 195.560645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:39.726678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:52:39.318228Z","time spent":"408.366471ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:52:39.726666Z","caller":"traceutil/trace.go:171","msg":"trace[1929126893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2060; }","duration":"203.379926ms","start":"2026-05-09T12:52:39.523247Z","end":"2026-05-09T12:52:39.726627Z","steps":["trace[1929126893] 'agreement among raft nodes before linearized reading' (duration: 203.167411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:52:39.726552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.117916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T12:52:39.726895Z","caller":"traceutil/trace.go:171","msg":"trace[962683105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2060; }","duration":"217.429424ms","start":"2026-05-09T12:52:39.509381Z","end":"2026-05-09T12:52:39.72681Z","steps":["trace[962683105] 'agreement among raft nodes before linearized reading' (duration: 216.970043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:18.163075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.981221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:54:18.163168Z","caller":"traceutil/trace.go:171","msg":"trace[1669387258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2693; }","duration":"368.120373ms","start":"2026-05-09T12:54:17.795025Z","end":"2026-05-09T12:54:18.163145Z","steps":["trace[1669387258] 'range keys from in-memory index tree' (duration: 367.782848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:18.163209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:17.794996Z","time spent":"368.203265ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:54:18.163418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.523312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:54:18.1635Z","caller":"traceutil/trace.go:171","msg":"trace[933875810] linearizableReadLoop","detail":"{readStateIndex:2796; appliedIndex:2795; }","duration":"333.687821ms","start":"2026-05-09T12:54:17.8298Z","end":"2026-05-09T12:54:18.163487Z","steps":["trace[933875810] 'read index received' (duration: 28.887234ms)","trace[933875810] 'applied index is now lower than readState.Index' (duration: 304.799787ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:54:18.163557Z","caller":"traceutil/trace.go:171","msg":"trace[658998640] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"342.381145ms","start":"2026-05-09T12:54:17.821167Z","end":"2026-05-09T12:54:18.163548Z","steps":["trace[658998640] 'process raft request' (duration: 37.637409ms)","trace[658998640] 'compare' (duration: 304.176016ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:54:18.163616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:17.82115Z","time spent":"342.428146ms","remote":"127.0.0.1:49434","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-05-09T12:54:18.163763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.947386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:54:18.163865Z","caller":"traceutil/trace.go:171","msg":"trace[299211281] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2694; }","duration":"334.073688ms","start":"2026-05-09T12:54:17.829773Z","end":"2026-05-09T12:54:18.163847Z","steps":["trace[299211281] 'agreement among raft nodes before linearized reading' (duration: 333.866954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:18.164084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:17.829763Z","time spent":"334.27979ms","remote":"127.0.0.1:49656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-09T12:54:18.184798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.665932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ade6d6beb2f850\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-09T12:54:18.184852Z","caller":"traceutil/trace.go:171","msg":"trace[859736394] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ade6d6beb2f850; range_end:; response_count:1; response_revision:2695; }","duration":"305.753414ms","start":"2026-05-09T12:54:17.879084Z","end":"2026-05-09T12:54:18.184838Z","steps":["trace[859736394] 'agreement among raft nodes before linearized reading' (duration: 305.485809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:18.184929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:54:17.879065Z","time spent":"305.853355ms","remote":"127.0.0.1:49226","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.18ade6d6beb2f850\" "} {"level":"info","ts":"2026-05-09T12:55:50.686388Z","caller":"traceutil/trace.go:171","msg":"trace[207583393] linearizableReadLoop","detail":"{readStateIndex:3300; appliedIndex:3299; }","duration":"100.386124ms","start":"2026-05-09T12:55:50.585976Z","end":"2026-05-09T12:55:50.686362Z","steps":["trace[207583393] 'read index received' (duration: 83.611835ms)","trace[207583393] 'applied index is now lower than readState.Index' (duration: 16.773019ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:55:50.686612Z","caller":"traceutil/trace.go:171","msg":"trace[205578962] transaction","detail":"{read_only:false; response_revision:3179; number_of_response:1; }","duration":"101.571267ms","start":"2026-05-09T12:55:50.584948Z","end":"2026-05-09T12:55:50.686519Z","steps":["trace[205578962] 'process raft request' (duration: 84.59164ms)","trace[205578962] 'compare' (duration: 16.709907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:55:50.68673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.724116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1313"} {"level":"info","ts":"2026-05-09T12:55:50.68681Z","caller":"traceutil/trace.go:171","msg":"trace[828732978] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3179; }","duration":"100.882822ms","start":"2026-05-09T12:55:50.585908Z","end":"2026-05-09T12:55:50.686791Z","steps":["trace[828732978] 'agreement among raft nodes before linearized reading' (duration: 100.602581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:50.910752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.730658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T12:55:50.910924Z","caller":"traceutil/trace.go:171","msg":"trace[336099193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3179; }","duration":"113.962527ms","start":"2026-05-09T12:55:50.796943Z","end":"2026-05-09T12:55:50.910905Z","steps":["trace[336099193] 'range keys from in-memory index tree' (duration: 113.545511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:50.910803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.781629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:55:50.911477Z","caller":"traceutil/trace.go:171","msg":"trace[888909618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3179; }","duration":"114.501667ms","start":"2026-05-09T12:55:50.796956Z","end":"2026-05-09T12:55:50.911458Z","steps":["trace[888909618] 'range keys from in-memory index tree' (duration: 113.653705ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:51.040421Z","caller":"traceutil/trace.go:171","msg":"trace[1136373682] transaction","detail":"{read_only:false; response_revision:3182; number_of_response:1; }","duration":"124.604478ms","start":"2026-05-09T12:55:50.915785Z","end":"2026-05-09T12:55:51.040389Z","steps":["trace[1136373682] 'process raft request' (duration: 124.501374ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:51.040666Z","caller":"traceutil/trace.go:171","msg":"trace[1378541079] transaction","detail":"{read_only:false; response_revision:3183; number_of_response:1; }","duration":"122.689538ms","start":"2026-05-09T12:55:50.917938Z","end":"2026-05-09T12:55:51.040628Z","steps":["trace[1378541079] 'process raft request' (duration: 122.418269ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:51.040819Z","caller":"traceutil/trace.go:171","msg":"trace[160687338] transaction","detail":"{read_only:false; response_revision:3181; number_of_response:1; }","duration":"124.670539ms","start":"2026-05-09T12:55:50.915787Z","end":"2026-05-09T12:55:51.040457Z","steps":["trace[160687338] 'process raft request' (duration: 124.367909ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:51.040855Z","caller":"traceutil/trace.go:171","msg":"trace[797914929] transaction","detail":"{read_only:false; response_revision:3184; number_of_response:1; }","duration":"119.814054ms","start":"2026-05-09T12:55:50.920957Z","end":"2026-05-09T12:55:51.040772Z","steps":["trace[797914929] 'process raft request' (duration: 119.610797ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:55.173686Z","caller":"traceutil/trace.go:171","msg":"trace[322994497] transaction","detail":"{read_only:false; response_revision:3202; number_of_response:1; }","duration":"106.279377ms","start":"2026-05-09T12:55:55.067375Z","end":"2026-05-09T12:55:55.173654Z","steps":["trace[322994497] 'process raft request' (duration: 35.772324ms)","trace[322994497] 'compare' (duration: 70.375188ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:55:55.202301Z","caller":"traceutil/trace.go:171","msg":"trace[672052635] transaction","detail":"{read_only:false; response_revision:3203; number_of_response:1; }","duration":"134.811074ms","start":"2026-05-09T12:55:55.067459Z","end":"2026-05-09T12:55:55.20227Z","steps":["trace[672052635] 'process raft request' (duration: 134.564135ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:55.202794Z","caller":"traceutil/trace.go:171","msg":"trace[1628937903] transaction","detail":"{read_only:false; response_revision:3204; number_of_response:1; }","duration":"135.169026ms","start":"2026-05-09T12:55:55.067603Z","end":"2026-05-09T12:55:55.202772Z","steps":["trace[1628937903] 'process raft request' (duration: 134.602516ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:55.202907Z","caller":"traceutil/trace.go:171","msg":"trace[1933861744] transaction","detail":"{read_only:false; response_revision:3205; number_of_response:1; }","duration":"135.056763ms","start":"2026-05-09T12:55:55.067811Z","end":"2026-05-09T12:55:55.202868Z","steps":["trace[1933861744] 'process raft request' (duration: 134.865286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:14.83102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.254051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/auth-system/\" range_end:\"/registry/secrets/auth-system0\" ","response":"range_response_count:3 size:76684"} {"level":"info","ts":"2026-05-09T12:56:14.831163Z","caller":"traceutil/trace.go:171","msg":"trace[914694087] range","detail":"{range_begin:/registry/secrets/auth-system/; range_end:/registry/secrets/auth-system0; response_count:3; response_revision:3310; }","duration":"124.385945ms","start":"2026-05-09T12:56:14.706728Z","end":"2026-05-09T12:56:14.831114Z","steps":["trace[914694087] 'range keys from in-memory index tree' (duration: 123.819835ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:37.743704Z","caller":"traceutil/trace.go:171","msg":"trace[1299791675] transaction","detail":"{read_only:false; response_revision:3516; number_of_response:1; }","duration":"104.799634ms","start":"2026-05-09T12:56:37.638866Z","end":"2026-05-09T12:56:37.743665Z","steps":["trace[1299791675] 'process raft request' (duration: 61.757592ms)","trace[1299791675] 'compare' (duration: 42.850017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:45.682652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.278255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:56:45.682948Z","caller":"traceutil/trace.go:171","msg":"trace[1042540652] transaction","detail":"{read_only:false; response_revision:3561; number_of_response:1; }","duration":"150.626414ms","start":"2026-05-09T12:56:45.532233Z","end":"2026-05-09T12:56:45.68286Z","steps":["trace[1042540652] 'process raft request' (duration: 34.998609ms)","trace[1042540652] 'compare' (duration: 114.357707ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:45.824954Z","caller":"traceutil/trace.go:171","msg":"trace[882594327] transaction","detail":"{read_only:false; response_revision:3563; number_of_response:1; }","duration":"135.489626ms","start":"2026-05-09T12:56:45.689426Z","end":"2026-05-09T12:56:45.824915Z","steps":["trace[882594327] 'process raft request' (duration: 100.377855ms)","trace[882594327] 'compare' (duration: 34.865833ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:45.825899Z","caller":"traceutil/trace.go:171","msg":"trace[1712782780] transaction","detail":"{read_only:false; response_revision:3564; number_of_response:1; }","duration":"136.213948ms","start":"2026-05-09T12:56:45.689637Z","end":"2026-05-09T12:56:45.825851Z","steps":["trace[1712782780] 'process raft request' (duration: 135.227378ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:47.906097Z","caller":"traceutil/trace.go:171","msg":"trace[221972589] linearizableReadLoop","detail":"{readStateIndex:3709; appliedIndex:3708; }","duration":"110.374328ms","start":"2026-05-09T12:56:47.795696Z","end":"2026-05-09T12:56:47.90607Z","steps":["trace[221972589] 'read index received' (duration: 14.091014ms)","trace[221972589] 'applied index is now lower than readState.Index' (duration: 96.282144ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:47.906364Z","caller":"traceutil/trace.go:171","msg":"trace[546255136] transaction","detail":"{read_only:false; response_revision:3572; number_of_response:1; }","duration":"139.135982ms","start":"2026-05-09T12:56:47.767187Z","end":"2026-05-09T12:56:47.906323Z","steps":["trace[546255136] 'process raft request' (duration: 42.631741ms)","trace[546255136] 'compare' (duration: 96.14771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:56:47.90646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.754118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:56:47.906762Z","caller":"traceutil/trace.go:171","msg":"trace[982520823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3572; }","duration":"110.854032ms","start":"2026-05-09T12:56:47.795648Z","end":"2026-05-09T12:56:47.906502Z","steps":["trace[982520823] 'agreement among raft nodes before linearized reading' (duration: 110.730798ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:51.702459Z","caller":"traceutil/trace.go:171","msg":"trace[619053861] transaction","detail":"{read_only:false; response_revision:3590; number_of_response:1; }","duration":"157.690564ms","start":"2026-05-09T12:56:51.544739Z","end":"2026-05-09T12:56:51.70243Z","steps":["trace[619053861] 'process raft request' (duration: 150.056008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:10.411873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.9327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:10.412187Z","caller":"traceutil/trace.go:171","msg":"trace[2074761307] transaction","detail":"{read_only:false; response_revision:3693; number_of_response:1; }","duration":"295.055899ms","start":"2026-05-09T12:57:10.117111Z","end":"2026-05-09T12:57:10.412167Z","steps":["trace[2074761307] 'process raft request' (duration: 294.970816ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:10.412258Z","caller":"traceutil/trace.go:171","msg":"trace[402649359] transaction","detail":"{read_only:false; response_revision:3692; number_of_response:1; }","duration":"295.160441ms","start":"2026-05-09T12:57:10.117052Z","end":"2026-05-09T12:57:10.412212Z","steps":["trace[402649359] 'process raft request' (duration: 171.83721ms)","trace[402649359] 'compare' (duration: 122.675243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:23.792258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.646237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:1596"} {"level":"info","ts":"2026-05-09T12:57:23.792511Z","caller":"traceutil/trace.go:171","msg":"trace[163627684] transaction","detail":"{read_only:false; response_revision:3765; number_of_response:1; }","duration":"208.581ms","start":"2026-05-09T12:57:23.583906Z","end":"2026-05-09T12:57:23.792487Z","steps":["trace[163627684] 'process raft request' (duration: 208.479588ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:23.792587Z","caller":"traceutil/trace.go:171","msg":"trace[744427205] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3764; }","duration":"210.408809ms","start":"2026-05-09T12:57:23.582122Z","end":"2026-05-09T12:57:23.792531Z","steps":["trace[744427205] 'process raft request' (duration: 70.389093ms)","trace[744427205] 'compare' (duration: 139.543464ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:23.823588Z","caller":"traceutil/trace.go:171","msg":"trace[240938782] transaction","detail":"{read_only:false; response_revision:3766; number_of_response:1; }","duration":"214.171863ms","start":"2026-05-09T12:57:23.609375Z","end":"2026-05-09T12:57:23.823547Z","steps":["trace[240938782] 'process raft request' (duration: 213.912216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:24.047854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.386637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:24.048308Z","caller":"traceutil/trace.go:171","msg":"trace[1930473713] transaction","detail":"{read_only:false; response_revision:3770; number_of_response:1; }","duration":"222.378344ms","start":"2026-05-09T12:57:23.825893Z","end":"2026-05-09T12:57:24.048271Z","steps":["trace[1930473713] 'process raft request' (duration: 222.088456ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:24.048312Z","caller":"traceutil/trace.go:171","msg":"trace[1510695246] transaction","detail":"{read_only:false; response_revision:3769; number_of_response:1; }","duration":"223.025212ms","start":"2026-05-09T12:57:23.825236Z","end":"2026-05-09T12:57:24.048261Z","steps":["trace[1510695246] 'process raft request' (duration: 117.121422ms)","trace[1510695246] 'compare' (duration: 104.80419ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:24.048425Z","caller":"traceutil/trace.go:171","msg":"trace[1783189945] transaction","detail":"{read_only:false; response_revision:3772; number_of_response:1; }","duration":"217.917681ms","start":"2026-05-09T12:57:23.830475Z","end":"2026-05-09T12:57:24.048393Z","steps":["trace[1783189945] 'process raft request' (duration: 217.793368ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:24.048413Z","caller":"traceutil/trace.go:171","msg":"trace[2025693823] transaction","detail":"{read_only:false; response_revision:3771; number_of_response:1; }","duration":"220.416731ms","start":"2026-05-09T12:57:23.827958Z","end":"2026-05-09T12:57:24.048374Z","steps":["trace[2025693823] 'process raft request' (duration: 220.200815ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:24.048723Z","caller":"traceutil/trace.go:171","msg":"trace[579354558] transaction","detail":"{read_only:false; number_of_response:1; response_revision:3772; }","duration":"215.174227ms","start":"2026-05-09T12:57:23.833496Z","end":"2026-05-09T12:57:24.04867Z","steps":["trace[579354558] 'process raft request' (duration: 214.826747ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:24.048767Z","caller":"traceutil/trace.go:171","msg":"trace[426082021] linearizableReadLoop","detail":"{readStateIndex:3919; appliedIndex:3914; }","duration":"188.778235ms","start":"2026-05-09T12:57:23.859975Z","end":"2026-05-09T12:57:24.048753Z","steps":["trace[426082021] 'read index received' (duration: 82.34388ms)","trace[426082021] 'applied index is now lower than readState.Index' (duration: 106.431765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:24.04895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.955151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:57:24.049001Z","caller":"traceutil/trace.go:171","msg":"trace[414891000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3772; }","duration":"189.083104ms","start":"2026-05-09T12:57:23.859904Z","end":"2026-05-09T12:57:24.048987Z","steps":["trace[414891000] 'agreement among raft nodes before linearized reading' (duration: 188.900739ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:24.049105Z","caller":"traceutil/trace.go:171","msg":"trace[1800162147] transaction","detail":"{read_only:false; response_revision:3773; number_of_response:1; }","duration":"187.358737ms","start":"2026-05-09T12:57:23.861727Z","end":"2026-05-09T12:57:24.049086Z","steps":["trace[1800162147] 'process raft request' (duration: 186.953176ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:27.530145Z","caller":"traceutil/trace.go:171","msg":"trace[596534200] transaction","detail":"{read_only:false; response_revision:3832; number_of_response:1; }","duration":"115.676402ms","start":"2026-05-09T12:57:27.414422Z","end":"2026-05-09T12:57:27.530098Z","steps":["trace[596534200] 'process raft request' (duration: 77.764776ms)","trace[596534200] 'compare' (duration: 37.30528ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:27.530778Z","caller":"traceutil/trace.go:171","msg":"trace[701664131] transaction","detail":"{read_only:false; response_revision:3833; number_of_response:1; }","duration":"110.474301ms","start":"2026-05-09T12:57:27.42029Z","end":"2026-05-09T12:57:27.530764Z","steps":["trace[701664131] 'process raft request' (duration: 109.789132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:28.077478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.675552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:28.078052Z","caller":"traceutil/trace.go:171","msg":"trace[704853380] transaction","detail":"{read_only:false; response_revision:3843; number_of_response:1; }","duration":"152.38948ms","start":"2026-05-09T12:57:27.925644Z","end":"2026-05-09T12:57:28.078033Z","steps":["trace[704853380] 'process raft request' (duration: 151.98888ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:28.078289Z","caller":"traceutil/trace.go:171","msg":"trace[406951864] transaction","detail":"{read_only:false; response_revision:3842; number_of_response:1; }","duration":"180.041978ms","start":"2026-05-09T12:57:27.898097Z","end":"2026-05-09T12:57:28.078139Z","steps":["trace[406951864] 'process raft request' (duration: 75.561005ms)","trace[406951864] 'compare' (duration: 103.345883ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:57:41.189602Z","caller":"traceutil/trace.go:171","msg":"trace[330917506] transaction","detail":"{read_only:false; response_revision:3923; number_of_response:1; }","duration":"139.464798ms","start":"2026-05-09T12:57:41.050114Z","end":"2026-05-09T12:57:41.189579Z","steps":["trace[330917506] 'process raft request' (duration: 139.18217ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:09.608711Z","caller":"traceutil/trace.go:171","msg":"trace[1823838936] transaction","detail":"{read_only:false; response_revision:4098; number_of_response:1; }","duration":"117.211386ms","start":"2026-05-09T12:58:09.491387Z","end":"2026-05-09T12:58:09.608598Z","steps":["trace[1823838936] 'process raft request' (duration: 117.005951ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:09.609614Z","caller":"traceutil/trace.go:171","msg":"trace[251864273] transaction","detail":"{read_only:false; response_revision:4099; number_of_response:1; }","duration":"114.50466ms","start":"2026-05-09T12:58:09.495082Z","end":"2026-05-09T12:58:09.609587Z","steps":["trace[251864273] 'process raft request' (duration: 114.287774ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:17.253479Z","caller":"traceutil/trace.go:171","msg":"trace[1460687026] transaction","detail":"{read_only:false; response_revision:4502; number_of_response:1; }","duration":"124.109883ms","start":"2026-05-09T12:58:17.129313Z","end":"2026-05-09T12:58:17.253423Z","steps":["trace[1460687026] 'process raft request' (duration: 95.724179ms)","trace[1460687026] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:535; } (duration: 27.952274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:17.253781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.104858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-09T12:58:17.253893Z","caller":"traceutil/trace.go:171","msg":"trace[784235416] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4502; }","duration":"116.248361ms","start":"2026-05-09T12:58:17.137618Z","end":"2026-05-09T12:58:17.253866Z","steps":["trace[784235416] 'agreement among raft nodes before linearized reading' (duration: 115.944854ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:17.25415Z","caller":"traceutil/trace.go:171","msg":"trace[1327422575] linearizableReadLoop","detail":"{readStateIndex:4678; appliedIndex:4677; }","duration":"116.483948ms","start":"2026-05-09T12:58:17.137643Z","end":"2026-05-09T12:58:17.253387Z","steps":["trace[1327422575] 'read index received' (duration: 87.408747ms)","trace[1327422575] 'applied index is now lower than readState.Index' (duration: 28.333393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:17.27728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.682717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-9w6mj.18ade6b7203a2135\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-05-09T12:58:17.27738Z","caller":"traceutil/trace.go:171","msg":"trace[1728369732] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-9w6mj.18ade6b7203a2135; range_end:; response_count:1; response_revision:4504; }","duration":"135.81195ms","start":"2026-05-09T12:58:17.141541Z","end":"2026-05-09T12:58:17.277353Z","steps":["trace[1728369732] 'agreement among raft nodes before linearized reading' (duration: 135.526304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:17.277381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.78859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/grafana-tls\" ","response":"range_response_count:1 size:2832"} {"level":"info","ts":"2026-05-09T12:58:17.277949Z","caller":"traceutil/trace.go:171","msg":"trace[1498475071] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/grafana-tls; range_end:; response_count:1; response_revision:4504; }","duration":"134.347694ms","start":"2026-05-09T12:58:17.14355Z","end":"2026-05-09T12:58:17.277898Z","steps":["trace[1498475071] 'agreement among raft nodes before linearized reading' (duration: 133.576915ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:20.834402Z","caller":"traceutil/trace.go:171","msg":"trace[217856692] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4571; }","duration":"106.034479ms","start":"2026-05-09T12:58:20.728333Z","end":"2026-05-09T12:58:20.834367Z","steps":["trace[217856692] 'process raft request' (duration: 68.304302ms)","trace[217856692] 'compare' (duration: 37.172124ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:20.834627Z","caller":"traceutil/trace.go:171","msg":"trace[513464656] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"103.325822ms","start":"2026-05-09T12:58:20.731266Z","end":"2026-05-09T12:58:20.834592Z","steps":["trace[513464656] 'process raft request' (duration: 102.943244ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:20.835399Z","caller":"traceutil/trace.go:171","msg":"trace[1575734986] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"103.931477ms","start":"2026-05-09T12:58:20.731434Z","end":"2026-05-09T12:58:20.835366Z","steps":["trace[1575734986] 'process raft request' (duration: 103.000305ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:20.836075Z","caller":"traceutil/trace.go:171","msg":"trace[1780222891] transaction","detail":"{read_only:false; response_revision:4574; number_of_response:1; }","duration":"100.255188ms","start":"2026-05-09T12:58:20.735784Z","end":"2026-05-09T12:58:20.83604Z","steps":["trace[1780222891] 'process raft request' (duration: 98.750902ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:22.271681Z","caller":"traceutil/trace.go:171","msg":"trace[1616171820] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"155.832547ms","start":"2026-05-09T12:58:22.115833Z","end":"2026-05-09T12:58:22.271665Z","steps":["trace[1616171820] 'process raft request' (duration: 155.490109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:24.047947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.362341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:58:24.048632Z","caller":"traceutil/trace.go:171","msg":"trace[1021454779] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"258.335608ms","start":"2026-05-09T12:58:23.790261Z","end":"2026-05-09T12:58:24.048596Z","steps":["trace[1021454779] 'process raft request' (duration: 58.229629ms)","trace[1021454779] 'compare' (duration: 199.153996ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:24.048772Z","caller":"traceutil/trace.go:171","msg":"trace[1134367291] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"257.397475ms","start":"2026-05-09T12:58:23.791344Z","end":"2026-05-09T12:58:24.048741Z","steps":["trace[1134367291] 'process raft request' (duration: 256.715349ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:24.049631Z","caller":"traceutil/trace.go:171","msg":"trace[1831596445] linearizableReadLoop","detail":"{readStateIndex:4852; appliedIndex:4848; }","duration":"256.537695ms","start":"2026-05-09T12:58:23.793052Z","end":"2026-05-09T12:58:24.04959Z","steps":["trace[1831596445] 'read index received' (duration: 696.137µs)","trace[1831596445] 'applied index is now lower than readState.Index' (duration: 255.839728ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:24.049843Z","caller":"traceutil/trace.go:171","msg":"trace[1547217030] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"258.429419ms","start":"2026-05-09T12:58:23.791393Z","end":"2026-05-09T12:58:24.049822Z","steps":["trace[1547217030] 'process raft request' (duration: 257.040695ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:24.050061Z","caller":"traceutil/trace.go:171","msg":"trace[1815571672] transaction","detail":"{read_only:false; response_revision:4672; number_of_response:1; }","duration":"256.834791ms","start":"2026-05-09T12:58:23.793211Z","end":"2026-05-09T12:58:24.050046Z","steps":["trace[1815571672] 'process raft request' (duration: 256.257798ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:24.050175Z","caller":"traceutil/trace.go:171","msg":"trace[210795531] transaction","detail":"{read_only:false; response_revision:4671; number_of_response:1; }","duration":"258.739687ms","start":"2026-05-09T12:58:23.791428Z","end":"2026-05-09T12:58:24.050168Z","steps":["trace[210795531] 'process raft request' (duration: 257.881916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:24.050459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.389215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-09T12:58:24.050504Z","caller":"traceutil/trace.go:171","msg":"trace[153937758] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4672; }","duration":"257.477236ms","start":"2026-05-09T12:58:23.793013Z","end":"2026-05-09T12:58:24.050491Z","steps":["trace[153937758] 'agreement among raft nodes before linearized reading' (duration: 257.21269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:24.055708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.734255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:24.055715Z","caller":"traceutil/trace.go:171","msg":"trace[992705675] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"260.647333ms","start":"2026-05-09T12:58:23.795009Z","end":"2026-05-09T12:58:24.055656Z","steps":["trace[992705675] 'process raft request' (duration: 260.469219ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:24.055736Z","caller":"traceutil/trace.go:171","msg":"trace[325185645] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"254.341332ms","start":"2026-05-09T12:58:23.801351Z","end":"2026-05-09T12:58:24.055692Z","steps":["trace[325185645] 'process raft request' (duration: 254.223389ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:24.055781Z","caller":"traceutil/trace.go:171","msg":"trace[1695968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4674; }","duration":"260.828249ms","start":"2026-05-09T12:58:23.794935Z","end":"2026-05-09T12:58:24.055763Z","steps":["trace[1695968] 'agreement among raft nodes before linearized reading' (duration: 260.698226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:24.056095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.651126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-qn42m.18ade712a77dbdd7\" ","response":"range_response_count:1 size:832"} {"level":"info","ts":"2026-05-09T12:58:24.056203Z","caller":"traceutil/trace.go:171","msg":"trace[366855434] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-qn42m.18ade712a77dbdd7; range_end:; response_count:1; response_revision:4674; }","duration":"189.82416ms","start":"2026-05-09T12:58:23.866355Z","end":"2026-05-09T12:58:24.056179Z","steps":["trace[366855434] 'agreement among raft nodes before linearized reading' (duration: 189.516152ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:25.463267Z","caller":"traceutil/trace.go:171","msg":"trace[244477485] transaction","detail":"{read_only:false; response_revision:4710; number_of_response:1; }","duration":"167.674252ms","start":"2026-05-09T12:58:25.295556Z","end":"2026-05-09T12:58:25.46323Z","steps":["trace[244477485] 'process raft request' (duration: 167.422746ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:25.465709Z","caller":"traceutil/trace.go:171","msg":"trace[461273814] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"166.441283ms","start":"2026-05-09T12:58:25.299253Z","end":"2026-05-09T12:58:25.465694Z","steps":["trace[461273814] 'process raft request' (duration: 166.063255ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:25.636619Z","caller":"traceutil/trace.go:171","msg":"trace[924530814] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"154.66514ms","start":"2026-05-09T12:58:25.48192Z","end":"2026-05-09T12:58:25.636585Z","steps":["trace[924530814] 'process raft request' (duration: 154.564607ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:25.63663Z","caller":"traceutil/trace.go:171","msg":"trace[2088226031] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"155.742655ms","start":"2026-05-09T12:58:25.480823Z","end":"2026-05-09T12:58:25.636566Z","steps":["trace[2088226031] 'process raft request' (duration: 152.059866ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:31.911263Z","caller":"traceutil/trace.go:171","msg":"trace[1032339441] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"418.81327ms","start":"2026-05-09T12:58:31.492428Z","end":"2026-05-09T12:58:31.911241Z","steps":["trace[1032339441] 'process raft request' (duration: 418.599555ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:31.91117Z","caller":"traceutil/trace.go:171","msg":"trace[2193087] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4957; }","duration":"415.744907ms","start":"2026-05-09T12:58:31.495407Z","end":"2026-05-09T12:58:31.911152Z","steps":["trace[2193087] 'read index received' (duration: 415.607324ms)","trace[2193087] 'applied index is now lower than readState.Index' (duration: 136.813µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:31.911438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:31.492397Z","time spent":"418.937674ms","remote":"127.0.0.1:49434","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-05-09T12:58:31.911581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.133616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-09T12:58:31.911676Z","caller":"traceutil/trace.go:171","msg":"trace[755762006] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4774; }","duration":"416.286909ms","start":"2026-05-09T12:58:31.495363Z","end":"2026-05-09T12:58:31.91165Z","steps":["trace[755762006] 'agreement among raft nodes before linearized reading' (duration: 415.974853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:31.911744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:31.495338Z","time spent":"416.398712ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T12:58:31.951607Z","caller":"traceutil/trace.go:171","msg":"trace[2035044140] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"232.978443ms","start":"2026-05-09T12:58:31.718587Z","end":"2026-05-09T12:58:31.951565Z","steps":["trace[2035044140] 'process raft request' (duration: 232.514462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:31.952087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.392274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:58:31.952152Z","caller":"traceutil/trace.go:171","msg":"trace[810399344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4776; }","duration":"107.522637ms","start":"2026-05-09T12:58:31.844612Z","end":"2026-05-09T12:58:31.952135Z","steps":["trace[810399344] 'agreement among raft nodes before linearized reading' (duration: 107.247531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:32.75344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.527098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-09T12:58:32.753663Z","caller":"traceutil/trace.go:171","msg":"trace[575753995] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4802; }","duration":"228.768784ms","start":"2026-05-09T12:58:32.524868Z","end":"2026-05-09T12:58:32.753637Z","steps":["trace[575753995] 'range keys from in-memory index tree' (duration: 228.352683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:32.75406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.268154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-09T12:58:32.754142Z","caller":"traceutil/trace.go:171","msg":"trace[960277180] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4802; }","duration":"229.399039ms","start":"2026-05-09T12:58:32.524725Z","end":"2026-05-09T12:58:32.754124Z","steps":["trace[960277180] 'range keys from in-memory index tree' (duration: 228.799054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:32.75447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.777413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:58:32.7547Z","caller":"traceutil/trace.go:171","msg":"trace[1831484192] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"137.18568ms","start":"2026-05-09T12:58:32.6175Z","end":"2026-05-09T12:58:32.754686Z","steps":["trace[1831484192] 'process raft request' (duration: 137.127489ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:32.754698Z","caller":"traceutil/trace.go:171","msg":"trace[1772744088] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"229.215993ms","start":"2026-05-09T12:58:32.525446Z","end":"2026-05-09T12:58:32.754662Z","steps":["trace[1772744088] 'process raft request' (duration: 229.101151ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:32.75486Z","caller":"traceutil/trace.go:171","msg":"trace[11327990] linearizableReadLoop","detail":"{readStateIndex:4988; appliedIndex:4986; }","duration":"229.261845ms","start":"2026-05-09T12:58:32.52559Z","end":"2026-05-09T12:58:32.754852Z","steps":["trace[11327990] 'read index received' (duration: 16.050203ms)","trace[11327990] 'applied index is now lower than readState.Index' (duration: 213.210722ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:32.754851Z","caller":"traceutil/trace.go:171","msg":"trace[1673436250] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"229.652095ms","start":"2026-05-09T12:58:32.525177Z","end":"2026-05-09T12:58:32.754829Z","steps":["trace[1673436250] 'process raft request' (duration: 16.451182ms)","trace[1673436250] 'compare' (duration: 211.897282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:32.754974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.395429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-09T12:58:32.756256Z","caller":"traceutil/trace.go:171","msg":"trace[1030418345] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4805; }","duration":"229.970172ms","start":"2026-05-09T12:58:32.525539Z","end":"2026-05-09T12:58:32.755509Z","steps":["trace[1030418345] 'agreement among raft nodes before linearized reading' (duration: 229.347547ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:32.979503Z","caller":"traceutil/trace.go:171","msg":"trace[1152969166] linearizableReadLoop","detail":"{readStateIndex:4995; appliedIndex:4994; }","duration":"129.820655ms","start":"2026-05-09T12:58:32.849643Z","end":"2026-05-09T12:58:32.979464Z","steps":["trace[1152969166] 'read index received' (duration: 33.933709ms)","trace[1152969166] 'applied index is now lower than readState.Index' (duration: 95.886116ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:32.979998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.16426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:32.980053Z","caller":"traceutil/trace.go:171","msg":"trace[1744914321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4811; }","duration":"150.273782ms","start":"2026-05-09T12:58:32.829763Z","end":"2026-05-09T12:58:32.980037Z","steps":["trace[1744914321] 'agreement among raft nodes before linearized reading' (duration: 150.132829ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:32.979987Z","caller":"traceutil/trace.go:171","msg":"trace[947015329] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"147.598129ms","start":"2026-05-09T12:58:32.832302Z","end":"2026-05-09T12:58:32.9799Z","steps":["trace[947015329] 'process raft request' (duration: 51.441926ms)","trace[947015329] 'compare' (duration: 95.528927ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:32.980286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.904815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T12:58:32.980306Z","caller":"traceutil/trace.go:171","msg":"trace[72010495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4811; }","duration":"131.936875ms","start":"2026-05-09T12:58:32.848363Z","end":"2026-05-09T12:58:32.9803Z","steps":["trace[72010495] 'agreement among raft nodes before linearized reading' (duration: 131.832163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:32.980729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.122863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"warn","ts":"2026-05-09T12:58:32.980732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.649703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-09T12:58:32.980672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.482409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:32.980785Z","caller":"traceutil/trace.go:171","msg":"trace[32964091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4811; }","duration":"132.804756ms","start":"2026-05-09T12:58:32.847965Z","end":"2026-05-09T12:58:32.98077Z","steps":["trace[32964091] 'agreement among raft nodes before linearized reading' (duration: 132.512779ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:32.980852Z","caller":"traceutil/trace.go:171","msg":"trace[770500538] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4811; }","duration":"127.679323ms","start":"2026-05-09T12:58:32.853154Z","end":"2026-05-09T12:58:32.980833Z","steps":["trace[770500538] 'agreement among raft nodes before linearized reading' (duration: 127.415327ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:32.980965Z","caller":"traceutil/trace.go:171","msg":"trace[444188024] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4811; }","duration":"125.163715ms","start":"2026-05-09T12:58:32.855584Z","end":"2026-05-09T12:58:32.980747Z","steps":["trace[444188024] 'agreement among raft nodes before linearized reading' (duration: 125.057642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:32.981318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.411017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:72 size:60099"} {"level":"info","ts":"2026-05-09T12:58:32.981366Z","caller":"traceutil/trace.go:171","msg":"trace[1726354207] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:72; response_revision:4811; }","duration":"127.50671ms","start":"2026-05-09T12:58:32.853846Z","end":"2026-05-09T12:58:32.981353Z","steps":["trace[1726354207] 'agreement among raft nodes before linearized reading' (duration: 126.973617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:33.276496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.501204ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T12:58:33.276663Z","caller":"traceutil/trace.go:171","msg":"trace[750779026] linearizableReadLoop","detail":"{readStateIndex:5000; appliedIndex:4996; }","duration":"248.784291ms","start":"2026-05-09T12:58:33.027854Z","end":"2026-05-09T12:58:33.276638Z","steps":["trace[750779026] 'read index received' (duration: 7.410427ms)","trace[750779026] 'applied index is now lower than readState.Index' (duration: 241.371334ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:33.276763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.489837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:33.276836Z","caller":"traceutil/trace.go:171","msg":"trace[297783970] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4815; }","duration":"288.592539ms","start":"2026-05-09T12:58:32.988226Z","end":"2026-05-09T12:58:33.276819Z","steps":["trace[297783970] 'agreement among raft nodes before linearized reading' (duration: 288.442135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:33.276914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.166903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-05-09T12:58:33.276918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.921423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T12:58:33.277008Z","caller":"traceutil/trace.go:171","msg":"trace[1813261727] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:4815; }","duration":"247.278516ms","start":"2026-05-09T12:58:33.029707Z","end":"2026-05-09T12:58:33.276986Z","steps":["trace[1813261727] 'agreement among raft nodes before linearized reading' (duration: 247.099642ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:33.277023Z","caller":"traceutil/trace.go:171","msg":"trace[1460229622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4815; }","duration":"275.057267ms","start":"2026-05-09T12:58:33.001942Z","end":"2026-05-09T12:58:33.277Z","steps":["trace[1460229622] 'agreement among raft nodes before linearized reading' (duration: 274.713569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:33.277059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.110211ms","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":"warn","ts":"2026-05-09T12:58:33.277095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.20288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T12:58:33.277149Z","caller":"traceutil/trace.go:171","msg":"trace[42305750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4815; }","duration":"191.277823ms","start":"2026-05-09T12:58:33.085851Z","end":"2026-05-09T12:58:33.277129Z","steps":["trace[42305750] 'agreement among raft nodes before linearized reading' (duration: 191.146379ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:33.27716Z","caller":"traceutil/trace.go:171","msg":"trace[22140941] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4815; }","duration":"289.270706ms","start":"2026-05-09T12:58:32.987867Z","end":"2026-05-09T12:58:33.277138Z","steps":["trace[22140941] 'agreement among raft nodes before linearized reading' (duration: 288.933768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:33.276936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.853096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-09T12:58:33.277313Z","caller":"traceutil/trace.go:171","msg":"trace[2125195488] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4815; }","duration":"271.283597ms","start":"2026-05-09T12:58:33.006012Z","end":"2026-05-09T12:58:33.277296Z","steps":["trace[2125195488] 'agreement among raft nodes before linearized reading' (duration: 270.629322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:33.277814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.32305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2195922"} {"level":"info","ts":"2026-05-09T12:58:33.277931Z","caller":"traceutil/trace.go:171","msg":"trace[783415629] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4815; }","duration":"261.481964ms","start":"2026-05-09T12:58:33.016423Z","end":"2026-05-09T12:58:33.277905Z","steps":["trace[783415629] 'agreement among raft nodes before linearized reading' (duration: 260.235724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.296618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575187456959895934,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T12:58:35.368023Z","caller":"traceutil/trace.go:171","msg":"trace[2065578508] transaction","detail":"{read_only:false; response_revision:4865; number_of_response:1; }","duration":"606.610081ms","start":"2026-05-09T12:58:34.761386Z","end":"2026-05-09T12:58:35.367996Z","steps":["trace[2065578508] 'process raft request' (duration: 606.423408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.368393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:34.761364Z","time spent":"606.815847ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:58:35.4779Z","caller":"traceutil/trace.go:171","msg":"trace[1502376032] linearizableReadLoop","detail":"{readStateIndex:5051; appliedIndex:5049; }","duration":"681.676522ms","start":"2026-05-09T12:58:34.796167Z","end":"2026-05-09T12:58:35.477843Z","steps":["trace[1502376032] 'read index received' (duration: 571.61397ms)","trace[1502376032] 'applied index is now lower than readState.Index' (duration: 110.061522ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:35.47819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.009479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:35.478207Z","caller":"traceutil/trace.go:171","msg":"trace[2058522154] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"368.085814ms","start":"2026-05-09T12:58:35.110034Z","end":"2026-05-09T12:58:35.47812Z","steps":["trace[2058522154] 'process raft request' (duration: 309.637797ms)","trace[2058522154] 'compare' (duration: 57.895534ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:35.478281Z","caller":"traceutil/trace.go:171","msg":"trace[886935046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4866; }","duration":"682.157134ms","start":"2026-05-09T12:58:34.796105Z","end":"2026-05-09T12:58:35.478262Z","steps":["trace[886935046] 'agreement among raft nodes before linearized reading' (duration: 682.001611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.478333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:34.796076Z","time spent":"682.250206ms","remote":"127.0.0.1:49158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T12:58:35.478474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:35.110007Z","time spent":"368.309389ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:58:35.478824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.323423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T12:58:35.478861Z","caller":"traceutil/trace.go:171","msg":"trace[1311744879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4866; }","duration":"439.403705ms","start":"2026-05-09T12:58:35.039449Z","end":"2026-05-09T12:58:35.478853Z","steps":["trace[1311744879] 'agreement among raft nodes before linearized reading' (duration: 439.234961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.478882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:35.039425Z","time spent":"439.451896ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T12:58:35.479215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.64969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-09T12:58:35.478669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.862018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T12:58:35.479434Z","caller":"traceutil/trace.go:171","msg":"trace[984979831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4866; }","duration":"188.743378ms","start":"2026-05-09T12:58:35.290667Z","end":"2026-05-09T12:58:35.47941Z","steps":["trace[984979831] 'agreement among raft nodes before linearized reading' (duration: 187.835957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.479444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.351514ms","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-05-09T12:58:35.479599Z","caller":"traceutil/trace.go:171","msg":"trace[1532430725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4866; }","duration":"190.152652ms","start":"2026-05-09T12:58:35.28943Z","end":"2026-05-09T12:58:35.479582Z","steps":["trace[1532430725] 'agreement among raft nodes before linearized reading' (duration: 189.294071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.479801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.988524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec5ad7f3-2698-4f85-affd-6fdd597fb1fd\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-09T12:58:35.47984Z","caller":"traceutil/trace.go:171","msg":"trace[741018070] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ec5ad7f3-2698-4f85-affd-6fdd597fb1fd; range_end:; response_count:1; response_revision:4866; }","duration":"532.087567ms","start":"2026-05-09T12:58:34.947741Z","end":"2026-05-09T12:58:35.479829Z","steps":["trace[741018070] 'agreement among raft nodes before linearized reading' (duration: 530.746745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.479873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:34.947712Z","time spent":"532.152478ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ec5ad7f3-2698-4f85-affd-6fdd597fb1fd\" "} {"level":"info","ts":"2026-05-09T12:58:35.479643Z","caller":"traceutil/trace.go:171","msg":"trace[927028333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4866; }","duration":"439.458627ms","start":"2026-05-09T12:58:35.040058Z","end":"2026-05-09T12:58:35.479517Z","steps":["trace[927028333] 'agreement among raft nodes before linearized reading' (duration: 438.596836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.480002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:35.040049Z","time spent":"439.939658ms","remote":"127.0.0.1:49434","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":"info","ts":"2026-05-09T12:58:35.618685Z","caller":"traceutil/trace.go:171","msg":"trace[23096736] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"133.094688ms","start":"2026-05-09T12:58:35.485554Z","end":"2026-05-09T12:58:35.618649Z","steps":["trace[23096736] 'process raft request' (duration: 132.864063ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:35.61918Z","caller":"traceutil/trace.go:171","msg":"trace[2042319059] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"133.272453ms","start":"2026-05-09T12:58:35.485897Z","end":"2026-05-09T12:58:35.619169Z","steps":["trace[2042319059] 'process raft request' (duration: 132.707108ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:35.619827Z","caller":"traceutil/trace.go:171","msg":"trace[880712705] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"133.820656ms","start":"2026-05-09T12:58:35.485992Z","end":"2026-05-09T12:58:35.619813Z","steps":["trace[880712705] 'process raft request' (duration: 132.905994ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:35.619856Z","caller":"traceutil/trace.go:171","msg":"trace[1994297271] linearizableReadLoop","detail":"{readStateIndex:5055; appliedIndex:5051; }","duration":"132.7597ms","start":"2026-05-09T12:58:35.487067Z","end":"2026-05-09T12:58:35.619827Z","steps":["trace[1994297271] 'read index received' (duration: 37.908409ms)","trace[1994297271] 'applied index is now lower than readState.Index' (duration: 94.849981ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:35.620159Z","caller":"traceutil/trace.go:171","msg":"trace[541498132] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"131.535512ms","start":"2026-05-09T12:58:35.488614Z","end":"2026-05-09T12:58:35.62015Z","steps":["trace[541498132] 'process raft request' (duration: 131.114622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:35.620387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.287343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-05-09T12:58:35.620971Z","caller":"traceutil/trace.go:171","msg":"trace[274340488] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4871; }","duration":"133.386905ms","start":"2026-05-09T12:58:35.487047Z","end":"2026-05-09T12:58:35.620434Z","steps":["trace[274340488] 'agreement among raft nodes before linearized reading' (duration: 133.080978ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:42.088296Z","caller":"traceutil/trace.go:171","msg":"trace[1137036872] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"128.466337ms","start":"2026-05-09T12:58:41.959752Z","end":"2026-05-09T12:58:42.088218Z","steps":["trace[1137036872] 'process raft request' (duration: 124.836843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:42.088921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.173484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:42.089035Z","caller":"traceutil/trace.go:171","msg":"trace[2127184492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4975; }","duration":"295.481944ms","start":"2026-05-09T12:58:41.793533Z","end":"2026-05-09T12:58:42.089015Z","steps":["trace[2127184492] 'range keys from in-memory index tree' (duration: 294.005138ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:42.095179Z","caller":"traceutil/trace.go:171","msg":"trace[302964705] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"109.057011ms","start":"2026-05-09T12:58:41.986104Z","end":"2026-05-09T12:58:42.095161Z","steps":["trace[302964705] 'process raft request' (duration: 108.943398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:46.590865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575187456959896293,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T12:58:46.788779Z","caller":"traceutil/trace.go:171","msg":"trace[242795019] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"729.831789ms","start":"2026-05-09T12:58:46.058915Z","end":"2026-05-09T12:58:46.788747Z","steps":["trace[242795019] 'process raft request' (duration: 729.549403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:46.789004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.058889Z","time spent":"729.976092ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:58:46.844459Z","caller":"traceutil/trace.go:171","msg":"trace[1715217395] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"734.907797ms","start":"2026-05-09T12:58:46.10953Z","end":"2026-05-09T12:58:46.844438Z","steps":["trace[1715217395] 'process raft request' (duration: 734.831136ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:46.844527Z","caller":"traceutil/trace.go:171","msg":"trace[394865872] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"739.955324ms","start":"2026-05-09T12:58:46.1045Z","end":"2026-05-09T12:58:46.844455Z","steps":["trace[394865872] 'process raft request' (duration: 739.736089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:46.844919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.620868ms","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-05-09T12:58:46.844644Z","caller":"traceutil/trace.go:171","msg":"trace[1671691559] linearizableReadLoop","detail":"{readStateIndex:5211; appliedIndex:5208; }","duration":"754.329891ms","start":"2026-05-09T12:58:46.09028Z","end":"2026-05-09T12:58:46.84461Z","steps":["trace[1671691559] 'read index received' (duration: 698.224341ms)","trace[1671691559] 'applied index is now lower than readState.Index' (duration: 56.102659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:46.844932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.104483Z","time spent":"740.14018ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:58:46.844981Z","caller":"traceutil/trace.go:171","msg":"trace[1341743477] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5021; }","duration":"754.718979ms","start":"2026-05-09T12:58:46.090244Z","end":"2026-05-09T12:58:46.844963Z","steps":["trace[1341743477] 'agreement among raft nodes before linearized reading' (duration: 754.551806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:46.845014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.090229Z","time spent":"754.778642ms","remote":"127.0.0.1:49290","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-05-09T12:58:46.84491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.109517Z","time spent":"735.074732ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:58:47.003899Z","caller":"traceutil/trace.go:171","msg":"trace[246955208] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"205.141837ms","start":"2026-05-09T12:58:46.798665Z","end":"2026-05-09T12:58:47.003807Z","steps":["trace[246955208] 'process raft request' (duration: 204.82906ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:47.004046Z","caller":"traceutil/trace.go:171","msg":"trace[284212638] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"203.677022ms","start":"2026-05-09T12:58:46.800353Z","end":"2026-05-09T12:58:47.00403Z","steps":["trace[284212638] 'process raft request' (duration: 203.485057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.004275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.707246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:47.004363Z","caller":"traceutil/trace.go:171","msg":"trace[787253823] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"204.03089ms","start":"2026-05-09T12:58:46.800308Z","end":"2026-05-09T12:58:47.004339Z","steps":["trace[787253823] 'process raft request' (duration: 203.463738ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:47.004386Z","caller":"traceutil/trace.go:171","msg":"trace[2050718375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5025; }","duration":"198.85976ms","start":"2026-05-09T12:58:46.805496Z","end":"2026-05-09T12:58:47.004356Z","steps":["trace[2050718375] 'agreement among raft nodes before linearized reading' (duration: 198.648675ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:47.003944Z","caller":"traceutil/trace.go:171","msg":"trace[1035950965] linearizableReadLoop","detail":"{readStateIndex:5216; appliedIndex:5212; }","duration":"158.924458ms","start":"2026-05-09T12:58:46.844983Z","end":"2026-05-09T12:58:47.003907Z","steps":["trace[1035950965] 'read index received' (duration: 158.55341ms)","trace[1035950965] 'applied index is now lower than readState.Index' (duration: 369.048µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:58:47.004773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.998154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T12:58:47.003941Z","caller":"traceutil/trace.go:171","msg":"trace[95681111] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"203.812026ms","start":"2026-05-09T12:58:46.800078Z","end":"2026-05-09T12:58:47.00389Z","steps":["trace[95681111] 'process raft request' (duration: 203.642162ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:47.00482Z","caller":"traceutil/trace.go:171","msg":"trace[432858066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5025; }","duration":"784.179157ms","start":"2026-05-09T12:58:46.220627Z","end":"2026-05-09T12:58:47.004806Z","steps":["trace[432858066] 'agreement among raft nodes before linearized reading' (duration: 783.917831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.004861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.220591Z","time spent":"784.25993ms","remote":"127.0.0.1:49434","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-05-09T12:58:47.004978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.289013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-09T12:58:47.005093Z","caller":"traceutil/trace.go:171","msg":"trace[617117169] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5025; }","duration":"887.445288ms","start":"2026-05-09T12:58:46.117621Z","end":"2026-05-09T12:58:47.005067Z","steps":["trace[617117169] 'agreement among raft nodes before linearized reading' (duration: 886.384703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.005153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:58:46.11761Z","time spent":"887.52974ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T12:58:47.021267Z","caller":"traceutil/trace.go:171","msg":"trace[10975185] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"169.216087ms","start":"2026-05-09T12:58:46.852028Z","end":"2026-05-09T12:58:47.021244Z","steps":["trace[10975185] 'process raft request' (duration: 169.142735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.021333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.313434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T12:58:47.021398Z","caller":"traceutil/trace.go:171","msg":"trace[385013000] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:5026; }","duration":"134.434076ms","start":"2026-05-09T12:58:46.886953Z","end":"2026-05-09T12:58:47.021387Z","steps":["trace[385013000] 'agreement among raft nodes before linearized reading' (duration: 134.293753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.02177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.552956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ec5ad7f3-2698-4f85-affd-6fdd597fb1fd\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-09T12:58:47.021842Z","caller":"traceutil/trace.go:171","msg":"trace[603453271] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ec5ad7f3-2698-4f85-affd-6fdd597fb1fd; range_end:; response_count:1; response_revision:5026; }","duration":"172.682659ms","start":"2026-05-09T12:58:46.849145Z","end":"2026-05-09T12:58:47.021828Z","steps":["trace[603453271] 'agreement among raft nodes before linearized reading' (duration: 172.129396ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:58:47.117311Z","caller":"traceutil/trace.go:171","msg":"trace[1658644803] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"105.520969ms","start":"2026-05-09T12:58:47.011756Z","end":"2026-05-09T12:58:47.117277Z","steps":["trace[1658644803] 'process raft request' (duration: 64.329049ms)","trace[1658644803] 'compare' (duration: 40.649738ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:58:47.117389Z","caller":"traceutil/trace.go:171","msg":"trace[581109265] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"105.5914ms","start":"2026-05-09T12:58:47.011764Z","end":"2026-05-09T12:58:47.117356Z","steps":["trace[581109265] 'process raft request' (duration: 105.339624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:58:47.11755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.631518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:58:47.117673Z","caller":"traceutil/trace.go:171","msg":"trace[2103948948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5028; }","duration":"110.791552ms","start":"2026-05-09T12:58:47.006806Z","end":"2026-05-09T12:58:47.117598Z","steps":["trace[2103948948] 'agreement among raft nodes before linearized reading' (duration: 110.488205ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:26.121369Z","caller":"traceutil/trace.go:171","msg":"trace[199175397] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"128.003318ms","start":"2026-05-09T12:59:25.993333Z","end":"2026-05-09T12:59:26.121336Z","steps":["trace[199175397] 'process raft request' (duration: 127.952306ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:26.122142Z","caller":"traceutil/trace.go:171","msg":"trace[1700130448] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"131.776411ms","start":"2026-05-09T12:59:25.99034Z","end":"2026-05-09T12:59:26.122116Z","steps":["trace[1700130448] 'process raft request' (duration: 130.83481ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:26.114484Z","caller":"traceutil/trace.go:171","msg":"trace[2128748116] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"124.299176ms","start":"2026-05-09T12:59:25.990153Z","end":"2026-05-09T12:59:26.114452Z","steps":["trace[2128748116] 'process raft request' (duration: 124.088122ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:41.614937Z","caller":"traceutil/trace.go:171","msg":"trace[725814169] linearizableReadLoop","detail":"{readStateIndex:5661; appliedIndex:5660; }","duration":"160.157983ms","start":"2026-05-09T12:59:41.454749Z","end":"2026-05-09T12:59:41.614907Z","steps":["trace[725814169] 'read index received' (duration: 159.908118ms)","trace[725814169] 'applied index is now lower than readState.Index' (duration: 248.655µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:59:41.615279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.4954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T12:59:41.61506Z","caller":"traceutil/trace.go:171","msg":"trace[779064377] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"170.505618ms","start":"2026-05-09T12:59:41.444513Z","end":"2026-05-09T12:59:41.615019Z","steps":["trace[779064377] 'process raft request' (duration: 170.188831ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:41.615338Z","caller":"traceutil/trace.go:171","msg":"trace[1178970179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5457; }","duration":"160.592872ms","start":"2026-05-09T12:59:41.454732Z","end":"2026-05-09T12:59:41.615325Z","steps":["trace[1178970179] 'agreement among raft nodes before linearized reading' (duration: 160.295205ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:59:54.755476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-05-09T12:59:54.849354Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"92.053392ms","hash":4216965022,"current-db-size-bytes":52318208,"current-db-size":"52 MB","current-db-size-in-use-bytes":28594176,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-09T12:59:54.849416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4216965022,"revision":2892,"compact-revision":-1} {"level":"warn","ts":"2026-05-09T13:02:31.923472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.130401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T13:02:31.924098Z","caller":"traceutil/trace.go:171","msg":"trace[1411110432] transaction","detail":"{read_only:false; response_revision:6632; number_of_response:1; }","duration":"124.891773ms","start":"2026-05-09T13:02:31.79918Z","end":"2026-05-09T13:02:31.924071Z","steps":["trace[1411110432] 'process raft request' (duration: 124.794681ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:31.924108Z","caller":"traceutil/trace.go:171","msg":"trace[446090253] transaction","detail":"{read_only:false; response_revision:6631; number_of_response:1; }","duration":"125.420294ms","start":"2026-05-09T13:02:31.798635Z","end":"2026-05-09T13:02:31.924056Z","steps":["trace[446090253] 'compare' (duration: 114.963076ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:35.9088Z","caller":"traceutil/trace.go:171","msg":"trace[1455960994] transaction","detail":"{read_only:false; response_revision:6649; number_of_response:1; }","duration":"135.555841ms","start":"2026-05-09T13:02:35.773211Z","end":"2026-05-09T13:02:35.908767Z","steps":["trace[1455960994] 'process raft request' (duration: 135.362497ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:35.946335Z","caller":"traceutil/trace.go:171","msg":"trace[609819463] linearizableReadLoop","detail":"{readStateIndex:6895; appliedIndex:6893; }","duration":"150.72778ms","start":"2026-05-09T13:02:35.795578Z","end":"2026-05-09T13:02:35.946306Z","steps":["trace[609819463] 'read index received' (duration: 112.988216ms)","trace[609819463] 'applied index is now lower than readState.Index' (duration: 37.738284ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:02:35.946463Z","caller":"traceutil/trace.go:171","msg":"trace[540490528] transaction","detail":"{read_only:false; response_revision:6650; number_of_response:1; }","duration":"157.349499ms","start":"2026-05-09T13:02:35.789102Z","end":"2026-05-09T13:02:35.946452Z","steps":["trace[540490528] 'process raft request' (duration: 157.043593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:35.946617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.030695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:02:35.946679Z","caller":"traceutil/trace.go:171","msg":"trace[1374973454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6650; }","duration":"151.160808ms","start":"2026-05-09T13:02:35.7955Z","end":"2026-05-09T13:02:35.946661Z","steps":["trace[1374973454] 'agreement among raft nodes before linearized reading' (duration: 151.054546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:35.961094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.35161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-05-09T13:02:35.96116Z","caller":"traceutil/trace.go:171","msg":"trace[122740142] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6652; }","duration":"155.525934ms","start":"2026-05-09T13:02:35.80562Z","end":"2026-05-09T13:02:35.961146Z","steps":["trace[122740142] 'agreement among raft nodes before linearized reading' (duration: 155.35419ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:03:00.933009Z","caller":"traceutil/trace.go:171","msg":"trace[1653934892] transaction","detail":"{read_only:false; response_revision:6837; number_of_response:1; }","duration":"104.869013ms","start":"2026-05-09T13:03:00.828103Z","end":"2026-05-09T13:03:00.932972Z","steps":["trace[1653934892] 'process raft request' (duration: 104.337802ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:03:13.76944Z","caller":"traceutil/trace.go:171","msg":"trace[755024355] linearizableReadLoop","detail":"{readStateIndex:7161; appliedIndex:7159; }","duration":"167.113212ms","start":"2026-05-09T13:03:13.602279Z","end":"2026-05-09T13:03:13.769392Z","steps":["trace[755024355] 'read index received' (duration: 65.700482ms)","trace[755024355] 'applied index is now lower than readState.Index' (duration: 101.370378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:03:13.769699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.398536ms","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-05-09T13:03:13.769736Z","caller":"traceutil/trace.go:171","msg":"trace[642702089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6904; }","duration":"167.495309ms","start":"2026-05-09T13:03:13.602227Z","end":"2026-05-09T13:03:13.769722Z","steps":["trace[642702089] 'agreement among raft nodes before linearized reading' (duration: 167.300795ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:03:13.770063Z","caller":"traceutil/trace.go:171","msg":"trace[1705162341] transaction","detail":"{read_only:false; response_revision:6904; number_of_response:1; }","duration":"186.960388ms","start":"2026-05-09T13:03:13.583089Z","end":"2026-05-09T13:03:13.77005Z","steps":["trace[1705162341] 'process raft request' (duration: 173.673448ms)","trace[1705162341] 'compare' (duration: 12.442544ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:04:54.808399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5558} {"level":"info","ts":"2026-05-09T13:04:55.065703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5558,"took":"254.603005ms","hash":3412473717,"current-db-size-bytes":52318208,"current-db-size":"52 MB","current-db-size-in-use-bytes":18386944,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-09T13:04:55.065765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3412473717,"revision":5558,"compact-revision":2892} {"level":"warn","ts":"2026-05-09T13:06:14.595774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.401763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-kq7hg\" ","response":"range_response_count:1 size:8247"} {"level":"info","ts":"2026-05-09T13:06:14.59586Z","caller":"traceutil/trace.go:171","msg":"trace[1898747142] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-kq7hg; range_end:; response_count:1; response_revision:7837; }","duration":"816.552527ms","start":"2026-05-09T13:06:13.779288Z","end":"2026-05-09T13:06:14.595841Z","steps":["trace[1898747142] 'range keys from in-memory index tree' (duration: 816.219727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:14.5959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:13.779266Z","time spent":"816.62421ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":8270,"request content":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-kq7hg\" "} {"level":"warn","ts":"2026-05-09T13:06:14.596201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.096186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:06:14.596249Z","caller":"traceutil/trace.go:171","msg":"trace[997192253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7837; }","duration":"802.182828ms","start":"2026-05-09T13:06:13.794053Z","end":"2026-05-09T13:06:14.596236Z","steps":["trace[997192253] 'range keys from in-memory index tree' (duration: 801.984272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:14.596276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:13.79403Z","time spent":"802.23902ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T13:06:14.59639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:13.937722Z","time spent":"658.656267ms","remote":"127.0.0.1:44800","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-09T13:06:14.596599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.871062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11487"} {"level":"info","ts":"2026-05-09T13:06:14.596641Z","caller":"traceutil/trace.go:171","msg":"trace[1102898735] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:7837; }","duration":"740.912083ms","start":"2026-05-09T13:06:13.855719Z","end":"2026-05-09T13:06:14.596631Z","steps":["trace[1102898735] 'range keys from in-memory index tree' (duration: 740.663616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:14.596675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:13.85567Z","time spent":"740.998315ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":11510,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-05-09T13:06:14.596988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.994419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-09T13:06:14.596942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.376918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T13:06:14.597093Z","caller":"traceutil/trace.go:171","msg":"trace[725505580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7837; }","duration":"446.587845ms","start":"2026-05-09T13:06:14.150469Z","end":"2026-05-09T13:06:14.597057Z","steps":["trace[725505580] 'range keys from in-memory index tree' (duration: 446.058859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:14.597176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.150441Z","time spent":"446.715798ms","remote":"127.0.0.1:49434","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-05-09T13:06:14.597026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.269164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-09T13:06:14.597394Z","caller":"traceutil/trace.go:171","msg":"trace[1996120660] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7837; }","duration":"580.769999ms","start":"2026-05-09T13:06:14.016607Z","end":"2026-05-09T13:06:14.597377Z","steps":["trace[1996120660] 'range keys from in-memory index tree' (duration: 580.131321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:14.597446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.016569Z","time spent":"580.863781ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T13:06:14.597055Z","caller":"traceutil/trace.go:171","msg":"trace[2113777670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7837; }","duration":"401.121292ms","start":"2026-05-09T13:06:14.195914Z","end":"2026-05-09T13:06:14.597035Z","steps":["trace[2113777670] 'range keys from in-memory index tree' (duration: 400.791052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:14.597984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.195893Z","time spent":"402.075029ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T13:06:15.543309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.532984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T13:06:15.545021Z","caller":"traceutil/trace.go:171","msg":"trace[229379299] transaction","detail":"{read_only:false; response_revision:7844; number_of_response:1; }","duration":"371.085917ms","start":"2026-05-09T13:06:15.173903Z","end":"2026-05-09T13:06:15.544988Z","steps":["trace[229379299] 'process raft request' (duration: 371.037605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.545201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:15.17387Z","time spent":"371.232631ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:06:15.545431Z","caller":"traceutil/trace.go:171","msg":"trace[136200243] linearizableReadLoop","detail":"{readStateIndex:8136; appliedIndex:8135; }","duration":"836.478923ms","start":"2026-05-09T13:06:14.708921Z","end":"2026-05-09T13:06:15.5454Z","steps":["trace[136200243] 'read index received' (duration: 477.725564ms)","trace[136200243] 'applied index is now lower than readState.Index' (duration: 358.751679ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:06:15.545648Z","caller":"traceutil/trace.go:171","msg":"trace[300688571] transaction","detail":"{read_only:false; response_revision:7841; number_of_response:1; }","duration":"902.777601ms","start":"2026-05-09T13:06:14.642828Z","end":"2026-05-09T13:06:15.545606Z","steps":["trace[300688571] 'process raft request' (duration: 543.824927ms)","trace[300688571] 'compare' (duration: 355.783033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:06:15.545767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.851544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-09T13:06:15.545803Z","caller":"traceutil/trace.go:171","msg":"trace[1125926888] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7844; }","duration":"836.938266ms","start":"2026-05-09T13:06:14.708857Z","end":"2026-05-09T13:06:15.545795Z","steps":["trace[1125926888] 'agreement among raft nodes before linearized reading' (duration: 836.766691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.545829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.708831Z","time spent":"836.991576ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-09T13:06:15.545788Z","caller":"traceutil/trace.go:171","msg":"trace[458023238] transaction","detail":"{read_only:false; response_revision:7843; number_of_response:1; }","duration":"676.326832ms","start":"2026-05-09T13:06:14.869403Z","end":"2026-05-09T13:06:15.54573Z","steps":["trace[458023238] 'process raft request' (duration: 675.491948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.545938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.642788Z","time spent":"902.968127ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T13:06:15.545951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.869371Z","time spent":"676.516367ms","remote":"127.0.0.1:49434","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-05-09T13:06:15.546043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.821899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T13:06:15.54607Z","caller":"traceutil/trace.go:171","msg":"trace[981331138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7844; }","duration":"816.893313ms","start":"2026-05-09T13:06:14.72917Z","end":"2026-05-09T13:06:15.546064Z","steps":["trace[981331138] 'agreement among raft nodes before linearized reading' (duration: 816.80443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.546088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.729146Z","time spent":"816.937284ms","remote":"127.0.0.1:49434","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":"info","ts":"2026-05-09T13:06:15.545702Z","caller":"traceutil/trace.go:171","msg":"trace[1819639662] transaction","detail":"{read_only:false; response_revision:7842; number_of_response:1; }","duration":"721.797815ms","start":"2026-05-09T13:06:14.823865Z","end":"2026-05-09T13:06:15.545663Z","steps":["trace[1819639662] 'process raft request' (duration: 720.887499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.546293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.466523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T13:06:15.546188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.823817Z","time spent":"722.339652ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:06:15.54632Z","caller":"traceutil/trace.go:171","msg":"trace[817730590] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:7844; }","duration":"333.497134ms","start":"2026-05-09T13:06:15.212816Z","end":"2026-05-09T13:06:15.546313Z","steps":["trace[817730590] 'agreement among raft nodes before linearized reading' (duration: 333.431501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.546343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:15.212784Z","time spent":"333.553105ms","remote":"127.0.0.1:49540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-09T13:06:15.546482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.862532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:06:15.54651Z","caller":"traceutil/trace.go:171","msg":"trace[1954829903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7844; }","duration":"749.901373ms","start":"2026-05-09T13:06:14.796601Z","end":"2026-05-09T13:06:15.546503Z","steps":["trace[1954829903] 'agreement among raft nodes before linearized reading' (duration: 749.854141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.546529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.796594Z","time spent":"749.929263ms","remote":"127.0.0.1:49146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T13:06:15.546691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.329022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/pxc-operator-69cb5bbdb9-jvjs9\" ","response":"range_response_count:1 size:4351"} {"level":"info","ts":"2026-05-09T13:06:15.546715Z","caller":"traceutil/trace.go:171","msg":"trace[844787376] range","detail":"{range_begin:/registry/pods/openstack/pxc-operator-69cb5bbdb9-jvjs9; range_end:; response_count:1; response_revision:7844; }","duration":"809.370064ms","start":"2026-05-09T13:06:14.737339Z","end":"2026-05-09T13:06:15.546709Z","steps":["trace[844787376] 'agreement among raft nodes before linearized reading' (duration: 809.299731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.546735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.737329Z","time spent":"809.400455ms","remote":"127.0.0.1:49324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":4374,"request content":"key:\"/registry/pods/openstack/pxc-operator-69cb5bbdb9-jvjs9\" "} {"level":"warn","ts":"2026-05-09T13:06:15.546911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.628986ms","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-05-09T13:06:15.546994Z","caller":"traceutil/trace.go:171","msg":"trace[1741959942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7844; }","duration":"814.739069ms","start":"2026-05-09T13:06:14.732246Z","end":"2026-05-09T13:06:15.546985Z","steps":["trace[1741959942] 'agreement among raft nodes before linearized reading' (duration: 814.444021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:06:15.547072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:06:14.732232Z","time spent":"814.831843ms","remote":"127.0.0.1:49434","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\" "}