{"level":"warn","ts":"2026-08-15T02:35:27.651358Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:35:27.651434Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.237: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.237:2380","--initial-cluster=instance=https://199.19.213.237:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.237:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.237:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-15T02:35:27.651488Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:35:27.651499Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-08-15T02:35:27.651518Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-15T02:35:27.652136Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"]} {"level":"info","ts":"2026-08-15T02:35:27.652203Z","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.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.237:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-15T02:35:27.656251Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.83747ms"} {"level":"info","ts":"2026-08-15T02:35:27.66172Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c51d7a9ea5537d6e","cluster-id":"bea7a5ea8f053dc3"} {"level":"info","ts":"2026-08-15T02:35:27.661777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=()"} {"level":"info","ts":"2026-08-15T02:35:27.661793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 0"} {"level":"info","ts":"2026-08-15T02:35:27.661807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c51d7a9ea5537d6e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-15T02:35:27.661827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became follower at term 1"} {"level":"info","ts":"2026-08-15T02:35:27.661855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"warn","ts":"2026-08-15T02:35:27.67019Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-15T02:35:27.67177Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-15T02:35:27.67282Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-15T02:35:27.679261Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c51d7a9ea5537d6e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-15T02:35:27.679785Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c51d7a9ea5537d6e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-15T02:35:27.679927Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-15T02:35:27.679964Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-15T02:35:27.679971Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-15T02:35:27.680694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e switched to configuration voters=(14203643621593546094)"} {"level":"info","ts":"2026-08-15T02:35:27.681705Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","added-peer-id":"c51d7a9ea5537d6e","added-peer-peer-urls":["https://199.19.213.237:2380"]} {"level":"info","ts":"2026-08-15T02:35:27.682997Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-15T02:35:27.683159Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-08-15T02:35:27.683211Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.237:2380"} {"level":"info","ts":"2026-08-15T02:35:27.683316Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c51d7a9ea5537d6e","initial-advertise-peer-urls":["https://199.19.213.237:2380"],"listen-peer-urls":["https://199.19.213.237:2380"],"advertise-client-urls":["https://199.19.213.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-15T02:35:27.683344Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-15T02:35:28.462917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e is starting a new election at term 1"} {"level":"info","ts":"2026-08-15T02:35:28.462976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-15T02:35:28.463014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgPreVoteResp from c51d7a9ea5537d6e at term 1"} {"level":"info","ts":"2026-08-15T02:35:28.463033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became candidate at term 2"} {"level":"info","ts":"2026-08-15T02:35:28.463044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e received MsgVoteResp from c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-08-15T02:35:28.46306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c51d7a9ea5537d6e became leader at term 2"} {"level":"info","ts":"2026-08-15T02:35:28.463082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c51d7a9ea5537d6e elected leader c51d7a9ea5537d6e at term 2"} {"level":"info","ts":"2026-08-15T02:35:28.469021Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:35:28.470254Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c51d7a9ea5537d6e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.237:2379]}","request-path":"/0/members/c51d7a9ea5537d6e/attributes","cluster-id":"bea7a5ea8f053dc3","publish-timeout":"7s"} {"level":"info","ts":"2026-08-15T02:35:28.470291Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:35:28.470327Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:35:28.470721Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-15T02:35:28.470777Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-15T02:35:28.471382Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bea7a5ea8f053dc3","local-member-id":"c51d7a9ea5537d6e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:35:28.471539Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:35:28.471733Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:35:28.472613Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-15T02:35:28.474075Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.237:2379"} {"level":"info","ts":"2026-08-15T02:36:05.332835Z","caller":"traceutil/trace.go:171","msg":"trace[1292312800] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"150.562411ms","start":"2026-08-15T02:36:05.182257Z","end":"2026-08-15T02:36:05.332819Z","steps":["trace[1292312800] 'process raft request' (duration: 147.720313ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.33683Z","caller":"traceutil/trace.go:171","msg":"trace[151332496] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"147.228219ms","start":"2026-08-15T02:36:05.189565Z","end":"2026-08-15T02:36:05.336793Z","steps":["trace[151332496] 'process raft request' (duration: 147.068749ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.337073Z","caller":"traceutil/trace.go:171","msg":"trace[2008786615] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"145.000847ms","start":"2026-08-15T02:36:05.19204Z","end":"2026-08-15T02:36:05.33704Z","steps":["trace[2008786615] 'process raft request' (duration: 144.688255ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.337566Z","caller":"traceutil/trace.go:171","msg":"trace[1293050257] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"145.43126ms","start":"2026-08-15T02:36:05.192123Z","end":"2026-08-15T02:36:05.337554Z","steps":["trace[1293050257] 'process raft request' (duration: 144.703945ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.339418Z","caller":"traceutil/trace.go:171","msg":"trace[1609531945] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"142.655303ms","start":"2026-08-15T02:36:05.196747Z","end":"2026-08-15T02:36:05.339402Z","steps":["trace[1609531945] 'process raft request' (duration: 140.146217ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.339726Z","caller":"traceutil/trace.go:171","msg":"trace[760450568] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"142.839104ms","start":"2026-08-15T02:36:05.196873Z","end":"2026-08-15T02:36:05.339713Z","steps":["trace[760450568] 'process raft request' (duration: 140.139507ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.339907Z","caller":"traceutil/trace.go:171","msg":"trace[2084643842] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"142.153119ms","start":"2026-08-15T02:36:05.197684Z","end":"2026-08-15T02:36:05.339837Z","steps":["trace[2084643842] 'process raft request' (duration: 141.850727ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.3401Z","caller":"traceutil/trace.go:171","msg":"trace[813273715] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"138.78436ms","start":"2026-08-15T02:36:05.201289Z","end":"2026-08-15T02:36:05.340073Z","steps":["trace[813273715] 'process raft request' (duration: 138.479578ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.340111Z","caller":"traceutil/trace.go:171","msg":"trace[1421891805] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"139.511824ms","start":"2026-08-15T02:36:05.200575Z","end":"2026-08-15T02:36:05.340087Z","steps":["trace[1421891805] 'process raft request' (duration: 139.145292ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.340511Z","caller":"traceutil/trace.go:171","msg":"trace[1423367748] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"129.939237ms","start":"2026-08-15T02:36:05.210552Z","end":"2026-08-15T02:36:05.340492Z","steps":["trace[1423367748] 'process raft request' (duration: 129.380164ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.342781Z","caller":"traceutil/trace.go:171","msg":"trace[797329880] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"118.713439ms","start":"2026-08-15T02:36:05.224047Z","end":"2026-08-15T02:36:05.342761Z","steps":["trace[797329880] 'process raft request' (duration: 118.234696ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.512617Z","caller":"traceutil/trace.go:171","msg":"trace[1584539578] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"142.461712ms","start":"2026-08-15T02:36:05.37013Z","end":"2026-08-15T02:36:05.512592Z","steps":["trace[1584539578] 'process raft request' (duration: 106.029015ms)","trace[1584539578] 'compare' (duration: 36.309166ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:36:05.5141Z","caller":"traceutil/trace.go:171","msg":"trace[56405227] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"142.525482ms","start":"2026-08-15T02:36:05.371548Z","end":"2026-08-15T02:36:05.514073Z","steps":["trace[56405227] 'process raft request' (duration: 142.177169ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.514533Z","caller":"traceutil/trace.go:171","msg":"trace[288044289] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"132.145869ms","start":"2026-08-15T02:36:05.382355Z","end":"2026-08-15T02:36:05.514501Z","steps":["trace[288044289] 'process raft request' (duration: 131.617506ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.514671Z","caller":"traceutil/trace.go:171","msg":"trace[449555840] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"128.82118ms","start":"2026-08-15T02:36:05.385835Z","end":"2026-08-15T02:36:05.514656Z","steps":["trace[449555840] 'process raft request' (duration: 128.665499ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.514675Z","caller":"traceutil/trace.go:171","msg":"trace[2129502764] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:518; }","duration":"140.935522ms","start":"2026-08-15T02:36:05.373708Z","end":"2026-08-15T02:36:05.514644Z","steps":["trace[2129502764] 'read index received' (duration: 102.477432ms)","trace[2129502764] 'applied index is now lower than readState.Index' (duration: 38.45735ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:36:05.514748Z","caller":"traceutil/trace.go:171","msg":"trace[424135956] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"132.328401ms","start":"2026-08-15T02:36:05.382413Z","end":"2026-08-15T02:36:05.514741Z","steps":["trace[424135956] 'process raft request' (duration: 131.870518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:05.514841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.115123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-08-15T02:36:05.514898Z","caller":"traceutil/trace.go:171","msg":"trace[1254416125] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:508; }","duration":"141.212843ms","start":"2026-08-15T02:36:05.373675Z","end":"2026-08-15T02:36:05.514887Z","steps":["trace[1254416125] 'agreement among raft nodes before linearized reading' (duration: 141.048053ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.51491Z","caller":"traceutil/trace.go:171","msg":"trace[1486627134] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"130.823181ms","start":"2026-08-15T02:36:05.384078Z","end":"2026-08-15T02:36:05.514901Z","steps":["trace[1486627134] 'process raft request' (duration: 130.332639ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.515068Z","caller":"traceutil/trace.go:171","msg":"trace[1033822822] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"107.388932ms","start":"2026-08-15T02:36:05.40767Z","end":"2026-08-15T02:36:05.515059Z","steps":["trace[1033822822] 'process raft request' (duration: 106.899828ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:05.515101Z","caller":"traceutil/trace.go:171","msg":"trace[1832580914] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"102.155291ms","start":"2026-08-15T02:36:05.412928Z","end":"2026-08-15T02:36:05.515083Z","steps":["trace[1832580914] 'process raft request' (duration: 101.689808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:11.162101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.361502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-15T02:36:11.162211Z","caller":"traceutil/trace.go:171","msg":"trace[577180095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"120.511494ms","start":"2026-08-15T02:36:11.041687Z","end":"2026-08-15T02:36:11.162198Z","steps":["trace[577180095] 'range keys from in-memory index tree' (duration: 120.236353ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:11.692497Z","caller":"traceutil/trace.go:171","msg":"trace[835451620] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"537.037616ms","start":"2026-08-15T02:36:11.155432Z","end":"2026-08-15T02:36:11.69247Z","steps":["trace[835451620] 'process raft request' (duration: 535.95798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:11.69319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.155412Z","time spent":"537.163618ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:36:11.705365Z","caller":"traceutil/trace.go:171","msg":"trace[2094947107] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:594; }","duration":"436.01954ms","start":"2026-08-15T02:36:11.269326Z","end":"2026-08-15T02:36:11.705346Z","steps":["trace[2094947107] 'read index received' (duration: 421.913235ms)","trace[2094947107] 'applied index is now lower than readState.Index' (duration: 14.105555ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:36:11.705587Z","caller":"traceutil/trace.go:171","msg":"trace[1590341036] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"535.82861ms","start":"2026-08-15T02:36:11.169747Z","end":"2026-08-15T02:36:11.705575Z","steps":["trace[1590341036] 'process raft request' (duration: 535.508519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:11.705736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.169712Z","time spent":"535.89915ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:36:11.705988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.670294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.237\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T02:36:11.706055Z","caller":"traceutil/trace.go:171","msg":"trace[95760442] range","detail":"{range_begin:/registry/masterleases/199.19.213.237; range_end:; response_count:1; response_revision:577; }","duration":"431.743624ms","start":"2026-08-15T02:36:11.274298Z","end":"2026-08-15T02:36:11.706041Z","steps":["trace[95760442] 'agreement among raft nodes before linearized reading' (duration: 431.611123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:11.706084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.274293Z","time spent":"431.783575ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.237\" "} {"level":"warn","ts":"2026-08-15T02:36:11.706345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.993396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rfz6f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-15T02:36:11.706403Z","caller":"traceutil/trace.go:171","msg":"trace[141924036] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rfz6f; range_end:; response_count:1; response_revision:577; }","duration":"437.088907ms","start":"2026-08-15T02:36:11.269303Z","end":"2026-08-15T02:36:11.706392Z","steps":["trace[141924036] 'agreement among raft nodes before linearized reading' (duration: 436.909095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:11.706436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.269287Z","time spent":"437.142417ms","remote":"127.0.0.1:49850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-rfz6f\" "} {"level":"warn","ts":"2026-08-15T02:36:12.414964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.929898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:36:12.415458Z","caller":"traceutil/trace.go:171","msg":"trace[2110406277] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:597; }","duration":"656.716268ms","start":"2026-08-15T02:36:11.758723Z","end":"2026-08-15T02:36:12.41544Z","steps":["trace[2110406277] 'read index received' (duration: 4.019274ms)","trace[2110406277] 'applied index is now lower than readState.Index' (duration: 652.696104ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:36:12.415458Z","caller":"traceutil/trace.go:171","msg":"trace[476612045] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"684.492695ms","start":"2026-08-15T02:36:11.730926Z","end":"2026-08-15T02:36:12.415419Z","steps":["trace[476612045] 'process raft request' (duration: 95.949937ms)","trace[476612045] 'compare' (duration: 586.649317ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:36:12.415654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.730911Z","time spent":"684.636577ms","remote":"127.0.0.1:49698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:36:12.415539Z","caller":"traceutil/trace.go:171","msg":"trace[1115919646] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"657.942355ms","start":"2026-08-15T02:36:11.757568Z","end":"2026-08-15T02:36:12.41551Z","steps":["trace[1115919646] 'process raft request' (duration: 657.490652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:12.415932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.995453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-15T02:36:12.415996Z","caller":"traceutil/trace.go:171","msg":"trace[1991929022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"438.090384ms","start":"2026-08-15T02:36:11.977886Z","end":"2026-08-15T02:36:12.415977Z","steps":["trace[1991929022] 'agreement among raft nodes before linearized reading' (duration: 437.924533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:12.415768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.388594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:36:12.416046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.977863Z","time spent":"438.171145ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-15T02:36:12.416056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.919055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-08-15T02:36:12.416086Z","caller":"traceutil/trace.go:171","msg":"trace[1208302367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"624.762116ms","start":"2026-08-15T02:36:11.791309Z","end":"2026-08-15T02:36:12.416072Z","steps":["trace[1208302367] 'agreement among raft nodes before linearized reading' (duration: 624.290313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:12.415993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.757546Z","time spent":"658.284407ms","remote":"127.0.0.1:49866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:36:12.41615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.791292Z","time spent":"624.847057ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-15T02:36:12.41627Z","caller":"traceutil/trace.go:171","msg":"trace[949796622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"226.993195ms","start":"2026-08-15T02:36:12.189091Z","end":"2026-08-15T02:36:12.416084Z","steps":["trace[949796622] 'agreement among raft nodes before linearized reading' (duration: 226.533791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:12.416069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.272532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-15T02:36:12.416427Z","caller":"traceutil/trace.go:171","msg":"trace[1178000319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"657.717675ms","start":"2026-08-15T02:36:11.758695Z","end":"2026-08-15T02:36:12.416412Z","steps":["trace[1178000319] 'agreement among raft nodes before linearized reading' (duration: 656.931539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:12.416668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:11.758686Z","time spent":"657.911815ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-15T02:36:27.813076Z","caller":"traceutil/trace.go:171","msg":"trace[1070576725] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"443.257811ms","start":"2026-08-15T02:36:27.369791Z","end":"2026-08-15T02:36:27.813049Z","steps":["trace[1070576725] 'process raft request' (duration: 369.06816ms)","trace[1070576725] 'compare' (duration: 73.92672ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:36:27.813235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:36:27.369777Z","time spent":"443.383261ms","remote":"127.0.0.1:49850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:37:14.193335Z","caller":"traceutil/trace.go:171","msg":"trace[25530045] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"103.957498ms","start":"2026-08-15T02:37:14.089352Z","end":"2026-08-15T02:37:14.193309Z","steps":["trace[25530045] 'process raft request' (duration: 102.113407ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:37:20.883937Z","caller":"traceutil/trace.go:171","msg":"trace[751949616] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"102.308219ms","start":"2026-08-15T02:37:20.78159Z","end":"2026-08-15T02:37:20.883898Z","steps":["trace[751949616] 'process raft request' (duration: 98.067192ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:37:20.914997Z","caller":"traceutil/trace.go:171","msg":"trace[2136202151] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"116.226474ms","start":"2026-08-15T02:37:20.798731Z","end":"2026-08-15T02:37:20.914957Z","steps":["trace[2136202151] 'process raft request' (duration: 115.59135ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:37:51.473109Z","caller":"traceutil/trace.go:171","msg":"trace[1640388675] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"192.699267ms","start":"2026-08-15T02:37:51.280392Z","end":"2026-08-15T02:37:51.473091Z","steps":["trace[1640388675] 'read index received' (duration: 192.537416ms)","trace[1640388675] 'applied index is now lower than readState.Index' (duration: 160.981µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:37:51.473158Z","caller":"traceutil/trace.go:171","msg":"trace[614605915] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"197.220864ms","start":"2026-08-15T02:37:51.275904Z","end":"2026-08-15T02:37:51.473124Z","steps":["trace[614605915] 'process raft request' (duration: 197.079674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:37:51.473302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.903009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.237\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-15T02:37:51.473328Z","caller":"traceutil/trace.go:171","msg":"trace[2003608612] range","detail":"{range_begin:/registry/masterleases/199.19.213.237; range_end:; response_count:1; response_revision:1899; }","duration":"192.960419ms","start":"2026-08-15T02:37:51.280359Z","end":"2026-08-15T02:37:51.473319Z","steps":["trace[2003608612] 'agreement among raft nodes before linearized reading' (duration: 192.842978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:37:51.473417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.055269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-08-15T02:37:51.473495Z","caller":"traceutil/trace.go:171","msg":"trace[1254053982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1899; }","duration":"138.184041ms","start":"2026-08-15T02:37:51.335292Z","end":"2026-08-15T02:37:51.473476Z","steps":["trace[1254053982] 'agreement among raft nodes before linearized reading' (duration: 137.95836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:38:26.052188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.192963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:38:26.05215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.155869ms","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":"info","ts":"2026-08-15T02:38:26.052332Z","caller":"traceutil/trace.go:171","msg":"trace[1420826424] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2145; }","duration":"123.39493ms","start":"2026-08-15T02:38:25.928919Z","end":"2026-08-15T02:38:26.052313Z","steps":["trace[1420826424] 'count revisions from in-memory index tree' (duration: 123.080088ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:38:26.052271Z","caller":"traceutil/trace.go:171","msg":"trace[60658385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2145; }","duration":"260.326123ms","start":"2026-08-15T02:38:25.791919Z","end":"2026-08-15T02:38:26.052245Z","steps":["trace[60658385] 'range keys from in-memory index tree' (duration: 260.089592ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:07.834217Z","caller":"traceutil/trace.go:171","msg":"trace[1127911972] transaction","detail":"{read_only:false; response_revision:2534; number_of_response:1; }","duration":"112.627575ms","start":"2026-08-15T02:39:07.721574Z","end":"2026-08-15T02:39:07.834202Z","steps":["trace[1127911972] 'process raft request' (duration: 112.538724ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:36.114349Z","caller":"traceutil/trace.go:171","msg":"trace[1644803536] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"101.439375ms","start":"2026-08-15T02:39:36.01288Z","end":"2026-08-15T02:39:36.114319Z","steps":["trace[1644803536] 'process raft request' (duration: 101.272184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:36.410322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.659506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:39:36.41045Z","caller":"traceutil/trace.go:171","msg":"trace[1995116497] linearizableReadLoop","detail":"{readStateIndex:2831; appliedIndex:2830; }","duration":"288.063345ms","start":"2026-08-15T02:39:36.122366Z","end":"2026-08-15T02:39:36.410429Z","steps":["trace[1995116497] 'read index received' (duration: 53.105877ms)","trace[1995116497] 'applied index is now lower than readState.Index' (duration: 234.955958ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:39:36.410563Z","caller":"traceutil/trace.go:171","msg":"trace[1159913356] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"289.873887ms","start":"2026-08-15T02:39:36.120672Z","end":"2026-08-15T02:39:36.410546Z","steps":["trace[1159913356] 'process raft request' (duration: 54.851299ms)","trace[1159913356] 'compare' (duration: 234.546684ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:39:36.410805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.424787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:5908"} {"level":"info","ts":"2026-08-15T02:39:36.410888Z","caller":"traceutil/trace.go:171","msg":"trace[388632867] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2731; }","duration":"288.523788ms","start":"2026-08-15T02:39:36.12235Z","end":"2026-08-15T02:39:36.410874Z","steps":["trace[388632867] 'agreement among raft nodes before linearized reading' (duration: 288.339107ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:39:36.768845Z","caller":"traceutil/trace.go:171","msg":"trace[830259431] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"352.672283ms","start":"2026-08-15T02:39:36.416131Z","end":"2026-08-15T02:39:36.768804Z","steps":["trace[830259431] 'process raft request' (duration: 352.466632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:39:36.769163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:39:36.416112Z","time spent":"352.810534ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:39:36.772711Z","caller":"traceutil/trace.go:171","msg":"trace[1174887484] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"226.319185ms","start":"2026-08-15T02:39:36.546375Z","end":"2026-08-15T02:39:36.772694Z","steps":["trace[1174887484] 'process raft request' (duration: 225.888673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:47.044865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.644294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:41:47.044988Z","caller":"traceutil/trace.go:171","msg":"trace[1784068347] linearizableReadLoop","detail":"{readStateIndex:3966; appliedIndex:3965; }","duration":"178.177934ms","start":"2026-08-15T02:41:46.866791Z","end":"2026-08-15T02:41:47.044969Z","steps":["trace[1784068347] 'read index received' (duration: 19.286527ms)","trace[1784068347] 'applied index is now lower than readState.Index' (duration: 158.890287ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:47.045078Z","caller":"traceutil/trace.go:171","msg":"trace[79649592] transaction","detail":"{read_only:false; response_revision:3796; number_of_response:1; }","duration":"219.638462ms","start":"2026-08-15T02:41:46.825369Z","end":"2026-08-15T02:41:47.045008Z","steps":["trace[79649592] 'process raft request' (duration: 60.756697ms)","trace[79649592] 'compare' (duration: 158.517489ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:47.045149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.363481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-08-15T02:41:47.04527Z","caller":"traceutil/trace.go:171","msg":"trace[1439803936] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3796; }","duration":"178.491358ms","start":"2026-08-15T02:41:46.866739Z","end":"2026-08-15T02:41:47.04523Z","steps":["trace[1439803936] 'agreement among raft nodes before linearized reading' (duration: 178.305129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:05.960874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.713553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:42:05.960977Z","caller":"traceutil/trace.go:171","msg":"trace[200426258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4486; }","duration":"167.930111ms","start":"2026-08-15T02:42:05.793027Z","end":"2026-08-15T02:42:05.960957Z","steps":["trace[200426258] 'range keys from in-memory index tree' (duration: 167.516854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:05.960951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.018282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62f2d8bc-6eca-4aad-bc2e-8770920593a7\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-15T02:42:05.961051Z","caller":"traceutil/trace.go:171","msg":"trace[413817320] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62f2d8bc-6eca-4aad-bc2e-8770920593a7; range_end:; response_count:1; response_revision:4486; }","duration":"117.182738ms","start":"2026-08-15T02:42:05.843847Z","end":"2026-08-15T02:42:05.961029Z","steps":["trace[413817320] 'range keys from in-memory index tree' (duration: 116.805153ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:06.084583Z","caller":"traceutil/trace.go:171","msg":"trace[2076638997] transaction","detail":"{read_only:false; response_revision:4488; number_of_response:1; }","duration":"102.380075ms","start":"2026-08-15T02:42:05.98213Z","end":"2026-08-15T02:42:06.08451Z","steps":["trace[2076638997] 'process raft request' (duration: 80.659463ms)","trace[2076638997] 'compare' (duration: 21.577766ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:10.238529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.90287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:42:10.23863Z","caller":"traceutil/trace.go:171","msg":"trace[1198293671] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4559; }","duration":"129.068228ms","start":"2026-08-15T02:42:10.109544Z","end":"2026-08-15T02:42:10.238612Z","steps":["trace[1198293671] 'count revisions from in-memory index tree' (duration: 128.816307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:19.295284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9038337438102345533,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-15T02:42:19.628066Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137743797s","expected-duration":"1s"} {"level":"info","ts":"2026-08-15T02:42:19.628485Z","caller":"traceutil/trace.go:171","msg":"trace[1441927149] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4880; }","duration":"833.45839ms","start":"2026-08-15T02:42:18.794998Z","end":"2026-08-15T02:42:19.628456Z","steps":["trace[1441927149] 'read index received' (duration: 833.20796ms)","trace[1441927149] 'applied index is now lower than readState.Index' (duration: 249.229µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:19.628606Z","caller":"traceutil/trace.go:171","msg":"trace[2147097255] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"1.138357792s","start":"2026-08-15T02:42:18.490233Z","end":"2026-08-15T02:42:19.62859Z","steps":["trace[2147097255] 'process raft request' (duration: 1.138030379s)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:19.628771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:18.490215Z","time spent":"1.138461855s","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:42:19.628835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.74533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-15T02:42:19.628896Z","caller":"traceutil/trace.go:171","msg":"trace[964873816] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4682; }","duration":"753.871685ms","start":"2026-08-15T02:42:18.875011Z","end":"2026-08-15T02:42:19.628883Z","steps":["trace[964873816] 'agreement among raft nodes before linearized reading' (duration: 753.687378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:19.628878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.853944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:42:19.628941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:18.874989Z","time spent":"753.942548ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-08-15T02:42:19.628961Z","caller":"traceutil/trace.go:171","msg":"trace[941857376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4682; }","duration":"833.99746ms","start":"2026-08-15T02:42:18.794946Z","end":"2026-08-15T02:42:19.628943Z","steps":["trace[941857376] 'agreement among raft nodes before linearized reading' (duration: 833.803053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:19.629017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:18.794923Z","time spent":"834.080784ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:42:19.629093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.927994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-15T02:42:19.629155Z","caller":"traceutil/trace.go:171","msg":"trace[69355705] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4682; }","duration":"469.024337ms","start":"2026-08-15T02:42:19.160115Z","end":"2026-08-15T02:42:19.62914Z","steps":["trace[69355705] 'agreement among raft nodes before linearized reading' (duration: 468.818129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:19.629208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:19.160096Z","time spent":"469.098851ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-15T02:42:19.629291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.956882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"info","ts":"2026-08-15T02:42:19.629342Z","caller":"traceutil/trace.go:171","msg":"trace[985382902] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4682; }","duration":"528.032025ms","start":"2026-08-15T02:42:19.101295Z","end":"2026-08-15T02:42:19.629327Z","steps":["trace[985382902] 'agreement among raft nodes before linearized reading' (duration: 527.89639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:19.629395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:19.10128Z","time spent":"528.107478ms","remote":"127.0.0.1:49850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10540,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-15T02:42:19.629744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.793831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-15T02:42:19.629815Z","caller":"traceutil/trace.go:171","msg":"trace[1736186001] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4682; }","duration":"162.879173ms","start":"2026-08-15T02:42:19.466902Z","end":"2026-08-15T02:42:19.629781Z","steps":["trace[1736186001] 'agreement among raft nodes before linearized reading' (duration: 161.836793ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:19.841916Z","caller":"traceutil/trace.go:171","msg":"trace[1033584548] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4891; }","duration":"111.550349ms","start":"2026-08-15T02:42:19.730338Z","end":"2026-08-15T02:42:19.841888Z","steps":["trace[1033584548] 'read index received' (duration: 68.024583ms)","trace[1033584548] 'applied index is now lower than readState.Index' (duration: 43.524666ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:19.842116Z","caller":"traceutil/trace.go:171","msg":"trace[1632665695] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"124.645217ms","start":"2026-08-15T02:42:19.717448Z","end":"2026-08-15T02:42:19.842093Z","steps":["trace[1632665695] 'process raft request' (duration: 80.862889ms)","trace[1632665695] 'compare' (duration: 43.429333ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:19.842291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.932004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-15T02:42:19.842329Z","caller":"traceutil/trace.go:171","msg":"trace[1117803638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4693; }","duration":"112.011577ms","start":"2026-08-15T02:42:19.730306Z","end":"2026-08-15T02:42:19.842317Z","steps":["trace[1117803638] 'agreement among raft nodes before linearized reading' (duration: 111.861891ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:20.215232Z","caller":"traceutil/trace.go:171","msg":"trace[877876776] linearizableReadLoop","detail":"{readStateIndex:4900; appliedIndex:4899; }","duration":"126.526656ms","start":"2026-08-15T02:42:20.088676Z","end":"2026-08-15T02:42:20.215202Z","steps":["trace[877876776] 'read index received' (duration: 44.923003ms)","trace[877876776] 'applied index is now lower than readState.Index' (duration: 81.602193ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:20.21533Z","caller":"traceutil/trace.go:171","msg":"trace[1563001173] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"180.178532ms","start":"2026-08-15T02:42:20.035119Z","end":"2026-08-15T02:42:20.215298Z","steps":["trace[1563001173] 'process raft request' (duration: 98.503417ms)","trace[1563001173] 'compare' (duration: 81.338472ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:20.215685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.990115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:42:20.215741Z","caller":"traceutil/trace.go:171","msg":"trace[1422928162] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4701; }","duration":"127.101729ms","start":"2026-08-15T02:42:20.088626Z","end":"2026-08-15T02:42:20.215728Z","steps":["trace[1422928162] 'agreement among raft nodes before linearized reading' (duration: 126.732925ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.287873Z","caller":"traceutil/trace.go:171","msg":"trace[860392305] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"100.222407ms","start":"2026-08-15T02:42:31.187609Z","end":"2026-08-15T02:42:31.287831Z","steps":["trace[860392305] 'process raft request' (duration: 99.5352ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:31.288292Z","caller":"traceutil/trace.go:171","msg":"trace[2022485122] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"100.465385ms","start":"2026-08-15T02:42:31.187778Z","end":"2026-08-15T02:42:31.288243Z","steps":["trace[2022485122] 'process raft request' (duration: 99.964626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:38.548751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.677221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-15T02:42:38.548913Z","caller":"traceutil/trace.go:171","msg":"trace[534527617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4859; }","duration":"119.860299ms","start":"2026-08-15T02:42:38.429028Z","end":"2026-08-15T02:42:38.548889Z","steps":["trace[534527617] 'agreement among raft nodes before linearized reading' (duration: 50.054078ms)","trace[534527617] 'range keys from in-memory index tree' (duration: 69.557891ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:38.549077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.931391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:42:38.54916Z","caller":"traceutil/trace.go:171","msg":"trace[1062924625] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:4859; }","duration":"161.265393ms","start":"2026-08-15T02:42:38.387873Z","end":"2026-08-15T02:42:38.549138Z","steps":["trace[1062924625] 'agreement among raft nodes before linearized reading' (duration: 91.200383ms)","trace[1062924625] 'count revisions from in-memory index tree' (duration: 69.712717ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:38.549964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.778881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-15T02:42:38.550026Z","caller":"traceutil/trace.go:171","msg":"trace[2018151419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4859; }","duration":"143.890926ms","start":"2026-08-15T02:42:38.406118Z","end":"2026-08-15T02:42:38.550008Z","steps":["trace[2018151419] 'agreement among raft nodes before linearized reading' (duration: 72.945213ms)","trace[2018151419] 'range keys from in-memory index tree' (duration: 70.528427ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:39.917232Z","caller":"traceutil/trace.go:171","msg":"trace[1253079425] transaction","detail":"{read_only:false; response_revision:4867; number_of_response:1; }","duration":"119.130422ms","start":"2026-08-15T02:42:39.79807Z","end":"2026-08-15T02:42:39.9172Z","steps":["trace[1253079425] 'process raft request' (duration: 64.023341ms)","trace[1253079425] 'compare' (duration: 54.942686ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:46.445903Z","caller":"traceutil/trace.go:171","msg":"trace[1440067789] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"119.771655ms","start":"2026-08-15T02:42:46.326108Z","end":"2026-08-15T02:42:46.445879Z","steps":["trace[1440067789] 'process raft request' (duration: 119.579028ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:54.230986Z","caller":"traceutil/trace.go:171","msg":"trace[375419452] linearizableReadLoop","detail":"{readStateIndex:5273; appliedIndex:5272; }","duration":"125.429462ms","start":"2026-08-15T02:42:54.105527Z","end":"2026-08-15T02:42:54.230957Z","steps":["trace[375419452] 'read index received' (duration: 103.131212ms)","trace[375419452] 'applied index is now lower than readState.Index' (duration: 22.29696ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:54.231183Z","caller":"traceutil/trace.go:171","msg":"trace[1617635147] transaction","detail":"{read_only:false; response_revision:5060; number_of_response:1; }","duration":"144.872907ms","start":"2026-08-15T02:42:54.086266Z","end":"2026-08-15T02:42:54.231139Z","steps":["trace[1617635147] 'process raft request' (duration: 122.497653ms)","trace[1617635147] 'compare' (duration: 21.97447ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:54.2313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.748283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-15T02:42:54.231347Z","caller":"traceutil/trace.go:171","msg":"trace[701954648] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5060; }","duration":"125.850227ms","start":"2026-08-15T02:42:54.10548Z","end":"2026-08-15T02:42:54.23133Z","steps":["trace[701954648] 'agreement among raft nodes before linearized reading' (duration: 125.648491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:54.231628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.344614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-15T02:42:54.231671Z","caller":"traceutil/trace.go:171","msg":"trace[411913965] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5060; }","duration":"118.404216ms","start":"2026-08-15T02:42:54.113253Z","end":"2026-08-15T02:42:54.231657Z","steps":["trace[411913965] 'agreement among raft nodes before linearized reading' (duration: 118.303423ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:54.410793Z","caller":"traceutil/trace.go:171","msg":"trace[516699709] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"146.593085ms","start":"2026-08-15T02:42:54.264155Z","end":"2026-08-15T02:42:54.410748Z","steps":["trace[516699709] 'process raft request' (duration: 89.676188ms)","trace[516699709] 'compare' (duration: 56.613966ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:54.411604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.331745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-08-15T02:42:54.411656Z","caller":"traceutil/trace.go:171","msg":"trace[986182481] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5062; }","duration":"170.51323ms","start":"2026-08-15T02:42:54.241129Z","end":"2026-08-15T02:42:54.411642Z","steps":["trace[986182481] 'agreement among raft nodes before linearized reading' (duration: 170.328084ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:56.59319Z","caller":"traceutil/trace.go:171","msg":"trace[1768598307] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"106.899529ms","start":"2026-08-15T02:42:56.486263Z","end":"2026-08-15T02:42:56.593163Z","steps":["trace[1768598307] 'process raft request' (duration: 106.711413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:56.659937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.984362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:42:56.660015Z","caller":"traceutil/trace.go:171","msg":"trace[970060089] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:5077; }","duration":"128.104656ms","start":"2026-08-15T02:42:56.531891Z","end":"2026-08-15T02:42:56.659995Z","steps":["trace[970060089] 'agreement among raft nodes before linearized reading' (duration: 127.983962ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:42:56.659971Z","caller":"traceutil/trace.go:171","msg":"trace[1156752215] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"158.474601ms","start":"2026-08-15T02:42:56.501463Z","end":"2026-08-15T02:42:56.659937Z","steps":["trace[1156752215] 'process raft request' (duration: 136.348102ms)","trace[1156752215] 'compare' (duration: 21.88043ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:56.660772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.787469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-15T02:42:56.660824Z","caller":"traceutil/trace.go:171","msg":"trace[474718139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5077; }","duration":"110.851411ms","start":"2026-08-15T02:42:56.549959Z","end":"2026-08-15T02:42:56.660811Z","steps":["trace[474718139] 'agreement among raft nodes before linearized reading' (duration: 109.91208ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:07.666843Z","caller":"traceutil/trace.go:171","msg":"trace[988642720] linearizableReadLoop","detail":"{readStateIndex:5916; appliedIndex:5915; }","duration":"119.389669ms","start":"2026-08-15T02:44:07.547427Z","end":"2026-08-15T02:44:07.666817Z","steps":["trace[988642720] 'read index received' (duration: 119.165763ms)","trace[988642720] 'applied index is now lower than readState.Index' (duration: 222.736µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:07.666899Z","caller":"traceutil/trace.go:171","msg":"trace[1777368272] transaction","detail":"{read_only:false; response_revision:5672; number_of_response:1; }","duration":"119.867501ms","start":"2026-08-15T02:44:07.546995Z","end":"2026-08-15T02:44:07.666863Z","steps":["trace[1777368272] 'process raft request' (duration: 119.661406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:07.667112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.631645ms","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-08-15T02:44:07.667199Z","caller":"traceutil/trace.go:171","msg":"trace[1501168566] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5672; }","duration":"119.777468ms","start":"2026-08-15T02:44:07.547399Z","end":"2026-08-15T02:44:07.667177Z","steps":["trace[1501168566] 'agreement among raft nodes before linearized reading' (duration: 119.517042ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:16.666708Z","caller":"traceutil/trace.go:171","msg":"trace[373650733] linearizableReadLoop","detail":"{readStateIndex:5974; appliedIndex:5973; }","duration":"211.487737ms","start":"2026-08-15T02:44:16.455173Z","end":"2026-08-15T02:44:16.666661Z","steps":["trace[373650733] 'read index received' (duration: 211.174269ms)","trace[373650733] 'applied index is now lower than readState.Index' (duration: 311.828µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:16.666734Z","caller":"traceutil/trace.go:171","msg":"trace[1286049502] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"221.25752ms","start":"2026-08-15T02:44:16.445439Z","end":"2026-08-15T02:44:16.666697Z","steps":["trace[1286049502] 'process raft request' (duration: 220.918372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:16.667149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.685431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-08-15T02:44:16.66722Z","caller":"traceutil/trace.go:171","msg":"trace[2116253218] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5727; }","duration":"207.781545ms","start":"2026-08-15T02:44:16.459415Z","end":"2026-08-15T02:44:16.667196Z","steps":["trace[2116253218] 'agreement among raft nodes before linearized reading' (duration: 207.630491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:16.666973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.769203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-15T02:44:16.667575Z","caller":"traceutil/trace.go:171","msg":"trace[130685345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5727; }","duration":"212.40367ms","start":"2026-08-15T02:44:16.455145Z","end":"2026-08-15T02:44:16.667549Z","steps":["trace[130685345] 'agreement among raft nodes before linearized reading' (duration: 211.60952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:16.887759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.848031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:16.887877Z","caller":"traceutil/trace.go:171","msg":"trace[1344021910] linearizableReadLoop","detail":"{readStateIndex:5976; appliedIndex:5975; }","duration":"193.327883ms","start":"2026-08-15T02:44:16.694529Z","end":"2026-08-15T02:44:16.887857Z","steps":["trace[1344021910] 'read index received' (duration: 3.259372ms)","trace[1344021910] 'applied index is now lower than readState.Index' (duration: 190.066851ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:16.888197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.62403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T02:44:16.88824Z","caller":"traceutil/trace.go:171","msg":"trace[1466078597] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5729; }","duration":"193.753303ms","start":"2026-08-15T02:44:16.694476Z","end":"2026-08-15T02:44:16.888229Z","steps":["trace[1466078597] 'agreement among raft nodes before linearized reading' (duration: 193.464695ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:16.888331Z","caller":"traceutil/trace.go:171","msg":"trace[1869816957] transaction","detail":"{read_only:false; response_revision:5729; number_of_response:1; }","duration":"214.752088ms","start":"2026-08-15T02:44:16.673183Z","end":"2026-08-15T02:44:16.887935Z","steps":["trace[1869816957] 'process raft request' (duration: 85.60842ms)","trace[1869816957] 'compare' (duration: 128.535174ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:16.920924Z","caller":"traceutil/trace.go:171","msg":"trace[1108577760] transaction","detail":"{read_only:false; response_revision:5730; number_of_response:1; }","duration":"203.558059ms","start":"2026-08-15T02:44:16.717339Z","end":"2026-08-15T02:44:16.920897Z","steps":["trace[1108577760] 'process raft request' (duration: 203.296442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:16.920926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.078252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:16.921014Z","caller":"traceutil/trace.go:171","msg":"trace[640087344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5730; }","duration":"128.222225ms","start":"2026-08-15T02:44:16.792778Z","end":"2026-08-15T02:44:16.921Z","steps":["trace[640087344] 'agreement among raft nodes before linearized reading' (duration: 128.054321ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:17.086014Z","caller":"traceutil/trace.go:171","msg":"trace[1173737783] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5730; }","duration":"155.524952ms","start":"2026-08-15T02:44:16.93046Z","end":"2026-08-15T02:44:17.085985Z","steps":["trace[1173737783] 'process raft request' (duration: 84.667786ms)","trace[1173737783] 'compare' (duration: 70.60359ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:17.264377Z","caller":"traceutil/trace.go:171","msg":"trace[762006918] linearizableReadLoop","detail":"{readStateIndex:5981; appliedIndex:5980; }","duration":"127.139289ms","start":"2026-08-15T02:44:17.137215Z","end":"2026-08-15T02:44:17.264354Z","steps":["trace[762006918] 'read index received' (duration: 118.681338ms)","trace[762006918] 'applied index is now lower than readState.Index' (duration: 8.456981ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:17.264504Z","caller":"traceutil/trace.go:171","msg":"trace[470197779] transaction","detail":"{read_only:false; response_revision:5733; number_of_response:1; }","duration":"166.692454ms","start":"2026-08-15T02:44:17.097783Z","end":"2026-08-15T02:44:17.264476Z","steps":["trace[470197779] 'process raft request' (duration: 158.164162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:17.264707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.439366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-15T02:44:17.264781Z","caller":"traceutil/trace.go:171","msg":"trace[1542257701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5733; }","duration":"127.593518ms","start":"2026-08-15T02:44:17.137172Z","end":"2026-08-15T02:44:17.264765Z","steps":["trace[1542257701] 'agreement among raft nodes before linearized reading' (duration: 127.309412ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:17.491018Z","caller":"traceutil/trace.go:171","msg":"trace[569978596] transaction","detail":"{read_only:false; response_revision:5735; number_of_response:1; }","duration":"222.69685ms","start":"2026-08-15T02:44:17.268292Z","end":"2026-08-15T02:44:17.490989Z","steps":["trace[569978596] 'process raft request' (duration: 193.179535ms)","trace[569978596] 'compare' (duration: 29.289149ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:17.491309Z","caller":"traceutil/trace.go:171","msg":"trace[1780791104] transaction","detail":"{read_only:false; response_revision:5736; number_of_response:1; }","duration":"222.055154ms","start":"2026-08-15T02:44:17.269242Z","end":"2026-08-15T02:44:17.491297Z","steps":["trace[1780791104] 'process raft request' (duration: 221.697975ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:17.491361Z","caller":"traceutil/trace.go:171","msg":"trace[1626349423] linearizableReadLoop","detail":"{readStateIndex:5984; appliedIndex:5981; }","duration":"206.171249ms","start":"2026-08-15T02:44:17.285001Z","end":"2026-08-15T02:44:17.491173Z","steps":["trace[1626349423] 'read index received' (duration: 74.005255ms)","trace[1626349423] 'applied index is now lower than readState.Index' (duration: 132.163814ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:17.491611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.589259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-08-15T02:44:17.491655Z","caller":"traceutil/trace.go:171","msg":"trace[280743021] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5736; }","duration":"206.673931ms","start":"2026-08-15T02:44:17.284968Z","end":"2026-08-15T02:44:17.491642Z","steps":["trace[280743021] 'agreement among raft nodes before linearized reading' (duration: 206.470826ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:19.208624Z","caller":"traceutil/trace.go:171","msg":"trace[2144541263] transaction","detail":"{read_only:false; response_revision:5744; number_of_response:1; }","duration":"114.665711ms","start":"2026-08-15T02:44:19.093939Z","end":"2026-08-15T02:44:19.208604Z","steps":["trace[2144541263] 'process raft request' (duration: 114.527208ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:19.573516Z","caller":"traceutil/trace.go:171","msg":"trace[1667496630] linearizableReadLoop","detail":"{readStateIndex:5993; appliedIndex:5992; }","duration":"122.150426ms","start":"2026-08-15T02:44:19.451337Z","end":"2026-08-15T02:44:19.573487Z","steps":["trace[1667496630] 'read index received' (duration: 121.827208ms)","trace[1667496630] 'applied index is now lower than readState.Index' (duration: 321.918µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:19.573853Z","caller":"traceutil/trace.go:171","msg":"trace[732259973] transaction","detail":"{read_only:false; response_revision:5745; number_of_response:1; }","duration":"200.208679ms","start":"2026-08-15T02:44:19.373603Z","end":"2026-08-15T02:44:19.573811Z","steps":["trace[732259973] 'process raft request' (duration: 199.689136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:19.574069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.665009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:19.57415Z","caller":"traceutil/trace.go:171","msg":"trace[2040675767] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:5745; }","duration":"122.839752ms","start":"2026-08-15T02:44:19.451293Z","end":"2026-08-15T02:44:19.574133Z","steps":["trace[2040675767] 'agreement among raft nodes before linearized reading' (duration: 122.589846ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:20.532196Z","caller":"traceutil/trace.go:171","msg":"trace[832362459] transaction","detail":"{read_only:false; response_revision:5750; number_of_response:1; }","duration":"238.053429ms","start":"2026-08-15T02:44:20.294111Z","end":"2026-08-15T02:44:20.532165Z","steps":["trace[832362459] 'process raft request' (duration: 201.839025ms)","trace[832362459] 'compare' (duration: 35.945588ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:20.571246Z","caller":"traceutil/trace.go:171","msg":"trace[2060068222] transaction","detail":"{read_only:false; response_revision:5751; number_of_response:1; }","duration":"273.363353ms","start":"2026-08-15T02:44:20.297862Z","end":"2026-08-15T02:44:20.571226Z","steps":["trace[2060068222] 'process raft request' (duration: 273.209478ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:20.751565Z","caller":"traceutil/trace.go:171","msg":"trace[855308793] transaction","detail":"{read_only:false; response_revision:5752; number_of_response:1; }","duration":"176.628993ms","start":"2026-08-15T02:44:20.574892Z","end":"2026-08-15T02:44:20.751521Z","steps":["trace[855308793] 'process raft request' (duration: 83.514402ms)","trace[855308793] 'compare' (duration: 92.629348ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:20.75159Z","caller":"traceutil/trace.go:171","msg":"trace[1248032438] transaction","detail":"{read_only:false; response_revision:5753; number_of_response:1; }","duration":"114.103498ms","start":"2026-08-15T02:44:20.63746Z","end":"2026-08-15T02:44:20.751544Z","steps":["trace[1248032438] 'process raft request' (duration: 113.78438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:21.010932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.968704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:21.011062Z","caller":"traceutil/trace.go:171","msg":"trace[1385827696] linearizableReadLoop","detail":"{readStateIndex:6002; appliedIndex:6001; }","duration":"242.017189ms","start":"2026-08-15T02:44:20.769023Z","end":"2026-08-15T02:44:21.01104Z","steps":["trace[1385827696] 'read index received' (duration: 110.966042ms)","trace[1385827696] 'applied index is now lower than readState.Index' (duration: 131.049417ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:21.011145Z","caller":"traceutil/trace.go:171","msg":"trace[1866261845] transaction","detail":"{read_only:false; response_revision:5754; number_of_response:1; }","duration":"255.613213ms","start":"2026-08-15T02:44:20.755495Z","end":"2026-08-15T02:44:21.011108Z","steps":["trace[1866261845] 'process raft request' (duration: 124.366952ms)","trace[1866261845] 'compare' (duration: 130.744179ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:21.011291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.260484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-15T02:44:21.011609Z","caller":"traceutil/trace.go:171","msg":"trace[2011834034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5754; }","duration":"242.594542ms","start":"2026-08-15T02:44:20.768995Z","end":"2026-08-15T02:44:21.01159Z","steps":["trace[2011834034] 'agreement among raft nodes before linearized reading' (duration: 242.160231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:21.011633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.780235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-15T02:44:21.011348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.963382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:21.011697Z","caller":"traceutil/trace.go:171","msg":"trace[1024617218] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5754; }","duration":"114.869298ms","start":"2026-08-15T02:44:20.896814Z","end":"2026-08-15T02:44:21.011683Z","steps":["trace[1024617218] 'agreement among raft nodes before linearized reading' (duration: 114.641541ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:21.01172Z","caller":"traceutil/trace.go:171","msg":"trace[2064212453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5754; }","duration":"222.353472ms","start":"2026-08-15T02:44:20.789338Z","end":"2026-08-15T02:44:21.011692Z","steps":["trace[2064212453] 'agreement among raft nodes before linearized reading' (duration: 221.981002ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:21.234042Z","caller":"traceutil/trace.go:171","msg":"trace[1499659146] linearizableReadLoop","detail":"{readStateIndex:6004; appliedIndex:6002; }","duration":"195.589027ms","start":"2026-08-15T02:44:21.03843Z","end":"2026-08-15T02:44:21.234019Z","steps":["trace[1499659146] 'read index received' (duration: 141.598577ms)","trace[1499659146] 'applied index is now lower than readState.Index' (duration: 53.98934ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:21.234112Z","caller":"traceutil/trace.go:171","msg":"trace[92517945] transaction","detail":"{read_only:false; response_revision:5756; number_of_response:1; }","duration":"216.742269ms","start":"2026-08-15T02:44:21.017331Z","end":"2026-08-15T02:44:21.234073Z","steps":["trace[92517945] 'process raft request' (duration: 216.623745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:21.234201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.751741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:44:21.234245Z","caller":"traceutil/trace.go:171","msg":"trace[1829696618] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5756; }","duration":"195.845073ms","start":"2026-08-15T02:44:21.038389Z","end":"2026-08-15T02:44:21.234234Z","steps":["trace[1829696618] 'agreement among raft nodes before linearized reading' (duration: 195.741491ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:21.234338Z","caller":"traceutil/trace.go:171","msg":"trace[1027790796] transaction","detail":"{read_only:false; response_revision:5755; number_of_response:1; }","duration":"217.839305ms","start":"2026-08-15T02:44:21.01647Z","end":"2026-08-15T02:44:21.234309Z","steps":["trace[1027790796] 'process raft request' (duration: 163.442586ms)","trace[1027790796] 'compare' (duration: 53.883806ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:21.270171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.880839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:21.270237Z","caller":"traceutil/trace.go:171","msg":"trace[964833455] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:5757; }","duration":"144.99919ms","start":"2026-08-15T02:44:21.125222Z","end":"2026-08-15T02:44:21.270221Z","steps":["trace[964833455] 'agreement among raft nodes before linearized reading' (duration: 144.870427ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:21.908942Z","caller":"traceutil/trace.go:171","msg":"trace[721081647] linearizableReadLoop","detail":"{readStateIndex:6010; appliedIndex:6009; }","duration":"119.021251ms","start":"2026-08-15T02:44:21.789897Z","end":"2026-08-15T02:44:21.908919Z","steps":["trace[721081647] 'read index received' (duration: 74.284389ms)","trace[721081647] 'applied index is now lower than readState.Index' (duration: 44.735862ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:21.909035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.128284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:21.909003Z","caller":"traceutil/trace.go:171","msg":"trace[1262830161] transaction","detail":"{read_only:false; response_revision:5761; number_of_response:1; }","duration":"233.858519ms","start":"2026-08-15T02:44:21.675109Z","end":"2026-08-15T02:44:21.908968Z","steps":["trace[1262830161] 'process raft request' (duration: 189.130588ms)","trace[1262830161] 'compare' (duration: 44.565317ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:21.909053Z","caller":"traceutil/trace.go:171","msg":"trace[2029235694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5761; }","duration":"119.203776ms","start":"2026-08-15T02:44:21.789844Z","end":"2026-08-15T02:44:21.909047Z","steps":["trace[2029235694] 'agreement among raft nodes before linearized reading' (duration: 119.148855ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:22.703471Z","caller":"traceutil/trace.go:171","msg":"trace[279579005] linearizableReadLoop","detail":"{readStateIndex:6011; appliedIndex:6010; }","duration":"128.875314ms","start":"2026-08-15T02:44:22.574572Z","end":"2026-08-15T02:44:22.703447Z","steps":["trace[279579005] 'read index received' (duration: 128.66593ms)","trace[279579005] 'applied index is now lower than readState.Index' (duration: 208.554µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:22.703792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.192852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-15T02:44:22.703852Z","caller":"traceutil/trace.go:171","msg":"trace[922223905] transaction","detail":"{read_only:false; response_revision:5762; number_of_response:1; }","duration":"129.615984ms","start":"2026-08-15T02:44:22.574213Z","end":"2026-08-15T02:44:22.703829Z","steps":["trace[922223905] 'process raft request' (duration: 129.094451ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:22.703861Z","caller":"traceutil/trace.go:171","msg":"trace[1090407967] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5762; }","duration":"129.304705ms","start":"2026-08-15T02:44:22.57454Z","end":"2026-08-15T02:44:22.703845Z","steps":["trace[1090407967] 'agreement among raft nodes before linearized reading' (duration: 129.0795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:22.705732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.739431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:22.70578Z","caller":"traceutil/trace.go:171","msg":"trace[1268811437] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5762; }","duration":"119.831143ms","start":"2026-08-15T02:44:22.585937Z","end":"2026-08-15T02:44:22.705768Z","steps":["trace[1268811437] 'agreement among raft nodes before linearized reading' (duration: 117.813293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.205459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.354017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-15T02:44:23.205682Z","caller":"traceutil/trace.go:171","msg":"trace[1395119691] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5762; }","duration":"493.233536ms","start":"2026-08-15T02:44:22.712391Z","end":"2026-08-15T02:44:23.205625Z","steps":["trace[1395119691] 'process raft request' (duration: 314.641414ms)","trace[1395119691] 'compare' (duration: 178.299365ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:23.206007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:22.712372Z","time spent":"493.545705ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-15T02:44:23.211272Z","caller":"traceutil/trace.go:171","msg":"trace[1446475103] linearizableReadLoop","detail":"{readStateIndex:6013; appliedIndex:6011; }","duration":"455.249864ms","start":"2026-08-15T02:44:22.756001Z","end":"2026-08-15T02:44:23.211251Z","steps":["trace[1446475103] 'read index received' (duration: 270.978813ms)","trace[1446475103] 'applied index is now lower than readState.Index' (duration: 184.269701ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:23.211375Z","caller":"traceutil/trace.go:171","msg":"trace[1615682585] transaction","detail":"{read_only:false; response_revision:5763; number_of_response:1; }","duration":"497.600944ms","start":"2026-08-15T02:44:22.71376Z","end":"2026-08-15T02:44:23.211361Z","steps":["trace[1615682585] 'process raft request' (duration: 497.290786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.211549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.534712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-15T02:44:23.211611Z","caller":"traceutil/trace.go:171","msg":"trace[1410859214] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5763; }","duration":"455.674115ms","start":"2026-08-15T02:44:22.755922Z","end":"2026-08-15T02:44:23.211596Z","steps":["trace[1410859214] 'agreement among raft nodes before linearized reading' (duration: 455.461919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.211652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:22.755883Z","time spent":"455.761456ms","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-15T02:44:23.211778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.84364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:44:23.211838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:22.713739Z","time spent":"497.756408ms","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:23.21187Z","caller":"traceutil/trace.go:171","msg":"trace[772631011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5763; }","duration":"418.977252ms","start":"2026-08-15T02:44:22.792874Z","end":"2026-08-15T02:44:23.211851Z","steps":["trace[772631011] 'agreement among raft nodes before linearized reading' (duration: 418.84964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.211927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:22.792858Z","time spent":"419.057965ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-15T02:44:23.21182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.280679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-08-15T02:44:23.212188Z","caller":"traceutil/trace.go:171","msg":"trace[391320760] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5763; }","duration":"123.692628ms","start":"2026-08-15T02:44:23.088475Z","end":"2026-08-15T02:44:23.212168Z","steps":["trace[391320760] 'agreement among raft nodes before linearized reading' (duration: 123.261968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.212327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.510313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T02:44:23.212374Z","caller":"traceutil/trace.go:171","msg":"trace[1970529751] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5763; }","duration":"194.622605ms","start":"2026-08-15T02:44:23.017739Z","end":"2026-08-15T02:44:23.212362Z","steps":["trace[1970529751] 'agreement among raft nodes before linearized reading' (duration: 194.503622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.212455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.193934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-15T02:44:23.212574Z","caller":"traceutil/trace.go:171","msg":"trace[1782961782] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5763; }","duration":"196.443769ms","start":"2026-08-15T02:44:23.01611Z","end":"2026-08-15T02:44:23.212554Z","steps":["trace[1782961782] 'agreement among raft nodes before linearized reading' (duration: 195.942196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.644951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.748447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:23.645316Z","caller":"traceutil/trace.go:171","msg":"trace[1617954708] transaction","detail":"{read_only:false; response_revision:5765; number_of_response:1; }","duration":"427.388355ms","start":"2026-08-15T02:44:23.217874Z","end":"2026-08-15T02:44:23.645262Z","steps":["trace[1617954708] 'process raft request' (duration: 179.242719ms)","trace[1617954708] 'compare' (duration: 247.616623ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:23.645333Z","caller":"traceutil/trace.go:171","msg":"trace[901396448] transaction","detail":"{read_only:false; response_revision:5766; number_of_response:1; }","duration":"426.672267ms","start":"2026-08-15T02:44:23.218643Z","end":"2026-08-15T02:44:23.645315Z","steps":["trace[901396448] 'process raft request' (duration: 426.525933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.645553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.218632Z","time spent":"426.859851ms","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:23.645437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.217866Z","time spent":"427.512267ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:23.645876Z","caller":"traceutil/trace.go:171","msg":"trace[1834686598] transaction","detail":"{read_only:false; response_revision:5769; number_of_response:1; }","duration":"371.387703ms","start":"2026-08-15T02:44:23.274457Z","end":"2026-08-15T02:44:23.645845Z","steps":["trace[1834686598] 'process raft request' (duration: 371.30318ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:23.645957Z","caller":"traceutil/trace.go:171","msg":"trace[1203525518] transaction","detail":"{read_only:false; response_revision:5768; number_of_response:1; }","duration":"423.898729ms","start":"2026-08-15T02:44:23.222038Z","end":"2026-08-15T02:44:23.645937Z","steps":["trace[1203525518] 'process raft request' (duration: 423.601281ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:23.646045Z","caller":"traceutil/trace.go:171","msg":"trace[2108421640] transaction","detail":"{read_only:false; response_revision:5767; number_of_response:1; }","duration":"426.755838ms","start":"2026-08-15T02:44:23.219233Z","end":"2026-08-15T02:44:23.645989Z","steps":["trace[2108421640] 'process raft request' (duration: 426.019041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.646108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.274435Z","time spent":"371.531336ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:23.646124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.222016Z","time spent":"424.054912ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16842,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:23.646196Z","caller":"traceutil/trace.go:171","msg":"trace[1051269229] linearizableReadLoop","detail":"{readStateIndex:6018; appliedIndex:6013; }","duration":"408.624965ms","start":"2026-08-15T02:44:23.237556Z","end":"2026-08-15T02:44:23.646181Z","steps":["trace[1051269229] 'read index received' (duration: 5.401472ms)","trace[1051269229] 'applied index is now lower than readState.Index' (duration: 403.221573ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:23.64644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.8786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-15T02:44:23.646565Z","caller":"traceutil/trace.go:171","msg":"trace[1689002703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5769; }","duration":"409.031395ms","start":"2026-08-15T02:44:23.237514Z","end":"2026-08-15T02:44:23.646546Z","steps":["trace[1689002703] 'agreement among raft nodes before linearized reading' (duration: 408.787039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.646654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.237497Z","time spent":"409.138158ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-15T02:44:23.646944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.367563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-15T02:44:23.647038Z","caller":"traceutil/trace.go:171","msg":"trace[648169995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5769; }","duration":"409.492696ms","start":"2026-08-15T02:44:23.23751Z","end":"2026-08-15T02:44:23.647003Z","steps":["trace[648169995] 'agreement among raft nodes before linearized reading' (duration: 408.913842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.647087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.237491Z","time spent":"409.585529ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-15T02:44:23.64789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.219227Z","time spent":"428.580763ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:23.995465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.108444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:23.995681Z","caller":"traceutil/trace.go:171","msg":"trace[224447111] linearizableReadLoop","detail":"{readStateIndex:6022; appliedIndex:6020; }","duration":"273.457625ms","start":"2026-08-15T02:44:23.722207Z","end":"2026-08-15T02:44:23.995665Z","steps":["trace[224447111] 'read index received' (duration: 52.193198ms)","trace[224447111] 'applied index is now lower than readState.Index' (duration: 221.262997ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:23.995769Z","caller":"traceutil/trace.go:171","msg":"trace[94441386] transaction","detail":"{read_only:false; response_revision:5772; number_of_response:1; }","duration":"342.68233ms","start":"2026-08-15T02:44:23.653077Z","end":"2026-08-15T02:44:23.995759Z","steps":["trace[94441386] 'process raft request' (duration: 342.485175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.995829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.653063Z","time spent":"342.730392ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:23.99586Z","caller":"traceutil/trace.go:171","msg":"trace[2012857175] transaction","detail":"{read_only:false; response_revision:5771; number_of_response:1; }","duration":"342.855115ms","start":"2026-08-15T02:44:23.652976Z","end":"2026-08-15T02:44:23.995831Z","steps":["trace[2012857175] 'process raft request' (duration: 121.29381ms)","trace[2012857175] 'compare' (duration: 220.984321ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:23.995993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.652968Z","time spent":"342.953977ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:44:23.996243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.622467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-08-15T02:44:23.996289Z","caller":"traceutil/trace.go:171","msg":"trace[1420124700] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5773; }","duration":"328.704358ms","start":"2026-08-15T02:44:23.667572Z","end":"2026-08-15T02:44:23.996277Z","steps":["trace[1420124700] 'agreement among raft nodes before linearized reading' (duration: 328.39198ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:23.996286Z","caller":"traceutil/trace.go:171","msg":"trace[308977563] transaction","detail":"{read_only:false; response_revision:5773; number_of_response:1; }","duration":"269.506569ms","start":"2026-08-15T02:44:23.726766Z","end":"2026-08-15T02:44:23.996272Z","steps":["trace[308977563] 'process raft request' (duration: 268.856343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.996325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.66755Z","time spent":"328.766899ms","remote":"127.0.0.1:49850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11520,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-15T02:44:23.99684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.412009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-15T02:44:23.996915Z","caller":"traceutil/trace.go:171","msg":"trace[1783412621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5773; }","duration":"341.508741ms","start":"2026-08-15T02:44:23.655388Z","end":"2026-08-15T02:44:23.996897Z","steps":["trace[1783412621] 'agreement among raft nodes before linearized reading' (duration: 340.594358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:23.996958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:23.655381Z","time spent":"341.567773ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-15T02:44:24.132644Z","caller":"traceutil/trace.go:171","msg":"trace[912991961] linearizableReadLoop","detail":"{readStateIndex:6025; appliedIndex:6023; }","duration":"125.030543ms","start":"2026-08-15T02:44:24.007589Z","end":"2026-08-15T02:44:24.13262Z","steps":["trace[912991961] 'read index received' (duration: 32.382811ms)","trace[912991961] 'applied index is now lower than readState.Index' (duration: 92.646912ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:24.132838Z","caller":"traceutil/trace.go:171","msg":"trace[1732174078] transaction","detail":"{read_only:false; response_revision:5775; number_of_response:1; }","duration":"131.119171ms","start":"2026-08-15T02:44:24.001686Z","end":"2026-08-15T02:44:24.132805Z","steps":["trace[1732174078] 'process raft request' (duration: 107.610268ms)","trace[1732174078] 'compare' (duration: 23.214906ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:24.132863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.251418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-08-15T02:44:24.133128Z","caller":"traceutil/trace.go:171","msg":"trace[1021565620] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5775; }","duration":"125.558475ms","start":"2026-08-15T02:44:24.007555Z","end":"2026-08-15T02:44:24.133113Z","steps":["trace[1021565620] 'agreement among raft nodes before linearized reading' (duration: 125.154895ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:24.360616Z","caller":"traceutil/trace.go:171","msg":"trace[306212109] transaction","detail":"{read_only:false; response_revision:5776; number_of_response:1; }","duration":"120.719497ms","start":"2026-08-15T02:44:24.239864Z","end":"2026-08-15T02:44:24.360583Z","steps":["trace[306212109] 'process raft request' (duration: 81.253013ms)","trace[306212109] 'compare' (duration: 39.194727ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:25.778302Z","caller":"traceutil/trace.go:171","msg":"trace[1160432551] linearizableReadLoop","detail":"{readStateIndex:6029; appliedIndex:6028; }","duration":"125.465774ms","start":"2026-08-15T02:44:25.65281Z","end":"2026-08-15T02:44:25.778275Z","steps":["trace[1160432551] 'read index received' (duration: 125.277699ms)","trace[1160432551] 'applied index is now lower than readState.Index' (duration: 186.725µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:25.778433Z","caller":"traceutil/trace.go:171","msg":"trace[1443370600] transaction","detail":"{read_only:false; response_revision:5779; number_of_response:1; }","duration":"126.250903ms","start":"2026-08-15T02:44:25.652172Z","end":"2026-08-15T02:44:25.778423Z","steps":["trace[1443370600] 'process raft request' (duration: 125.933866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:25.778673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.844943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-08-15T02:44:25.778735Z","caller":"traceutil/trace.go:171","msg":"trace[1514727971] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5779; }","duration":"125.929646ms","start":"2026-08-15T02:44:25.652774Z","end":"2026-08-15T02:44:25.778704Z","steps":["trace[1514727971] 'agreement among raft nodes before linearized reading' (duration: 125.783452ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:26.146041Z","caller":"traceutil/trace.go:171","msg":"trace[1334592099] transaction","detail":"{read_only:false; response_revision:5784; number_of_response:1; }","duration":"142.416107ms","start":"2026-08-15T02:44:26.003602Z","end":"2026-08-15T02:44:26.146018Z","steps":["trace[1334592099] 'process raft request' (duration: 142.13271ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:26.146267Z","caller":"traceutil/trace.go:171","msg":"trace[462364933] linearizableReadLoop","detail":"{readStateIndex:6035; appliedIndex:6033; }","duration":"102.750065ms","start":"2026-08-15T02:44:26.043479Z","end":"2026-08-15T02:44:26.146229Z","steps":["trace[462364933] 'read index received' (duration: 102.176011ms)","trace[462364933] 'applied index is now lower than readState.Index' (duration: 572.924µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:26.146415Z","caller":"traceutil/trace.go:171","msg":"trace[110593141] transaction","detail":"{read_only:false; response_revision:5785; number_of_response:1; }","duration":"140.197693ms","start":"2026-08-15T02:44:26.006201Z","end":"2026-08-15T02:44:26.146399Z","steps":["trace[110593141] 'process raft request' (duration: 139.746842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:26.146593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.089943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-15T02:44:26.146674Z","caller":"traceutil/trace.go:171","msg":"trace[227063432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5785; }","duration":"103.207946ms","start":"2026-08-15T02:44:26.043449Z","end":"2026-08-15T02:44:26.146657Z","steps":["trace[227063432] 'agreement among raft nodes before linearized reading' (duration: 102.985739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:26.796654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.916878ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-15T02:44:28.006783Z","caller":"traceutil/trace.go:171","msg":"trace[1215426032] linearizableReadLoop","detail":"{readStateIndex:6042; appliedIndex:6041; }","duration":"117.771821ms","start":"2026-08-15T02:44:27.888987Z","end":"2026-08-15T02:44:28.006758Z","steps":["trace[1215426032] 'read index received' (duration: 63.498007ms)","trace[1215426032] 'applied index is now lower than readState.Index' (duration: 54.272724ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:28.007003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.992355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-08-15T02:44:28.007002Z","caller":"traceutil/trace.go:171","msg":"trace[2137815497] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"118.897067ms","start":"2026-08-15T02:44:27.888071Z","end":"2026-08-15T02:44:28.006968Z","steps":["trace[2137815497] 'process raft request' (duration: 64.47091ms)","trace[2137815497] 'compare' (duration: 54.099149ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:28.007068Z","caller":"traceutil/trace.go:171","msg":"trace[199359799] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5791; }","duration":"118.118659ms","start":"2026-08-15T02:44:27.888929Z","end":"2026-08-15T02:44:28.007048Z","steps":["trace[199359799] 'agreement among raft nodes before linearized reading' (duration: 117.925344ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:28.425304Z","caller":"traceutil/trace.go:171","msg":"trace[1710704332] linearizableReadLoop","detail":"{readStateIndex:6046; appliedIndex:6045; }","duration":"207.922838ms","start":"2026-08-15T02:44:28.217357Z","end":"2026-08-15T02:44:28.42528Z","steps":["trace[1710704332] 'read index received' (duration: 155.921053ms)","trace[1710704332] 'applied index is now lower than readState.Index' (duration: 52.000845ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:28.425417Z","caller":"traceutil/trace.go:171","msg":"trace[605433319] transaction","detail":"{read_only:false; response_revision:5795; number_of_response:1; }","duration":"238.400034ms","start":"2026-08-15T02:44:28.187006Z","end":"2026-08-15T02:44:28.425406Z","steps":["trace[605433319] 'process raft request' (duration: 186.353239ms)","trace[605433319] 'compare' (duration: 51.769988ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:28.425623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.220815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-15T02:44:28.425654Z","caller":"traceutil/trace.go:171","msg":"trace[107675855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5795; }","duration":"208.330728ms","start":"2026-08-15T02:44:28.217315Z","end":"2026-08-15T02:44:28.425646Z","steps":["trace[107675855] 'agreement among raft nodes before linearized reading' (duration: 208.192585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:28.425804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.692463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-15T02:44:28.425898Z","caller":"traceutil/trace.go:171","msg":"trace[199264728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5795; }","duration":"183.815816ms","start":"2026-08-15T02:44:28.242065Z","end":"2026-08-15T02:44:28.425881Z","steps":["trace[199264728] 'agreement among raft nodes before linearized reading' (duration: 183.632633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:28.426036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.73786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-15T02:44:28.426164Z","caller":"traceutil/trace.go:171","msg":"trace[2039667358] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5795; }","duration":"182.901885ms","start":"2026-08-15T02:44:28.243243Z","end":"2026-08-15T02:44:28.426145Z","steps":["trace[2039667358] 'agreement among raft nodes before linearized reading' (duration: 182.67821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:29.613584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.016922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-15T02:44:29.613706Z","caller":"traceutil/trace.go:171","msg":"trace[433239777] linearizableReadLoop","detail":"{readStateIndex:6052; appliedIndex:6051; }","duration":"320.545605ms","start":"2026-08-15T02:44:29.29314Z","end":"2026-08-15T02:44:29.613686Z","steps":["trace[433239777] 'read index received' (duration: 145.128313ms)","trace[433239777] 'applied index is now lower than readState.Index' (duration: 175.414852ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:29.613909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.75619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-08-15T02:44:29.613965Z","caller":"traceutil/trace.go:171","msg":"trace[1353479297] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5800; }","duration":"320.860302ms","start":"2026-08-15T02:44:29.293091Z","end":"2026-08-15T02:44:29.613951Z","steps":["trace[1353479297] 'agreement among raft nodes before linearized reading' (duration: 320.644677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:29.614017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:29.293062Z","time spent":"320.943684ms","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-08-15T02:44:29.613752Z","caller":"traceutil/trace.go:171","msg":"trace[53254659] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5800; }","duration":"343.976638ms","start":"2026-08-15T02:44:29.269734Z","end":"2026-08-15T02:44:29.61371Z","steps":["trace[53254659] 'process raft request' (duration: 168.761251ms)","trace[53254659] 'compare' (duration: 174.94582ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:29.614671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:29.269711Z","time spent":"344.603694ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:44:29.944773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.551375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:29.945006Z","caller":"traceutil/trace.go:171","msg":"trace[145427282] transaction","detail":"{read_only:false; response_revision:5802; number_of_response:1; }","duration":"319.93069ms","start":"2026-08-15T02:44:29.625041Z","end":"2026-08-15T02:44:29.944971Z","steps":["trace[145427282] 'process raft request' (duration: 203.067797ms)","trace[145427282] 'compare' (duration: 116.3542ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:29.945167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:29.625025Z","time spent":"320.064453ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16842,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:44:29.945028Z","caller":"traceutil/trace.go:171","msg":"trace[774685275] linearizableReadLoop","detail":"{readStateIndex:6054; appliedIndex:6052; }","duration":"299.565688ms","start":"2026-08-15T02:44:29.64544Z","end":"2026-08-15T02:44:29.945006Z","steps":["trace[774685275] 'read index received' (duration: 22.05149ms)","trace[774685275] 'applied index is now lower than readState.Index' (duration: 277.502018ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:29.945387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.307141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-08-15T02:44:29.945443Z","caller":"traceutil/trace.go:171","msg":"trace[511597181] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:5803; }","duration":"249.425483ms","start":"2026-08-15T02:44:29.696005Z","end":"2026-08-15T02:44:29.94543Z","steps":["trace[511597181] 'agreement among raft nodes before linearized reading' (duration: 249.32276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:29.945498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.046722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:29.945703Z","caller":"traceutil/trace.go:171","msg":"trace[1123902546] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5803; }","duration":"300.276826ms","start":"2026-08-15T02:44:29.645385Z","end":"2026-08-15T02:44:29.945662Z","steps":["trace[1123902546] 'agreement among raft nodes before linearized reading' (duration: 299.684082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:29.945809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:29.645371Z","time spent":"300.370688ms","remote":"127.0.0.1:59788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:44:29.945766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.194865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:120966"} {"level":"warn","ts":"2026-08-15T02:44:29.945528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.247678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:29.945926Z","caller":"traceutil/trace.go:171","msg":"trace[1621459612] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5803; }","duration":"246.37678ms","start":"2026-08-15T02:44:29.69953Z","end":"2026-08-15T02:44:29.945907Z","steps":["trace[1621459612] 'agreement among raft nodes before linearized reading' (duration: 245.99868ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:29.946035Z","caller":"traceutil/trace.go:171","msg":"trace[1507826848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5803; }","duration":"153.791562ms","start":"2026-08-15T02:44:29.792181Z","end":"2026-08-15T02:44:29.945973Z","steps":["trace[1507826848] 'agreement among raft nodes before linearized reading' (duration: 153.320211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:30.197973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.23884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:30.198128Z","caller":"traceutil/trace.go:171","msg":"trace[1305964268] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5804; }","duration":"146.442944ms","start":"2026-08-15T02:44:30.051668Z","end":"2026-08-15T02:44:30.198111Z","steps":["trace[1305964268] 'count revisions from in-memory index tree' (duration: 146.166089ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:32.386592Z","caller":"traceutil/trace.go:171","msg":"trace[1144693381] linearizableReadLoop","detail":"{readStateIndex:6071; appliedIndex:6070; }","duration":"149.837255ms","start":"2026-08-15T02:44:32.236726Z","end":"2026-08-15T02:44:32.386563Z","steps":["trace[1144693381] 'read index received' (duration: 129.699985ms)","trace[1144693381] 'applied index is now lower than readState.Index' (duration: 20.13583ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:32.38667Z","caller":"traceutil/trace.go:171","msg":"trace[1396658439] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"258.848788ms","start":"2026-08-15T02:44:32.127768Z","end":"2026-08-15T02:44:32.386617Z","steps":["trace[1396658439] 'process raft request' (duration: 238.688147ms)","trace[1396658439] 'compare' (duration: 19.752371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:32.386848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.826035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-08-15T02:44:32.386837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.088672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-15T02:44:32.38689Z","caller":"traceutil/trace.go:171","msg":"trace[1887468656] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5818; }","duration":"105.922787ms","start":"2026-08-15T02:44:32.280955Z","end":"2026-08-15T02:44:32.386878Z","steps":["trace[1887468656] 'agreement among raft nodes before linearized reading' (duration: 105.754374ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:32.38691Z","caller":"traceutil/trace.go:171","msg":"trace[1000986413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5818; }","duration":"150.205324ms","start":"2026-08-15T02:44:32.236686Z","end":"2026-08-15T02:44:32.386891Z","steps":["trace[1000986413] 'agreement among raft nodes before linearized reading' (duration: 149.972939ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:32.572377Z","caller":"traceutil/trace.go:171","msg":"trace[920171203] linearizableReadLoop","detail":"{readStateIndex:6074; appliedIndex:6073; }","duration":"100.468217ms","start":"2026-08-15T02:44:32.471884Z","end":"2026-08-15T02:44:32.572352Z","steps":["trace[920171203] 'read index received' (duration: 28.113121ms)","trace[920171203] 'applied index is now lower than readState.Index' (duration: 72.354216ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:32.572524Z","caller":"traceutil/trace.go:171","msg":"trace[1332850429] transaction","detail":"{read_only:false; response_revision:5821; number_of_response:1; }","duration":"103.852479ms","start":"2026-08-15T02:44:32.468616Z","end":"2026-08-15T02:44:32.572468Z","steps":["trace[1332850429] 'process raft request' (duration: 31.46195ms)","trace[1332850429] 'compare' (duration: 72.145972ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:32.57269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.779014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-15T02:44:32.573392Z","caller":"traceutil/trace.go:171","msg":"trace[503938734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5821; }","duration":"101.462322ms","start":"2026-08-15T02:44:32.471866Z","end":"2026-08-15T02:44:32.573328Z","steps":["trace[503938734] 'agreement among raft nodes before linearized reading' (duration: 100.642242ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:32.741942Z","caller":"traceutil/trace.go:171","msg":"trace[1461193522] linearizableReadLoop","detail":"{readStateIndex:6076; appliedIndex:6074; }","duration":"137.011259ms","start":"2026-08-15T02:44:32.604904Z","end":"2026-08-15T02:44:32.741916Z","steps":["trace[1461193522] 'read index received' (duration: 26.532473ms)","trace[1461193522] 'applied index is now lower than readState.Index' (duration: 110.478176ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:32.74197Z","caller":"traceutil/trace.go:171","msg":"trace[385931813] transaction","detail":"{read_only:false; response_revision:5823; number_of_response:1; }","duration":"164.311821ms","start":"2026-08-15T02:44:32.577618Z","end":"2026-08-15T02:44:32.74193Z","steps":["trace[385931813] 'process raft request' (duration: 95.086539ms)","trace[385931813] 'compare' (duration: 69.092049ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:32.742127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.209554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-15T02:44:32.742176Z","caller":"traceutil/trace.go:171","msg":"trace[1693872361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5823; }","duration":"137.300117ms","start":"2026-08-15T02:44:32.604864Z","end":"2026-08-15T02:44:32.742164Z","steps":["trace[1693872361] 'agreement among raft nodes before linearized reading' (duration: 137.130893ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:32.881781Z","caller":"traceutil/trace.go:171","msg":"trace[934181617] linearizableReadLoop","detail":"{readStateIndex:6077; appliedIndex:6076; }","duration":"127.642195ms","start":"2026-08-15T02:44:32.754098Z","end":"2026-08-15T02:44:32.88174Z","steps":["trace[934181617] 'read index received' (duration: 92.292222ms)","trace[934181617] 'applied index is now lower than readState.Index' (duration: 35.348343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:32.882185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.050235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-08-15T02:44:32.882156Z","caller":"traceutil/trace.go:171","msg":"trace[851089514] transaction","detail":"{read_only:false; response_revision:5824; number_of_response:1; }","duration":"135.527895ms","start":"2026-08-15T02:44:32.746576Z","end":"2026-08-15T02:44:32.882104Z","steps":["trace[851089514] 'process raft request' (duration: 99.892485ms)","trace[851089514] 'compare' (duration: 34.84709ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:32.882238Z","caller":"traceutil/trace.go:171","msg":"trace[1796680478] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5824; }","duration":"128.161989ms","start":"2026-08-15T02:44:32.754061Z","end":"2026-08-15T02:44:32.882223Z","steps":["trace[1796680478] 'agreement among raft nodes before linearized reading' (duration: 127.845201ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:34.519609Z","caller":"traceutil/trace.go:171","msg":"trace[1007425813] transaction","detail":"{read_only:false; response_revision:5829; number_of_response:1; }","duration":"123.746437ms","start":"2026-08-15T02:44:34.395843Z","end":"2026-08-15T02:44:34.51959Z","steps":["trace[1007425813] 'process raft request' (duration: 123.400528ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:34.906484Z","caller":"traceutil/trace.go:171","msg":"trace[416994148] linearizableReadLoop","detail":"{readStateIndex:6087; appliedIndex:6086; }","duration":"159.69275ms","start":"2026-08-15T02:44:34.74676Z","end":"2026-08-15T02:44:34.906453Z","steps":["trace[416994148] 'read index received' (duration: 96.51349ms)","trace[416994148] 'applied index is now lower than readState.Index' (duration: 63.178049ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:34.906873Z","caller":"traceutil/trace.go:171","msg":"trace[1790000183] transaction","detail":"{read_only:false; response_revision:5834; number_of_response:1; }","duration":"211.417228ms","start":"2026-08-15T02:44:34.695435Z","end":"2026-08-15T02:44:34.906852Z","steps":["trace[1790000183] 'process raft request' (duration: 147.894491ms)","trace[1790000183] 'compare' (duration: 62.987145ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:34.907114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.328637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:34.907192Z","caller":"traceutil/trace.go:171","msg":"trace[1716444513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5834; }","duration":"115.45149ms","start":"2026-08-15T02:44:34.791724Z","end":"2026-08-15T02:44:34.907176Z","steps":["trace[1716444513] 'agreement among raft nodes before linearized reading' (duration: 115.327306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:34.90734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.573251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-15T02:44:34.907418Z","caller":"traceutil/trace.go:171","msg":"trace[270001903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5834; }","duration":"160.710494ms","start":"2026-08-15T02:44:34.74669Z","end":"2026-08-15T02:44:34.907401Z","steps":["trace[270001903] 'agreement among raft nodes before linearized reading' (duration: 160.437387ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:35.151267Z","caller":"traceutil/trace.go:171","msg":"trace[1328348607] transaction","detail":"{read_only:false; response_revision:5838; number_of_response:1; }","duration":"115.506094ms","start":"2026-08-15T02:44:35.035733Z","end":"2026-08-15T02:44:35.151239Z","steps":["trace[1328348607] 'process raft request' (duration: 115.34459ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:35.28427Z","caller":"traceutil/trace.go:171","msg":"trace[745756033] linearizableReadLoop","detail":"{readStateIndex:6092; appliedIndex:6090; }","duration":"191.211666ms","start":"2026-08-15T02:44:35.093034Z","end":"2026-08-15T02:44:35.284245Z","steps":["trace[745756033] 'read index received' (duration: 58.052334ms)","trace[745756033] 'applied index is now lower than readState.Index' (duration: 133.158112ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:35.284358Z","caller":"traceutil/trace.go:171","msg":"trace[470692623] transaction","detail":"{read_only:false; response_revision:5839; number_of_response:1; }","duration":"192.067606ms","start":"2026-08-15T02:44:35.092255Z","end":"2026-08-15T02:44:35.284323Z","steps":["trace[470692623] 'process raft request' (duration: 100.336325ms)","trace[470692623] 'compare' (duration: 91.469415ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:35.284614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.561714ms","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-08-15T02:44:35.284821Z","caller":"traceutil/trace.go:171","msg":"trace[1412636064] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5839; }","duration":"191.803559ms","start":"2026-08-15T02:44:35.093006Z","end":"2026-08-15T02:44:35.28481Z","steps":["trace[1412636064] 'agreement among raft nodes before linearized reading' (duration: 191.388549ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:35.307409Z","caller":"traceutil/trace.go:171","msg":"trace[450194271] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5839; }","duration":"150.40517ms","start":"2026-08-15T02:44:35.156977Z","end":"2026-08-15T02:44:35.307382Z","steps":["trace[450194271] 'process raft request' (duration: 150.279747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:35.60432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.249209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:35.604469Z","caller":"traceutil/trace.go:171","msg":"trace[642027802] transaction","detail":"{read_only:false; response_revision:5841; number_of_response:1; }","duration":"264.5331ms","start":"2026-08-15T02:44:35.339911Z","end":"2026-08-15T02:44:35.604444Z","steps":["trace[642027802] 'process raft request' (duration: 45.052086ms)","trace[642027802] 'compare' (duration: 218.958872ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:35.872431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.225146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-08-15T02:44:35.872517Z","caller":"traceutil/trace.go:171","msg":"trace[1592154803] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5841; }","duration":"112.368469ms","start":"2026-08-15T02:44:35.760129Z","end":"2026-08-15T02:44:35.872498Z","steps":["trace[1592154803] 'range keys from in-memory index tree' (duration: 112.027861ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:36.861178Z","caller":"traceutil/trace.go:171","msg":"trace[1008760319] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"138.050749ms","start":"2026-08-15T02:44:36.72309Z","end":"2026-08-15T02:44:36.861141Z","steps":["trace[1008760319] 'process raft request' (duration: 137.898125ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:37.562795Z","caller":"traceutil/trace.go:171","msg":"trace[785660199] transaction","detail":"{read_only:false; response_revision:5852; number_of_response:1; }","duration":"148.887094ms","start":"2026-08-15T02:44:37.413883Z","end":"2026-08-15T02:44:37.56277Z","steps":["trace[785660199] 'process raft request' (duration: 148.710201ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:38.734955Z","caller":"traceutil/trace.go:171","msg":"trace[1825028217] transaction","detail":"{read_only:false; response_revision:5856; number_of_response:1; }","duration":"112.394968ms","start":"2026-08-15T02:44:38.622523Z","end":"2026-08-15T02:44:38.734918Z","steps":["trace[1825028217] 'process raft request' (duration: 71.102728ms)","trace[1825028217] 'compare' (duration: 40.976792ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:38.97654Z","caller":"traceutil/trace.go:171","msg":"trace[630205198] linearizableReadLoop","detail":"{readStateIndex:6112; appliedIndex:6111; }","duration":"184.659155ms","start":"2026-08-15T02:44:38.791848Z","end":"2026-08-15T02:44:38.976507Z","steps":["trace[630205198] 'read index received' (duration: 146.031638ms)","trace[630205198] 'applied index is now lower than readState.Index' (duration: 38.626267ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:38.976631Z","caller":"traceutil/trace.go:171","msg":"trace[1927100731] transaction","detail":"{read_only:false; response_revision:5857; number_of_response:1; }","duration":"237.159148ms","start":"2026-08-15T02:44:38.739415Z","end":"2026-08-15T02:44:38.976574Z","steps":["trace[1927100731] 'process raft request' (duration: 198.48529ms)","trace[1927100731] 'compare' (duration: 38.462743ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:38.976766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.8876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:38.976808Z","caller":"traceutil/trace.go:171","msg":"trace[366141224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5857; }","duration":"184.994373ms","start":"2026-08-15T02:44:38.791802Z","end":"2026-08-15T02:44:38.976796Z","steps":["trace[366141224] 'agreement among raft nodes before linearized reading' (duration: 184.89411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:38.97702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.589139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-15T02:44:38.97711Z","caller":"traceutil/trace.go:171","msg":"trace[1576404895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5857; }","duration":"111.697362ms","start":"2026-08-15T02:44:38.865391Z","end":"2026-08-15T02:44:38.977088Z","steps":["trace[1576404895] 'agreement among raft nodes before linearized reading' (duration: 111.415805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:39.182936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.162229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-15T02:44:39.18304Z","caller":"traceutil/trace.go:171","msg":"trace[1225899009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5858; }","duration":"101.331352ms","start":"2026-08-15T02:44:39.081682Z","end":"2026-08-15T02:44:39.183013Z","steps":["trace[1225899009] 'range keys from in-memory index tree' (duration: 100.84602ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:39.414011Z","caller":"traceutil/trace.go:171","msg":"trace[242004633] transaction","detail":"{read_only:false; response_revision:5861; number_of_response:1; }","duration":"129.601675ms","start":"2026-08-15T02:44:39.284366Z","end":"2026-08-15T02:44:39.413968Z","steps":["trace[242004633] 'process raft request' (duration: 109.597336ms)","trace[242004633] 'compare' (duration: 19.797914ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:39.772124Z","caller":"traceutil/trace.go:171","msg":"trace[1783531078] transaction","detail":"{read_only:false; response_revision:5864; number_of_response:1; }","duration":"104.731022ms","start":"2026-08-15T02:44:39.66735Z","end":"2026-08-15T02:44:39.772081Z","steps":["trace[1783531078] 'process raft request' (duration: 72.266702ms)","trace[1783531078] 'compare' (duration: 32.314786ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:40.758082Z","caller":"traceutil/trace.go:171","msg":"trace[2019856044] linearizableReadLoop","detail":"{readStateIndex:6123; appliedIndex:6122; }","duration":"107.361357ms","start":"2026-08-15T02:44:40.650695Z","end":"2026-08-15T02:44:40.758057Z","steps":["trace[2019856044] 'read index received' (duration: 107.151922ms)","trace[2019856044] 'applied index is now lower than readState.Index' (duration: 208.385µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:40.758145Z","caller":"traceutil/trace.go:171","msg":"trace[695940429] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"132.31241ms","start":"2026-08-15T02:44:40.625807Z","end":"2026-08-15T02:44:40.758119Z","steps":["trace[695940429] 'process raft request' (duration: 132.105765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:40.758682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.967321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-08-15T02:44:40.758932Z","caller":"traceutil/trace.go:171","msg":"trace[991205227] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5868; }","duration":"108.267267ms","start":"2026-08-15T02:44:40.650642Z","end":"2026-08-15T02:44:40.758909Z","steps":["trace[991205227] 'agreement among raft nodes before linearized reading' (duration: 107.832417ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:41.044166Z","caller":"traceutil/trace.go:171","msg":"trace[1487924587] transaction","detail":"{read_only:false; response_revision:5870; number_of_response:1; }","duration":"150.252196ms","start":"2026-08-15T02:44:40.893879Z","end":"2026-08-15T02:44:41.044132Z","steps":["trace[1487924587] 'process raft request' (duration: 92.771227ms)","trace[1487924587] 'compare' (duration: 57.330406ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:41.289107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.92054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:41.289244Z","caller":"traceutil/trace.go:171","msg":"trace[1381275896] transaction","detail":"{read_only:false; response_revision:5871; number_of_response:1; }","duration":"177.238407ms","start":"2026-08-15T02:44:41.111985Z","end":"2026-08-15T02:44:41.289224Z","steps":["trace[1381275896] 'process raft request' (duration: 76.097312ms)","trace[1381275896] 'compare' (duration: 100.753596ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:41.304421Z","caller":"traceutil/trace.go:171","msg":"trace[616090404] linearizableReadLoop","detail":"{readStateIndex:6128; appliedIndex:6126; }","duration":"160.727372ms","start":"2026-08-15T02:44:41.143677Z","end":"2026-08-15T02:44:41.304404Z","steps":["trace[616090404] 'read index received' (duration: 44.391444ms)","trace[616090404] 'applied index is now lower than readState.Index' (duration: 116.334818ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:41.304494Z","caller":"traceutil/trace.go:171","msg":"trace[1502861443] transaction","detail":"{read_only:false; response_revision:5872; number_of_response:1; }","duration":"185.50997ms","start":"2026-08-15T02:44:41.118945Z","end":"2026-08-15T02:44:41.304455Z","steps":["trace[1502861443] 'process raft request' (duration: 185.316455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:41.3053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.611362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:44:41.305387Z","caller":"traceutil/trace.go:171","msg":"trace[293973526] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5872; }","duration":"161.761677ms","start":"2026-08-15T02:44:41.143601Z","end":"2026-08-15T02:44:41.305363Z","steps":["trace[293973526] 'agreement among raft nodes before linearized reading' (duration: 161.123452ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:41.521541Z","caller":"traceutil/trace.go:171","msg":"trace[304902353] linearizableReadLoop","detail":"{readStateIndex:6133; appliedIndex:6132; }","duration":"133.405656ms","start":"2026-08-15T02:44:41.388111Z","end":"2026-08-15T02:44:41.521517Z","steps":["trace[304902353] 'read index received' (duration: 59.044845ms)","trace[304902353] 'applied index is now lower than readState.Index' (duration: 74.359291ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:41.521744Z","caller":"traceutil/trace.go:171","msg":"trace[1548966767] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"186.633926ms","start":"2026-08-15T02:44:41.335089Z","end":"2026-08-15T02:44:41.521723Z","steps":["trace[1548966767] 'process raft request' (duration: 112.06756ms)","trace[1548966767] 'compare' (duration: 74.239609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:41.521962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.850657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-08-15T02:44:41.522285Z","caller":"traceutil/trace.go:171","msg":"trace[398341439] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5876; }","duration":"198.208995ms","start":"2026-08-15T02:44:41.324063Z","end":"2026-08-15T02:44:41.522272Z","steps":["trace[398341439] 'agreement among raft nodes before linearized reading' (duration: 197.711524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:41.522761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.990635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T02:44:41.522944Z","caller":"traceutil/trace.go:171","msg":"trace[626750381] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5876; }","duration":"105.171508ms","start":"2026-08-15T02:44:41.41776Z","end":"2026-08-15T02:44:41.522931Z","steps":["trace[626750381] 'agreement among raft nodes before linearized reading' (duration: 104.910533ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:41.93035Z","caller":"traceutil/trace.go:171","msg":"trace[1376595240] transaction","detail":"{read_only:false; response_revision:5880; number_of_response:1; }","duration":"116.470622ms","start":"2026-08-15T02:44:41.8138Z","end":"2026-08-15T02:44:41.930271Z","steps":["trace[1376595240] 'process raft request' (duration: 91.337886ms)","trace[1376595240] 'compare' (duration: 24.634704ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:44.141459Z","caller":"traceutil/trace.go:171","msg":"trace[26102851] transaction","detail":"{read_only:false; response_revision:5891; number_of_response:1; }","duration":"147.885768ms","start":"2026-08-15T02:44:43.993553Z","end":"2026-08-15T02:44:44.141439Z","steps":["trace[26102851] 'process raft request' (duration: 113.33783ms)","trace[26102851] 'compare' (duration: 34.302082ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:45.115226Z","caller":"traceutil/trace.go:171","msg":"trace[1561530693] transaction","detail":"{read_only:false; response_revision:5896; number_of_response:1; }","duration":"215.312479ms","start":"2026-08-15T02:44:44.899893Z","end":"2026-08-15T02:44:45.115206Z","steps":["trace[1561530693] 'process raft request' (duration: 167.450566ms)","trace[1561530693] 'compare' (duration: 47.465614ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:45.749153Z","caller":"traceutil/trace.go:171","msg":"trace[917407847] transaction","detail":"{read_only:false; response_revision:5901; number_of_response:1; }","duration":"101.279624ms","start":"2026-08-15T02:44:45.647845Z","end":"2026-08-15T02:44:45.749125Z","steps":["trace[917407847] 'process raft request' (duration: 88.421047ms)","trace[917407847] 'compare' (duration: 12.312084ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:46.28439Z","caller":"traceutil/trace.go:171","msg":"trace[1501536100] linearizableReadLoop","detail":"{readStateIndex:6159; appliedIndex:6158; }","duration":"108.728939ms","start":"2026-08-15T02:44:46.175619Z","end":"2026-08-15T02:44:46.284348Z","steps":["trace[1501536100] 'read index received' (duration: 108.566015ms)","trace[1501536100] 'applied index is now lower than readState.Index' (duration: 161.814µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:46.284719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.070797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-08-15T02:44:46.284828Z","caller":"traceutil/trace.go:171","msg":"trace[547601304] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5902; }","duration":"109.19749ms","start":"2026-08-15T02:44:46.175614Z","end":"2026-08-15T02:44:46.284811Z","steps":["trace[547601304] 'agreement among raft nodes before linearized reading' (duration: 108.861502ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:46.285348Z","caller":"traceutil/trace.go:171","msg":"trace[652486864] transaction","detail":"{read_only:false; response_revision:5902; number_of_response:1; }","duration":"135.840061ms","start":"2026-08-15T02:44:46.149499Z","end":"2026-08-15T02:44:46.285339Z","steps":["trace[652486864] 'process raft request' (duration: 134.604272ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:46.747437Z","caller":"traceutil/trace.go:171","msg":"trace[1185049274] transaction","detail":"{read_only:false; response_revision:5906; number_of_response:1; }","duration":"140.209222ms","start":"2026-08-15T02:44:46.607197Z","end":"2026-08-15T02:44:46.747406Z","steps":["trace[1185049274] 'process raft request' (duration: 140.054728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:47.080183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.606884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-08-15T02:44:47.080281Z","caller":"traceutil/trace.go:171","msg":"trace[322735449] linearizableReadLoop","detail":"{readStateIndex:6165; appliedIndex:6164; }","duration":"288.069137ms","start":"2026-08-15T02:44:46.792197Z","end":"2026-08-15T02:44:47.080266Z","steps":["trace[322735449] 'read index received' (duration: 135.257828ms)","trace[322735449] 'applied index is now lower than readState.Index' (duration: 152.810329ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:47.080725Z","caller":"traceutil/trace.go:171","msg":"trace[934263474] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5906; }","duration":"318.490236ms","start":"2026-08-15T02:44:46.762221Z","end":"2026-08-15T02:44:47.080711Z","steps":["trace[934263474] 'process raft request' (duration: 165.294659ms)","trace[934263474] 'compare' (duration: 152.566283ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:47.080837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.385195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:44:47.080904Z","caller":"traceutil/trace.go:171","msg":"trace[1626196938] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5906; }","duration":"235.494609ms","start":"2026-08-15T02:44:46.845393Z","end":"2026-08-15T02:44:47.080887Z","steps":["trace[1626196938] 'agreement among raft nodes before linearized reading' (duration: 235.383407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:47.080887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:44:46.762209Z","time spent":"318.555827ms","remote":"127.0.0.1:50170","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-15T02:44:47.08098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.773313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:44:47.081106Z","caller":"traceutil/trace.go:171","msg":"trace[1875372287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5906; }","duration":"288.929147ms","start":"2026-08-15T02:44:46.792157Z","end":"2026-08-15T02:44:47.081086Z","steps":["trace[1875372287] 'agreement among raft nodes before linearized reading' (duration: 288.776003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:47.081256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.132674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-15T02:44:47.081311Z","caller":"traceutil/trace.go:171","msg":"trace[1545821549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5906; }","duration":"213.209816ms","start":"2026-08-15T02:44:46.868089Z","end":"2026-08-15T02:44:47.081298Z","steps":["trace[1545821549] 'agreement among raft nodes before linearized reading' (duration: 213.051652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:48.601059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.956797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:44:48.601166Z","caller":"traceutil/trace.go:171","msg":"trace[565981943] linearizableReadLoop","detail":"{readStateIndex:6182; appliedIndex:6181; }","duration":"235.178587ms","start":"2026-08-15T02:44:48.365972Z","end":"2026-08-15T02:44:48.60115Z","steps":["trace[565981943] 'read index received' (duration: 57.979486ms)","trace[565981943] 'applied index is now lower than readState.Index' (duration: 177.198101ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:44:48.601394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.340211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-08-15T02:44:48.601618Z","caller":"traceutil/trace.go:171","msg":"trace[1874805604] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5923; }","duration":"235.67447ms","start":"2026-08-15T02:44:48.36593Z","end":"2026-08-15T02:44:48.601604Z","steps":["trace[1874805604] 'agreement among raft nodes before linearized reading' (duration: 235.26476ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:44:48.60169Z","caller":"traceutil/trace.go:171","msg":"trace[270853606] transaction","detail":"{read_only:false; response_revision:5923; number_of_response:1; }","duration":"238.489203ms","start":"2026-08-15T02:44:48.363167Z","end":"2026-08-15T02:44:48.601656Z","steps":["trace[270853606] 'process raft request' (duration: 60.837701ms)","trace[270853606] 'compare' (duration: 176.803732ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:49.773613Z","caller":"traceutil/trace.go:171","msg":"trace[1709463876] transaction","detail":"{read_only:false; response_revision:5930; number_of_response:1; }","duration":"128.236095ms","start":"2026-08-15T02:44:49.645348Z","end":"2026-08-15T02:44:49.773584Z","steps":["trace[1709463876] 'process raft request' (duration: 46.561663ms)","trace[1709463876] 'compare' (duration: 81.55951ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:44:50.400858Z","caller":"traceutil/trace.go:171","msg":"trace[687802911] transaction","detail":"{read_only:false; response_revision:5933; number_of_response:1; }","duration":"107.26915ms","start":"2026-08-15T02:44:50.293559Z","end":"2026-08-15T02:44:50.400828Z","steps":["trace[687802911] 'process raft request' (duration: 106.78534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:44:57.984818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.963457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-15T02:44:57.984956Z","caller":"traceutil/trace.go:171","msg":"trace[1972238552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5989; }","duration":"165.178113ms","start":"2026-08-15T02:44:57.819749Z","end":"2026-08-15T02:44:57.984927Z","steps":["trace[1972238552] 'range keys from in-memory index tree' (duration: 164.656601ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:45:28.497502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3173} {"level":"info","ts":"2026-08-15T02:45:28.615062Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3173,"took":"116.276534ms","hash":362638460,"current-db-size-bytes":58654720,"current-db-size":"59 MB","current-db-size-in-use-bytes":31313920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-15T02:45:28.615288Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":362638460,"revision":3173,"compact-revision":-1} {"level":"info","ts":"2026-08-15T02:45:48.748385Z","caller":"traceutil/trace.go:171","msg":"trace[1054386086] linearizableReadLoop","detail":"{readStateIndex:6789; appliedIndex:6788; }","duration":"100.902344ms","start":"2026-08-15T02:45:48.647454Z","end":"2026-08-15T02:45:48.748356Z","steps":["trace[1054386086] 'read index received' (duration: 100.771591ms)","trace[1054386086] 'applied index is now lower than readState.Index' (duration: 129.983µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:45:48.748546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.084378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4837"} {"level":"info","ts":"2026-08-15T02:45:48.748408Z","caller":"traceutil/trace.go:171","msg":"trace[235250748] transaction","detail":"{read_only:false; response_revision:6501; number_of_response:1; }","duration":"102.016336ms","start":"2026-08-15T02:45:48.646371Z","end":"2026-08-15T02:45:48.748388Z","steps":["trace[235250748] 'process raft request' (duration: 101.849792ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:45:48.748583Z","caller":"traceutil/trace.go:171","msg":"trace[1647716301] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6501; }","duration":"101.16896ms","start":"2026-08-15T02:45:48.647405Z","end":"2026-08-15T02:45:48.748574Z","steps":["trace[1647716301] 'agreement among raft nodes before linearized reading' (duration: 101.073138ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:45:50.861384Z","caller":"traceutil/trace.go:171","msg":"trace[1006029273] transaction","detail":"{read_only:false; response_revision:6515; number_of_response:1; }","duration":"100.598571ms","start":"2026-08-15T02:45:50.760756Z","end":"2026-08-15T02:45:50.861354Z","steps":["trace[1006029273] 'process raft request' (duration: 100.361277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:46:18.631766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.419214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:46:18.63207Z","caller":"traceutil/trace.go:171","msg":"trace[1572544676] transaction","detail":"{read_only:false; response_revision:6753; number_of_response:1; }","duration":"232.494026ms","start":"2026-08-15T02:46:18.399558Z","end":"2026-08-15T02:46:18.632052Z","steps":["trace[1572544676] 'process raft request' (duration: 64.720745ms)","trace[1572544676] 'compare' (duration: 167.288452ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:46:18.632273Z","caller":"traceutil/trace.go:171","msg":"trace[1121226586] linearizableReadLoop","detail":"{readStateIndex:7057; appliedIndex:7056; }","duration":"142.05708ms","start":"2026-08-15T02:46:18.490207Z","end":"2026-08-15T02:46:18.632264Z","steps":["trace[1121226586] 'read index received' (duration: 33.47µs)","trace[1121226586] 'applied index is now lower than readState.Index' (duration: 142.02252ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:46:18.632398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.191213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107303"} {"level":"info","ts":"2026-08-15T02:46:18.632422Z","caller":"traceutil/trace.go:171","msg":"trace[285286801] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6753; }","duration":"142.244824ms","start":"2026-08-15T02:46:18.49017Z","end":"2026-08-15T02:46:18.632415Z","steps":["trace[285286801] 'agreement among raft nodes before linearized reading' (duration: 142.127132ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:46:29.078363Z","caller":"traceutil/trace.go:171","msg":"trace[655573328] transaction","detail":"{read_only:false; response_revision:6825; number_of_response:1; }","duration":"164.057574ms","start":"2026-08-15T02:46:28.914283Z","end":"2026-08-15T02:46:29.07834Z","steps":["trace[655573328] 'process raft request' (duration: 163.481242ms)"],"step_count":1}