{"level":"warn","ts":"2026-05-31T05:43:36.027218Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-31T05:43:36.027321Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-31T05:43:36.02739Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-31T05:43:36.027403Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-31T05:43:36.027422Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-31T05:43:36.028095Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-31T05:43:36.028174Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-31T05:43:36.047795Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"19.421457ms"} {"level":"info","ts":"2026-05-31T05:43:36.085044Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-31T05:43:36.085187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-31T05:43:36.085222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-31T05:43:36.08525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-31T05:43:36.085265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-31T05:43:36.085369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-31T05:43:36.12689Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-31T05:43:36.134775Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-31T05:43:36.144696Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-31T05:43:36.155069Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-31T05:43:36.158656Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-31T05:43:36.158719Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-31T05:43:36.158742Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-31T05:43:36.158694Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-31T05:43:36.161761Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-31T05:43:36.162069Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-31T05:43:36.162114Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-31T05:43:36.162248Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-31T05:43:36.162266Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-31T05:43:36.16652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-31T05:43:36.166632Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-31T05:43:36.286121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-31T05:43:36.286375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-31T05:43:36.28651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-31T05:43:36.286622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-31T05:43:36.286643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-31T05:43:36.286799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-31T05:43:36.28688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-31T05:43:36.322996Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:43:36.33963Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-31T05:43:36.339989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-31T05:43:36.339999Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-31T05:43:36.3401Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-31T05:43:36.339922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-31T05:43:36.343354Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-31T05:43:36.345532Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-31T05:43:36.35466Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:43:36.354815Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:43:36.354886Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:43:41.939395Z","caller":"traceutil/trace.go:171","msg":"trace[945329113] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"128.615807ms","start":"2026-05-31T05:43:41.810745Z","end":"2026-05-31T05:43:41.939361Z","steps":["trace[945329113] 'process raft request' (duration: 128.514363ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:43:41.939445Z","caller":"traceutil/trace.go:171","msg":"trace[1546368739] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"139.280287ms","start":"2026-05-31T05:43:41.800139Z","end":"2026-05-31T05:43:41.939419Z","steps":["trace[1546368739] 'process raft request' (duration: 128.675489ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:43:42.253528Z","caller":"traceutil/trace.go:171","msg":"trace[1271198302] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"193.752718ms","start":"2026-05-31T05:43:42.059734Z","end":"2026-05-31T05:43:42.253487Z","steps":["trace[1271198302] 'read index received' (duration: 181.904382ms)","trace[1271198302] 'applied index is now lower than readState.Index' (duration: 11.846906ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:43:42.25383Z","caller":"traceutil/trace.go:171","msg":"trace[322181497] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"216.925148ms","start":"2026-05-31T05:43:42.036849Z","end":"2026-05-31T05:43:42.253774Z","steps":["trace[322181497] 'process raft request' (duration: 204.73767ms)","trace[322181497] 'compare' (duration: 11.366847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:43:42.253906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.129701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-31T05:43:42.254134Z","caller":"traceutil/trace.go:171","msg":"trace[799018033] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:307; }","duration":"194.423812ms","start":"2026-05-31T05:43:42.059694Z","end":"2026-05-31T05:43:42.254118Z","steps":["trace[799018033] 'agreement among raft nodes before linearized reading' (duration: 193.940214ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:43:42.652884Z","caller":"traceutil/trace.go:171","msg":"trace[1833034082] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"123.897531ms","start":"2026-05-31T05:43:42.528926Z","end":"2026-05-31T05:43:42.652823Z","steps":["trace[1833034082] 'process raft request' (duration: 122.657346ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:43:42.68646Z","caller":"traceutil/trace.go:171","msg":"trace[1012954571] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:321; }","duration":"126.499346ms","start":"2026-05-31T05:43:42.559931Z","end":"2026-05-31T05:43:42.686431Z","steps":["trace[1012954571] 'read index received' (duration: 91.649166ms)","trace[1012954571] 'applied index is now lower than readState.Index' (duration: 34.84881ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:43:42.686648Z","caller":"traceutil/trace.go:171","msg":"trace[1821260466] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"157.38244ms","start":"2026-05-31T05:43:42.529254Z","end":"2026-05-31T05:43:42.686637Z","steps":["trace[1821260466] 'process raft request' (duration: 157.019807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:42.686904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.950783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-31T05:43:42.68701Z","caller":"traceutil/trace.go:171","msg":"trace[7071563] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:315; }","duration":"127.112178ms","start":"2026-05-31T05:43:42.559873Z","end":"2026-05-31T05:43:42.686985Z","steps":["trace[7071563] 'agreement among raft nodes before linearized reading' (duration: 126.936272ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:43:42.973239Z","caller":"traceutil/trace.go:171","msg":"trace[302679194] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"112.596096ms","start":"2026-05-31T05:43:42.860613Z","end":"2026-05-31T05:43:42.973209Z","steps":["trace[302679194] 'read index received' (duration: 99.157873ms)","trace[302679194] 'applied index is now lower than readState.Index' (duration: 13.436523ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:43:42.973432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.791323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-31T05:43:42.973403Z","caller":"traceutil/trace.go:171","msg":"trace[475658092] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"148.482613ms","start":"2026-05-31T05:43:42.824862Z","end":"2026-05-31T05:43:42.973344Z","steps":["trace[475658092] 'process raft request' (duration: 134.980878ms)","trace[475658092] 'compare' (duration: 13.233236ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:43:42.973463Z","caller":"traceutil/trace.go:171","msg":"trace[1441387398] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:319; }","duration":"112.894288ms","start":"2026-05-31T05:43:42.86056Z","end":"2026-05-31T05:43:42.973455Z","steps":["trace[1441387398] 'agreement among raft nodes before linearized reading' (duration: 112.765972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:46.313401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.88717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:43:46.313636Z","caller":"traceutil/trace.go:171","msg":"trace[1363511879] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"453.266172ms","start":"2026-05-31T05:43:45.860345Z","end":"2026-05-31T05:43:46.313612Z","steps":["trace[1363511879] 'read index received' (duration: 6.545981ms)","trace[1363511879] 'applied index is now lower than readState.Index' (duration: 446.718251ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:43:46.31387Z","caller":"traceutil/trace.go:171","msg":"trace[312360918] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"492.885863ms","start":"2026-05-31T05:43:45.820939Z","end":"2026-05-31T05:43:46.313825Z","steps":["trace[312360918] 'process raft request' (duration: 46.005566ms)","trace[312360918] 'compare' (duration: 445.716323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:43:46.313882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.50984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-31T05:43:46.313991Z","caller":"traceutil/trace.go:171","msg":"trace[108579787] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:367; }","duration":"453.665416ms","start":"2026-05-31T05:43:45.860293Z","end":"2026-05-31T05:43:46.313958Z","steps":["trace[108579787] 'agreement among raft nodes before linearized reading' (duration: 453.438388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:46.314034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:45.820926Z","time spent":"493.034109ms","remote":"127.0.0.1:43324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:43:46.314038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:45.860273Z","time spent":"453.75223ms","remote":"127.0.0.1:43024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-31T05:43:46.888125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.289349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:43:46.888251Z","caller":"traceutil/trace.go:171","msg":"trace[1934815553] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"569.161499ms","start":"2026-05-31T05:43:46.31907Z","end":"2026-05-31T05:43:46.888231Z","steps":["trace[1934815553] 'process raft request' (duration: 372.695894ms)","trace[1934815553] 'compare' (duration: 196.150603ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:43:46.888311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:46.319056Z","time spent":"569.229091ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:43:47.811472Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.119670576s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-31T05:43:48.151429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.692632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:43:48.151631Z","caller":"traceutil/trace.go:171","msg":"trace[737834994] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:375; }","duration":"1.83031692s","start":"2026-05-31T05:43:46.3213Z","end":"2026-05-31T05:43:48.151617Z","steps":["trace[737834994] 'read index received' (duration: 370.484061ms)","trace[737834994] 'applied index is now lower than readState.Index' (duration: 1.459831969s)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:43:48.151718Z","caller":"traceutil/trace.go:171","msg":"trace[1278422399] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.828706782s","start":"2026-05-31T05:43:46.322995Z","end":"2026-05-31T05:43:48.151702Z","steps":["trace[1278422399] 'process raft request' (duration: 1.828550136s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:48.152156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:46.322976Z","time spent":"1.828764034s","remote":"127.0.0.1:42990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:43:48.153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.831650389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-31T05:43:48.153195Z","caller":"traceutil/trace.go:171","msg":"trace[670157030] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"1.831857096s","start":"2026-05-31T05:43:46.321258Z","end":"2026-05-31T05:43:48.153115Z","steps":["trace[670157030] 'agreement among raft nodes before linearized reading' (duration: 1.830443795s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:48.153276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:46.321238Z","time spent":"1.832019264s","remote":"127.0.0.1:43024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-05-31T05:43:48.155139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61961754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:43:48.155197Z","caller":"traceutil/trace.go:171","msg":"trace[1634216542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"1.619703053s","start":"2026-05-31T05:43:46.535471Z","end":"2026-05-31T05:43:48.155174Z","steps":["trace[1634216542] 'agreement among raft nodes before linearized reading' (duration: 1.619173844s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:48.155231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:46.535448Z","time spent":"1.619775076s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-31T05:43:48.156022Z","caller":"traceutil/trace.go:171","msg":"trace[527737104] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"1.831020047s","start":"2026-05-31T05:43:46.320704Z","end":"2026-05-31T05:43:48.151725Z","steps":["trace[527737104] 'process raft request' (duration: 1.491100433s)","trace[527737104] 'compare' (duration: 337.11009ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:43:48.156468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457288857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:43:48.156549Z","caller":"traceutil/trace.go:171","msg":"trace[229862532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"1.45740029s","start":"2026-05-31T05:43:46.699126Z","end":"2026-05-31T05:43:48.156527Z","steps":["trace[229862532] 'agreement among raft nodes before linearized reading' (duration: 1.457071348s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:48.156617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:46.699102Z","time spent":"1.457502854s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:43:48.1566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:46.320681Z","time spent":"1.835757323s","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:43:48.156368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428497312s","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-05-31T05:43:48.156864Z","caller":"traceutil/trace.go:171","msg":"trace[1287393838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"1.428929849s","start":"2026-05-31T05:43:46.727827Z","end":"2026-05-31T05:43:48.156757Z","steps":["trace[1287393838] 'agreement among raft nodes before linearized reading' (duration: 1.428361958s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:48.15695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:46.72781Z","time spent":"1.429114245s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-31T05:43:48.156907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489804887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-31T05:43:48.15712Z","caller":"traceutil/trace.go:171","msg":"trace[546417913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"1.490055826s","start":"2026-05-31T05:43:46.66701Z","end":"2026-05-31T05:43:48.157066Z","steps":["trace[546417913] 'agreement among raft nodes before linearized reading' (duration: 1.489182014s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:48.157233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:46.666992Z","time spent":"1.490207241s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T05:43:52.528681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.676831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:43:52.528875Z","caller":"traceutil/trace.go:171","msg":"trace[783627662] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"336.43755ms","start":"2026-05-31T05:43:52.19242Z","end":"2026-05-31T05:43:52.528858Z","steps":["trace[783627662] 'process raft request' (duration: 336.355937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:43:52.528931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:52.192406Z","time spent":"336.501772ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:43:52.529086Z","caller":"traceutil/trace.go:171","msg":"trace[575203099] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"336.781393ms","start":"2026-05-31T05:43:52.192297Z","end":"2026-05-31T05:43:52.529078Z","steps":["trace[575203099] 'process raft request' (duration: 53.603473ms)","trace[575203099] 'compare' (duration: 282.38649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:43:52.529126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:43:52.192282Z","time spent":"336.824935ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:43:56.420921Z","caller":"traceutil/trace.go:171","msg":"trace[140529092] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"124.402274ms","start":"2026-05-31T05:43:56.296483Z","end":"2026-05-31T05:43:56.420885Z","steps":["trace[140529092] 'process raft request' (duration: 85.849654ms)","trace[140529092] 'compare' (duration: 38.412435ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:44:20.739184Z","caller":"traceutil/trace.go:171","msg":"trace[251034760] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"299.612037ms","start":"2026-05-31T05:44:20.439545Z","end":"2026-05-31T05:44:20.739157Z","steps":["trace[251034760] 'process raft request' (duration: 299.440395ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755058Z","caller":"traceutil/trace.go:171","msg":"trace[1571068863] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"253.711966ms","start":"2026-05-31T05:44:20.501308Z","end":"2026-05-31T05:44:20.75502Z","steps":["trace[1571068863] 'process raft request' (duration: 253.506954ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755227Z","caller":"traceutil/trace.go:171","msg":"trace[588956812] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"248.547697ms","start":"2026-05-31T05:44:20.506671Z","end":"2026-05-31T05:44:20.755219Z","steps":["trace[588956812] 'process raft request' (duration: 248.325805ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755291Z","caller":"traceutil/trace.go:171","msg":"trace[848063193] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"248.740579ms","start":"2026-05-31T05:44:20.506543Z","end":"2026-05-31T05:44:20.755284Z","steps":["trace[848063193] 'process raft request' (duration: 248.406596ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.75541Z","caller":"traceutil/trace.go:171","msg":"trace[311802958] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"238.113583ms","start":"2026-05-31T05:44:20.517274Z","end":"2026-05-31T05:44:20.755388Z","steps":["trace[311802958] 'process raft request' (duration: 238.025222ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755485Z","caller":"traceutil/trace.go:171","msg":"trace[309616947] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"242.762085ms","start":"2026-05-31T05:44:20.512714Z","end":"2026-05-31T05:44:20.755476Z","steps":["trace[309616947] 'process raft request' (duration: 242.368651ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755575Z","caller":"traceutil/trace.go:171","msg":"trace[646943843] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"240.685505ms","start":"2026-05-31T05:44:20.514881Z","end":"2026-05-31T05:44:20.755566Z","steps":["trace[646943843] 'process raft request' (duration: 240.361683ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.75557Z","caller":"traceutil/trace.go:171","msg":"trace[1631282889] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"244.786753ms","start":"2026-05-31T05:44:20.510759Z","end":"2026-05-31T05:44:20.755546Z","steps":["trace[1631282889] 'process raft request' (duration: 244.282369ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755605Z","caller":"traceutil/trace.go:171","msg":"trace[552651399] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"212.542988ms","start":"2026-05-31T05:44:20.543049Z","end":"2026-05-31T05:44:20.755592Z","steps":["trace[552651399] 'process raft request' (duration: 212.296575ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755582Z","caller":"traceutil/trace.go:171","msg":"trace[1235083092] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"241.687745ms","start":"2026-05-31T05:44:20.513874Z","end":"2026-05-31T05:44:20.755562Z","steps":["trace[1235083092] 'process raft request' (duration: 241.308172ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755667Z","caller":"traceutil/trace.go:171","msg":"trace[1604496837] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"212.607689ms","start":"2026-05-31T05:44:20.543049Z","end":"2026-05-31T05:44:20.755657Z","steps":["trace[1604496837] 'process raft request' (duration: 212.449207ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:20.755795Z","caller":"traceutil/trace.go:171","msg":"trace[1271409148] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"184.435561ms","start":"2026-05-31T05:44:20.57135Z","end":"2026-05-31T05:44:20.755785Z","steps":["trace[1271409148] 'process raft request' (duration: 184.237549ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:28.50804Z","caller":"traceutil/trace.go:171","msg":"trace[1636267992] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"385.167157ms","start":"2026-05-31T05:44:28.122844Z","end":"2026-05-31T05:44:28.508011Z","steps":["trace[1636267992] 'process raft request' (duration: 384.309777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:28.508257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:28.122807Z","time spent":"385.331899ms","remote":"127.0.0.1:42916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:44:29.04786Z","caller":"traceutil/trace.go:171","msg":"trace[1877541112] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"242.698538ms","start":"2026-05-31T05:44:28.805132Z","end":"2026-05-31T05:44:29.04783Z","steps":["trace[1877541112] 'read index received' (duration: 242.481905ms)","trace[1877541112] 'applied index is now lower than readState.Index' (duration: 215.353µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:44:29.047903Z","caller":"traceutil/trace.go:171","msg":"trace[1893410428] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"263.580882ms","start":"2026-05-31T05:44:28.784293Z","end":"2026-05-31T05:44:29.047874Z","steps":["trace[1893410428] 'process raft request' (duration: 263.329809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:29.04805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.497102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:44:29.048101Z","caller":"traceutil/trace.go:171","msg":"trace[1366661517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"171.602684ms","start":"2026-05-31T05:44:28.876483Z","end":"2026-05-31T05:44:29.048086Z","steps":["trace[1366661517] 'agreement among raft nodes before linearized reading' (duration: 171.508423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:29.048377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.443666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-31T05:44:29.048492Z","caller":"traceutil/trace.go:171","msg":"trace[744037614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"113.605138ms","start":"2026-05-31T05:44:28.934861Z","end":"2026-05-31T05:44:29.048466Z","steps":["trace[744037614] 'agreement among raft nodes before linearized reading' (duration: 113.361605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:29.048806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.66451ms","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-05-31T05:44:29.048875Z","caller":"traceutil/trace.go:171","msg":"trace[712790049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"243.757321ms","start":"2026-05-31T05:44:28.805103Z","end":"2026-05-31T05:44:29.04886Z","steps":["trace[712790049] 'agreement among raft nodes before linearized reading' (duration: 242.81636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:30.231074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410758076308054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:44:30.732161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410758076308054,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:44:30.813082Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.714330505s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:44:30.813808Z","caller":"traceutil/trace.go:171","msg":"trace[139216965] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.760409862s","start":"2026-05-31T05:44:29.053342Z","end":"2026-05-31T05:44:30.813752Z","steps":["trace[139216965] 'process raft request' (duration: 1.760042557s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:30.814123Z","caller":"traceutil/trace.go:171","msg":"trace[940058293] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.760374331s","start":"2026-05-31T05:44:29.053502Z","end":"2026-05-31T05:44:30.813876Z","steps":["trace[940058293] 'process raft request' (duration: 1.760163419s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:30.814217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:29.05349Z","time spent":"1.760676665s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:44:30.814182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:29.05333Z","time spent":"1.760570754s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:44:30.848842Z","caller":"traceutil/trace.go:171","msg":"trace[1628488206] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:680; }","duration":"1.118745163s","start":"2026-05-31T05:44:29.730071Z","end":"2026-05-31T05:44:30.848816Z","steps":["trace[1628488206] 'read index received' (duration: 1.083200767s)","trace[1628488206] 'applied index is now lower than readState.Index' (duration: 35.543156ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:44:30.84902Z","caller":"traceutil/trace.go:171","msg":"trace[680735779] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.722047554s","start":"2026-05-31T05:44:29.126929Z","end":"2026-05-31T05:44:30.848977Z","steps":["trace[680735779] 'process raft request' (duration: 1.721759719s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:30.849099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119003937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-31T05:44:30.849138Z","caller":"traceutil/trace.go:171","msg":"trace[808191192] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:659; }","duration":"1.119098398s","start":"2026-05-31T05:44:29.730027Z","end":"2026-05-31T05:44:30.849126Z","steps":["trace[808191192] 'agreement among raft nodes before linearized reading' (duration: 1.118929076s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:30.84918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:29.730004Z","time spent":"1.119167598s","remote":"127.0.0.1:42838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-05-31T05:44:30.849279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:29.126903Z","time spent":"1.722207947s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:44:30.985419Z","caller":"traceutil/trace.go:171","msg":"trace[1673020100] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:683; }","duration":"107.025676ms","start":"2026-05-31T05:44:30.87837Z","end":"2026-05-31T05:44:30.985395Z","steps":["trace[1673020100] 'read index received' (duration: 84.918671ms)","trace[1673020100] 'applied index is now lower than readState.Index' (duration: 22.105895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:44:30.985543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.153487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:44:30.985518Z","caller":"traceutil/trace.go:171","msg":"trace[641378061] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"131.915876ms","start":"2026-05-31T05:44:30.853524Z","end":"2026-05-31T05:44:30.98544Z","steps":["trace[641378061] 'process raft request' (duration: 131.758524ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:30.985577Z","caller":"traceutil/trace.go:171","msg":"trace[1327671039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"107.244159ms","start":"2026-05-31T05:44:30.878323Z","end":"2026-05-31T05:44:30.985567Z","steps":["trace[1327671039] 'agreement among raft nodes before linearized reading' (duration: 107.165398ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:31.856069Z","caller":"traceutil/trace.go:171","msg":"trace[479821554] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"101.016123ms","start":"2026-05-31T05:44:31.755022Z","end":"2026-05-31T05:44:31.856038Z","steps":["trace[479821554] 'process raft request' (duration: 100.846191ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:31.964372Z","caller":"traceutil/trace.go:171","msg":"trace[1207475482] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"196.675396ms","start":"2026-05-31T05:44:31.767669Z","end":"2026-05-31T05:44:31.964344Z","steps":["trace[1207475482] 'process raft request' (duration: 196.267061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:49.908198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.744731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:44:49.908316Z","caller":"traceutil/trace.go:171","msg":"trace[140825424] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"976.557906ms","start":"2026-05-31T05:44:48.931734Z","end":"2026-05-31T05:44:49.908292Z","steps":["trace[140825424] 'read index received' (duration: 422.50271ms)","trace[140825424] 'applied index is now lower than readState.Index' (duration: 554.053866ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:44:49.908557Z","caller":"traceutil/trace.go:171","msg":"trace[1656842489] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"994.29785ms","start":"2026-05-31T05:44:48.914159Z","end":"2026-05-31T05:44:49.908457Z","steps":["trace[1656842489] 'process raft request' (duration: 440.190993ms)","trace[1656842489] 'compare' (duration: 553.301283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:44:49.908619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.873142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-31T05:44:49.908712Z","caller":"traceutil/trace.go:171","msg":"trace[2110422922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"977.033005ms","start":"2026-05-31T05:44:48.931665Z","end":"2026-05-31T05:44:49.908698Z","steps":["trace[2110422922] 'agreement among raft nodes before linearized reading' (duration: 976.823661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:49.908783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:48.931653Z","time spent":"977.123046ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T05:44:49.908771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:48.914128Z","time spent":"994.518964ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:44:49.912393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.711227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-31T05:44:49.912465Z","caller":"traceutil/trace.go:171","msg":"trace[2050233233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:823; }","duration":"808.84729ms","start":"2026-05-31T05:44:49.103602Z","end":"2026-05-31T05:44:49.912449Z","steps":["trace[2050233233] 'agreement among raft nodes before linearized reading' (duration: 808.605325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:49.912467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.501359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-31T05:44:49.912509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:49.103568Z","time spent":"808.92678ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-31T05:44:49.91253Z","caller":"traceutil/trace.go:171","msg":"trace[1227385467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:823; }","duration":"701.595261ms","start":"2026-05-31T05:44:49.21092Z","end":"2026-05-31T05:44:49.912515Z","steps":["trace[1227385467] 'agreement among raft nodes before linearized reading' (duration: 701.376007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:49.912579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:49.210907Z","time spent":"701.655682ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:44:49.912589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.549952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-31T05:44:49.912674Z","caller":"traceutil/trace.go:171","msg":"trace[1026476983] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:823; }","duration":"181.636853ms","start":"2026-05-31T05:44:49.73101Z","end":"2026-05-31T05:44:49.912647Z","steps":["trace[1026476983] 'agreement among raft nodes before linearized reading' (duration: 181.397099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:49.912577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.06568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-31T05:44:49.912929Z","caller":"traceutil/trace.go:171","msg":"trace[884248258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:823; }","duration":"798.278144ms","start":"2026-05-31T05:44:49.114479Z","end":"2026-05-31T05:44:49.912757Z","steps":["trace[884248258] 'agreement among raft nodes before linearized reading' (duration: 797.888127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:49.912948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.827937ms","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":"warn","ts":"2026-05-31T05:44:49.913005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:44:49.114412Z","time spent":"798.574089ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-31T05:44:49.913177Z","caller":"traceutil/trace.go:171","msg":"trace[338586749] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:823; }","duration":"279.738923ms","start":"2026-05-31T05:44:49.633412Z","end":"2026-05-31T05:44:49.913151Z","steps":["trace[338586749] 'agreement among raft nodes before linearized reading' (duration: 278.831427ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:54.830097Z","caller":"traceutil/trace.go:171","msg":"trace[298048685] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"100.915966ms","start":"2026-05-31T05:44:54.729125Z","end":"2026-05-31T05:44:54.830041Z","steps":["trace[298048685] 'process raft request' (duration: 53.733167ms)","trace[298048685] 'compare' (duration: 46.920614ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:44:58.134227Z","caller":"traceutil/trace.go:171","msg":"trace[1237350914] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"178.335879ms","start":"2026-05-31T05:44:57.955862Z","end":"2026-05-31T05:44:58.134198Z","steps":["trace[1237350914] 'read index received' (duration: 78.725299ms)","trace[1237350914] 'applied index is now lower than readState.Index' (duration: 99.60906ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:44:58.134343Z","caller":"traceutil/trace.go:171","msg":"trace[448445929] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"180.616266ms","start":"2026-05-31T05:44:57.953672Z","end":"2026-05-31T05:44:58.134288Z","steps":["trace[448445929] 'process raft request' (duration: 81.054647ms)","trace[448445929] 'compare' (duration: 99.326014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:44:58.134517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.621055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:44:58.134633Z","caller":"traceutil/trace.go:171","msg":"trace[1522138086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"178.755968ms","start":"2026-05-31T05:44:57.955856Z","end":"2026-05-31T05:44:58.134612Z","steps":["trace[1522138086] 'agreement among raft nodes before linearized reading' (duration: 178.451472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:58.135062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.734054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-31T05:44:58.135196Z","caller":"traceutil/trace.go:171","msg":"trace[1234036427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"166.896918ms","start":"2026-05-31T05:44:57.968257Z","end":"2026-05-31T05:44:58.135154Z","steps":["trace[1234036427] 'agreement among raft nodes before linearized reading' (duration: 166.5278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:44:58.136402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.285436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:44:58.13648Z","caller":"traceutil/trace.go:171","msg":"trace[1903618533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:905; }","duration":"169.335667ms","start":"2026-05-31T05:44:57.967103Z","end":"2026-05-31T05:44:58.136438Z","steps":["trace[1903618533] 'agreement among raft nodes before linearized reading' (duration: 167.631242ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:58.281681Z","caller":"traceutil/trace.go:171","msg":"trace[865085697] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"141.675795ms","start":"2026-05-31T05:44:58.139981Z","end":"2026-05-31T05:44:58.281657Z","steps":["trace[865085697] 'process raft request' (duration: 141.632704ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:44:58.282363Z","caller":"traceutil/trace.go:171","msg":"trace[606262558] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"142.42782ms","start":"2026-05-31T05:44:58.139888Z","end":"2026-05-31T05:44:58.282316Z","steps":["trace[606262558] 'process raft request' (duration: 122.611332ms)","trace[606262558] 'compare' (duration: 19.012292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:45:08.392086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.923463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:45:08.392133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.024385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-31T05:45:08.392196Z","caller":"traceutil/trace.go:171","msg":"trace[385187796] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1012; }","duration":"132.097106ms","start":"2026-05-31T05:45:08.260078Z","end":"2026-05-31T05:45:08.392175Z","steps":["trace[385187796] 'count revisions from in-memory index tree' (duration: 131.796519ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:08.392202Z","caller":"traceutil/trace.go:171","msg":"trace[1784339106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"167.154188ms","start":"2026-05-31T05:45:08.225032Z","end":"2026-05-31T05:45:08.392186Z","steps":["trace[1784339106] 'range keys from in-memory index tree' (duration: 166.623317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:08.391984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.949756ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:45:08.392662Z","caller":"traceutil/trace.go:171","msg":"trace[1043093186] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1012; }","duration":"106.652772ms","start":"2026-05-31T05:45:08.285993Z","end":"2026-05-31T05:45:08.392646Z","steps":["trace[1043093186] 'range keys from in-memory index tree' (duration: 105.929876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:08.393062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.768534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-31T05:45:08.393148Z","caller":"traceutil/trace.go:171","msg":"trace[2021864923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1012; }","duration":"194.906597ms","start":"2026-05-31T05:45:08.198219Z","end":"2026-05-31T05:45:08.393125Z","steps":["trace[2021864923] 'range keys from in-memory index tree' (duration: 194.57989ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:08.562161Z","caller":"traceutil/trace.go:171","msg":"trace[1254854960] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"164.288035ms","start":"2026-05-31T05:45:08.397838Z","end":"2026-05-31T05:45:08.562126Z","steps":["trace[1254854960] 'process raft request' (duration: 164.0742ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:08.562186Z","caller":"traceutil/trace.go:171","msg":"trace[2076286213] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1047; }","duration":"122.114441ms","start":"2026-05-31T05:45:08.440056Z","end":"2026-05-31T05:45:08.56217Z","steps":["trace[2076286213] 'read index received' (duration: 36.492475ms)","trace[2076286213] 'applied index is now lower than readState.Index' (duration: 85.621046ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:08.562262Z","caller":"traceutil/trace.go:171","msg":"trace[523288740] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"161.693386ms","start":"2026-05-31T05:45:08.40052Z","end":"2026-05-31T05:45:08.562213Z","steps":["trace[523288740] 'process raft request' (duration: 161.566893ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:08.562322Z","caller":"traceutil/trace.go:171","msg":"trace[923326869] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"161.635235ms","start":"2026-05-31T05:45:08.40063Z","end":"2026-05-31T05:45:08.562265Z","steps":["trace[923326869] 'process raft request' (duration: 161.494352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:08.562404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.323816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:45:08.562444Z","caller":"traceutil/trace.go:171","msg":"trace[22097594] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1016; }","duration":"122.388988ms","start":"2026-05-31T05:45:08.440045Z","end":"2026-05-31T05:45:08.562434Z","steps":["trace[22097594] 'agreement among raft nodes before linearized reading' (duration: 122.177413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:22.37635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410758076309213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:45:22.659559Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.092593628s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:45:22.660055Z","caller":"traceutil/trace.go:171","msg":"trace[523274992] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"1.093250394s","start":"2026-05-31T05:45:21.566779Z","end":"2026-05-31T05:45:22.660029Z","steps":["trace[523274992] 'process raft request' (duration: 1.09309099s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:22.660232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:21.566738Z","time spent":"1.093401629s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:45:22.715378Z","caller":"traceutil/trace.go:171","msg":"trace[961609272] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1268; }","duration":"839.396723ms","start":"2026-05-31T05:45:21.875955Z","end":"2026-05-31T05:45:22.715352Z","steps":["trace[961609272] 'read index received' (duration: 783.909598ms)","trace[961609272] 'applied index is now lower than readState.Index' (duration: 55.485575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:45:22.71555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.586788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:45:22.715591Z","caller":"traceutil/trace.go:171","msg":"trace[1361578914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"839.694551ms","start":"2026-05-31T05:45:21.875886Z","end":"2026-05-31T05:45:22.71558Z","steps":["trace[1361578914] 'agreement among raft nodes before linearized reading' (duration: 839.613859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:22.715631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:21.875862Z","time spent":"839.761693ms","remote":"127.0.0.1:42796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-31T05:45:22.715788Z","caller":"traceutil/trace.go:171","msg":"trace[314818911] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"551.28664ms","start":"2026-05-31T05:45:22.164402Z","end":"2026-05-31T05:45:22.715689Z","steps":["trace[314818911] 'process raft request' (duration: 550.774778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:22.715815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.564221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-31T05:45:22.715965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.568592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:45:22.716061Z","caller":"traceutil/trace.go:171","msg":"trace[1462519038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"169.681735ms","start":"2026-05-31T05:45:22.546356Z","end":"2026-05-31T05:45:22.716038Z","steps":["trace[1462519038] 'agreement among raft nodes before linearized reading' (duration: 169.386138ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:22.716062Z","caller":"traceutil/trace.go:171","msg":"trace[676640539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1232; }","duration":"622.856118ms","start":"2026-05-31T05:45:22.09317Z","end":"2026-05-31T05:45:22.716026Z","steps":["trace[676640539] 'agreement among raft nodes before linearized reading' (duration: 622.486338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:22.716123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:22.093152Z","time spent":"622.957261ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-31T05:45:22.716206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:22.164375Z","time spent":"551.563437ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:45:22.919097Z","caller":"traceutil/trace.go:171","msg":"trace[1163526008] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"197.883641ms","start":"2026-05-31T05:45:22.721179Z","end":"2026-05-31T05:45:22.919063Z","steps":["trace[1163526008] 'process raft request' (duration: 197.644605ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:22.919491Z","caller":"traceutil/trace.go:171","msg":"trace[1757744215] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1271; }","duration":"102.207592ms","start":"2026-05-31T05:45:22.817255Z","end":"2026-05-31T05:45:22.919462Z","steps":["trace[1757744215] 'read index received' (duration: 101.428792ms)","trace[1757744215] 'applied index is now lower than readState.Index' (duration: 777.82µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:22.919529Z","caller":"traceutil/trace.go:171","msg":"trace[2048536701] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"198.24686ms","start":"2026-05-31T05:45:22.721265Z","end":"2026-05-31T05:45:22.919512Z","steps":["trace[2048536701] 'process raft request' (duration: 198.008884ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:22.919567Z","caller":"traceutil/trace.go:171","msg":"trace[1326330928] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"198.345072ms","start":"2026-05-31T05:45:22.721213Z","end":"2026-05-31T05:45:22.919558Z","steps":["trace[1326330928] 'process raft request' (duration: 197.800079ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:22.919659Z","caller":"traceutil/trace.go:171","msg":"trace[2110460407] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"198.2979ms","start":"2026-05-31T05:45:22.721307Z","end":"2026-05-31T05:45:22.919605Z","steps":["trace[2110460407] 'process raft request' (duration: 198.085715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:22.919828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.55754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-31T05:45:22.919985Z","caller":"traceutil/trace.go:171","msg":"trace[1811801924] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1237; }","duration":"102.747724ms","start":"2026-05-31T05:45:22.817196Z","end":"2026-05-31T05:45:22.919944Z","steps":["trace[1811801924] 'agreement among raft nodes before linearized reading' (duration: 102.392496ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:23.216382Z","caller":"traceutil/trace.go:171","msg":"trace[1590358553] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"180.935184ms","start":"2026-05-31T05:45:23.03542Z","end":"2026-05-31T05:45:23.216355Z","steps":["trace[1590358553] 'read index received' (duration: 180.76512ms)","trace[1590358553] 'applied index is now lower than readState.Index' (duration: 168.644µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:45:23.216606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.16895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2897"} {"level":"info","ts":"2026-05-31T05:45:23.216653Z","caller":"traceutil/trace.go:171","msg":"trace[454068355] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1238; }","duration":"181.281472ms","start":"2026-05-31T05:45:23.035354Z","end":"2026-05-31T05:45:23.216636Z","steps":["trace[454068355] 'agreement among raft nodes before linearized reading' (duration: 181.108258ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:23.216849Z","caller":"traceutil/trace.go:171","msg":"trace[901759664] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"184.077463ms","start":"2026-05-31T05:45:23.032348Z","end":"2026-05-31T05:45:23.216426Z","steps":["trace[901759664] 'process raft request' (duration: 183.736695ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:25.221146Z","caller":"traceutil/trace.go:171","msg":"trace[1220268070] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"103.10277ms","start":"2026-05-31T05:45:25.118014Z","end":"2026-05-31T05:45:25.221116Z","steps":["trace[1220268070] 'process raft request' (duration: 102.900495ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:38.864824Z","caller":"traceutil/trace.go:171","msg":"trace[2057553917] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"107.998386ms","start":"2026-05-31T05:45:38.756785Z","end":"2026-05-31T05:45:38.864784Z","steps":["trace[2057553917] 'process raft request' (duration: 107.717679ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:40.582316Z","caller":"traceutil/trace.go:171","msg":"trace[2099721733] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"110.086247ms","start":"2026-05-31T05:45:40.472205Z","end":"2026-05-31T05:45:40.582291Z","steps":["trace[2099721733] 'process raft request' (duration: 109.924243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:41.377131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410758076309524,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T05:45:41.400368Z","caller":"traceutil/trace.go:171","msg":"trace[2068143432] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"523.828296ms","start":"2026-05-31T05:45:40.876512Z","end":"2026-05-31T05:45:41.400341Z","steps":["trace[2068143432] 'read index received' (duration: 523.62144ms)","trace[2068143432] 'applied index is now lower than readState.Index' (duration: 205.586µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:41.400499Z","caller":"traceutil/trace.go:171","msg":"trace[1420653218] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"527.227698ms","start":"2026-05-31T05:45:40.873209Z","end":"2026-05-31T05:45:41.400437Z","steps":["trace[1420653218] 'process raft request' (duration: 526.949261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:41.400681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.167155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:45:41.400684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:40.873154Z","time spent":"527.411634ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:45:41.40073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.034557ms","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-05-31T05:45:41.400774Z","caller":"traceutil/trace.go:171","msg":"trace[88138317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"518.11681ms","start":"2026-05-31T05:45:40.882645Z","end":"2026-05-31T05:45:41.400762Z","steps":["trace[88138317] 'agreement among raft nodes before linearized reading' (duration: 517.913524ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:41.40076Z","caller":"traceutil/trace.go:171","msg":"trace[1170076608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"524.268767ms","start":"2026-05-31T05:45:40.876469Z","end":"2026-05-31T05:45:41.400738Z","steps":["trace[1170076608] 'agreement among raft nodes before linearized reading' (duration: 523.98192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:41.400824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:40.882637Z","time spent":"518.177841ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-31T05:45:41.400889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:40.876453Z","time spent":"524.390341ms","remote":"127.0.0.1:42796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-31T05:45:41.40101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.344326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-31T05:45:41.401046Z","caller":"traceutil/trace.go:171","msg":"trace[615297701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1376; }","duration":"317.416617ms","start":"2026-05-31T05:45:41.083619Z","end":"2026-05-31T05:45:41.401035Z","steps":["trace[615297701] 'agreement among raft nodes before linearized reading' (duration: 317.320495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:41.401081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:41.083597Z","time spent":"317.477449ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T05:45:41.401282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.183692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-hm6ps\" ","response":"range_response_count:1 size:4197"} {"level":"warn","ts":"2026-05-31T05:45:41.401275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.582256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-31T05:45:41.401332Z","caller":"traceutil/trace.go:171","msg":"trace[149986303] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-hm6ps; range_end:; response_count:1; response_revision:1376; }","duration":"309.269884ms","start":"2026-05-31T05:45:41.092047Z","end":"2026-05-31T05:45:41.401317Z","steps":["trace[149986303] 'agreement among raft nodes before linearized reading' (duration: 309.084299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:41.401332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.796114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-31T05:45:41.401362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:41.09203Z","time spent":"309.325077ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":4219,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-hm6ps\" "} {"level":"info","ts":"2026-05-31T05:45:41.401366Z","caller":"traceutil/trace.go:171","msg":"trace[145742061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1376; }","duration":"327.72998ms","start":"2026-05-31T05:45:41.073613Z","end":"2026-05-31T05:45:41.401343Z","steps":["trace[145742061] 'agreement among raft nodes before linearized reading' (duration: 327.441142ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:41.4014Z","caller":"traceutil/trace.go:171","msg":"trace[1902937457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1376; }","duration":"311.891697ms","start":"2026-05-31T05:45:41.089493Z","end":"2026-05-31T05:45:41.401385Z","steps":["trace[1902937457] 'agreement among raft nodes before linearized reading' (duration: 311.644789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:41.401416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:41.073588Z","time spent":"327.819364ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:45:41.401441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:41.089482Z","time spent":"311.951198ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-31T05:45:41.401592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.054919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:45:41.401688Z","caller":"traceutil/trace.go:171","msg":"trace[1438815981] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1376; }","duration":"477.185082ms","start":"2026-05-31T05:45:40.924487Z","end":"2026-05-31T05:45:41.401672Z","steps":["trace[1438815981] 'agreement among raft nodes before linearized reading' (duration: 477.032427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:41.401823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:40.924472Z","time spent":"477.294995ms","remote":"127.0.0.1:43102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:45:41.401914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.311315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-31T05:45:41.40198Z","caller":"traceutil/trace.go:171","msg":"trace[908812960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1376; }","duration":"318.280831ms","start":"2026-05-31T05:45:41.08368Z","end":"2026-05-31T05:45:41.401961Z","steps":["trace[908812960] 'agreement among raft nodes before linearized reading' (duration: 317.031927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:41.402026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:45:41.083668Z","time spent":"318.342824ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-31T05:45:49.698777Z","caller":"traceutil/trace.go:171","msg":"trace[1177795256] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"140.402399ms","start":"2026-05-31T05:45:49.558329Z","end":"2026-05-31T05:45:49.698731Z","steps":["trace[1177795256] 'process raft request' (duration: 68.317061ms)","trace[1177795256] 'compare' (duration: 71.936404ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:49.820863Z","caller":"traceutil/trace.go:171","msg":"trace[2081646261] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"261.923204ms","start":"2026-05-31T05:45:49.558906Z","end":"2026-05-31T05:45:49.82083Z","steps":["trace[2081646261] 'process raft request' (duration: 261.493122ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:49.821025Z","caller":"traceutil/trace.go:171","msg":"trace[2080851523] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"262.355877ms","start":"2026-05-31T05:45:49.558635Z","end":"2026-05-31T05:45:49.820991Z","steps":["trace[2080851523] 'process raft request' (duration: 247.632521ms)","trace[2080851523] 'compare' (duration: 13.976095ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:51.347214Z","caller":"traceutil/trace.go:171","msg":"trace[580816053] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"149.885696ms","start":"2026-05-31T05:45:51.197292Z","end":"2026-05-31T05:45:51.347177Z","steps":["trace[580816053] 'process raft request' (duration: 149.525666ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:51.479863Z","caller":"traceutil/trace.go:171","msg":"trace[551236382] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"123.893856ms","start":"2026-05-31T05:45:51.355931Z","end":"2026-05-31T05:45:51.479825Z","steps":["trace[551236382] 'process raft request' (duration: 114.88137ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:51.741903Z","caller":"traceutil/trace.go:171","msg":"trace[1490823177] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1522; }","duration":"132.826871ms","start":"2026-05-31T05:45:51.609023Z","end":"2026-05-31T05:45:51.74185Z","steps":["trace[1490823177] 'process raft request' (duration: 88.678045ms)","trace[1490823177] 'compare' (duration: 43.592111ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:54.109699Z","caller":"traceutil/trace.go:171","msg":"trace[1763498730] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"167.911891ms","start":"2026-05-31T05:45:53.941751Z","end":"2026-05-31T05:45:54.109663Z","steps":["trace[1763498730] 'process raft request' (duration: 167.652343ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:54.109855Z","caller":"traceutil/trace.go:171","msg":"trace[1169924437] linearizableReadLoop","detail":"{readStateIndex:1605; appliedIndex:1603; }","duration":"167.364675ms","start":"2026-05-31T05:45:53.942459Z","end":"2026-05-31T05:45:54.109824Z","steps":["trace[1169924437] 'read index received' (duration: 9.148093ms)","trace[1169924437] 'applied index is now lower than readState.Index' (duration: 158.214432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:45:54.109997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.512629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:45:54.110043Z","caller":"traceutil/trace.go:171","msg":"trace[690194564] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/operatorpolicies.rabbitmq.com; range_end:; response_count:0; response_revision:1556; }","duration":"167.604492ms","start":"2026-05-31T05:45:53.942423Z","end":"2026-05-31T05:45:54.110028Z","steps":["trace[690194564] 'agreement among raft nodes before linearized reading' (duration: 167.512659ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:54.110037Z","caller":"traceutil/trace.go:171","msg":"trace[559591661] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"164.830733ms","start":"2026-05-31T05:45:53.945072Z","end":"2026-05-31T05:45:54.109903Z","steps":["trace[559591661] 'process raft request' (duration: 164.523324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:54.494293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.817172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:45:54.494431Z","caller":"traceutil/trace.go:171","msg":"trace[1626538558] linearizableReadLoop","detail":"{readStateIndex:1613; appliedIndex:1612; }","duration":"171.56879ms","start":"2026-05-31T05:45:54.32284Z","end":"2026-05-31T05:45:54.494409Z","steps":["trace[1626538558] 'read index received' (duration: 42.550822ms)","trace[1626538558] 'applied index is now lower than readState.Index' (duration: 129.016218ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:54.494446Z","caller":"traceutil/trace.go:171","msg":"trace[1236379375] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"177.001077ms","start":"2026-05-31T05:45:54.317411Z","end":"2026-05-31T05:45:54.494412Z","steps":["trace[1236379375] 'process raft request' (duration: 47.960319ms)","trace[1236379375] 'compare' (duration: 128.685648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:45:54.49469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.842558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-05-31T05:45:54.494737Z","caller":"traceutil/trace.go:171","msg":"trace[819530549] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:1563; }","duration":"171.91753ms","start":"2026-05-31T05:45:54.322804Z","end":"2026-05-31T05:45:54.494722Z","steps":["trace[819530549] 'agreement among raft nodes before linearized reading' (duration: 171.663893ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:54.495659Z","caller":"traceutil/trace.go:171","msg":"trace[475768900] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"143.859245ms","start":"2026-05-31T05:45:54.351785Z","end":"2026-05-31T05:45:54.495644Z","steps":["trace[475768900] 'process raft request' (duration: 143.810824ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:54.495859Z","caller":"traceutil/trace.go:171","msg":"trace[1750084921] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"171.783318ms","start":"2026-05-31T05:45:54.324014Z","end":"2026-05-31T05:45:54.495797Z","steps":["trace[1750084921] 'process raft request' (duration: 171.5052ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:55.406945Z","caller":"traceutil/trace.go:171","msg":"trace[402693588] linearizableReadLoop","detail":"{readStateIndex:1639; appliedIndex:1637; }","duration":"231.35987ms","start":"2026-05-31T05:45:55.175549Z","end":"2026-05-31T05:45:55.406909Z","steps":["trace[402693588] 'read index received' (duration: 44.537954ms)","trace[402693588] 'applied index is now lower than readState.Index' (duration: 186.819906ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:55.407062Z","caller":"traceutil/trace.go:171","msg":"trace[381205463] transaction","detail":"{read_only:false; response_revision:1588; number_of_response:1; }","duration":"253.844179ms","start":"2026-05-31T05:45:55.153177Z","end":"2026-05-31T05:45:55.407021Z","steps":["trace[381205463] 'process raft request' (duration: 244.683895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:55.408534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.182104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-j4xkh\" ","response":"range_response_count:1 size:4218"} {"level":"info","ts":"2026-05-31T05:45:55.408652Z","caller":"traceutil/trace.go:171","msg":"trace[2101233175] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-j4xkh; range_end:; response_count:1; response_revision:1588; }","duration":"232.323898ms","start":"2026-05-31T05:45:55.176273Z","end":"2026-05-31T05:45:55.408597Z","steps":["trace[2101233175] 'agreement among raft nodes before linearized reading' (duration: 230.835925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:55.409592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.002297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:45:55.40969Z","caller":"traceutil/trace.go:171","msg":"trace[1426710091] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1588; }","duration":"234.164682ms","start":"2026-05-31T05:45:55.175503Z","end":"2026-05-31T05:45:55.409667Z","steps":["trace[1426710091] 'agreement among raft nodes before linearized reading' (duration: 231.575687ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:55.539822Z","caller":"traceutil/trace.go:171","msg":"trace[1065091206] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"117.982421ms","start":"2026-05-31T05:45:55.421763Z","end":"2026-05-31T05:45:55.539745Z","steps":["trace[1065091206] 'process raft request' (duration: 88.45232ms)","trace[1065091206] 'compare' (duration: 29.239063ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:55.717206Z","caller":"traceutil/trace.go:171","msg":"trace[291945264] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"146.629518ms","start":"2026-05-31T05:45:55.570543Z","end":"2026-05-31T05:45:55.717172Z","steps":["trace[291945264] 'process raft request' (duration: 94.997289ms)","trace[291945264] 'compare' (duration: 51.261109ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:55.717531Z","caller":"traceutil/trace.go:171","msg":"trace[1088060] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"131.620855ms","start":"2026-05-31T05:45:55.585873Z","end":"2026-05-31T05:45:55.717494Z","steps":["trace[1088060] 'process raft request' (duration: 131.245425ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:45:55.728837Z","caller":"traceutil/trace.go:171","msg":"trace[983309746] linearizableReadLoop","detail":"{readStateIndex:1647; appliedIndex:1644; }","duration":"105.743109ms","start":"2026-05-31T05:45:55.623076Z","end":"2026-05-31T05:45:55.728819Z","steps":["trace[983309746] 'read index received' (duration: 42.439683ms)","trace[983309746] 'applied index is now lower than readState.Index' (duration: 63.302586ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:45:55.72894Z","caller":"traceutil/trace.go:171","msg":"trace[1947687076] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"131.689467ms","start":"2026-05-31T05:45:55.597224Z","end":"2026-05-31T05:45:55.728914Z","steps":["trace[1947687076] 'process raft request' (duration: 131.505491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:45:55.729036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.961795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:45:55.729063Z","caller":"traceutil/trace.go:171","msg":"trace[715983010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1596; }","duration":"106.185892ms","start":"2026-05-31T05:45:55.622869Z","end":"2026-05-31T05:45:55.729055Z","steps":["trace[715983010] 'agreement among raft nodes before linearized reading' (duration: 106.045108ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:18.118543Z","caller":"traceutil/trace.go:171","msg":"trace[1418736213] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1995; }","duration":"243.590056ms","start":"2026-05-31T05:46:17.87492Z","end":"2026-05-31T05:46:18.11851Z","steps":["trace[1418736213] 'read index received' (duration: 243.374189ms)","trace[1418736213] 'applied index is now lower than readState.Index' (duration: 213.997µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:46:18.118648Z","caller":"traceutil/trace.go:171","msg":"trace[1230570400] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"303.446951ms","start":"2026-05-31T05:46:17.815154Z","end":"2026-05-31T05:46:18.118601Z","steps":["trace[1230570400] 'process raft request' (duration: 303.143371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.118808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.860733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:46:18.118967Z","caller":"traceutil/trace.go:171","msg":"trace[1093968394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1920; }","duration":"244.006848ms","start":"2026-05-31T05:46:17.874901Z","end":"2026-05-31T05:46:18.118908Z","steps":["trace[1093968394] 'agreement among raft nodes before linearized reading' (duration: 243.802182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.118975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:17.815122Z","time spent":"303.695818ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:46:18.380017Z","caller":"traceutil/trace.go:171","msg":"trace[1286251879] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"261.271224ms","start":"2026-05-31T05:46:18.118709Z","end":"2026-05-31T05:46:18.37998Z","steps":["trace[1286251879] 'read index received' (duration: 260.959764ms)","trace[1286251879] 'applied index is now lower than readState.Index' (duration: 310.35µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:46:18.380237Z","caller":"traceutil/trace.go:171","msg":"trace[1733536618] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"331.931633ms","start":"2026-05-31T05:46:18.048277Z","end":"2026-05-31T05:46:18.380209Z","steps":["trace[1733536618] 'process raft request' (duration: 331.50416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.380349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.202315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-31T05:46:18.380403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.301983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:46:18.380408Z","caller":"traceutil/trace.go:171","msg":"trace[1697347237] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1921; }","duration":"266.324608ms","start":"2026-05-31T05:46:18.114067Z","end":"2026-05-31T05:46:18.380392Z","steps":["trace[1697347237] 'agreement among raft nodes before linearized reading' (duration: 266.098292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.380419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:18.04824Z","time spent":"332.085057ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:46:18.380463Z","caller":"traceutil/trace.go:171","msg":"trace[566603658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1921; }","duration":"487.411877ms","start":"2026-05-31T05:46:17.893035Z","end":"2026-05-31T05:46:18.380447Z","steps":["trace[566603658] 'agreement among raft nodes before linearized reading' (duration: 487.251332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.38042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.782878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-740d7e57-fedd-4516-ac7f-e44b26940ac6\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-31T05:46:18.380599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.217381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:46:18.380527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.221515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-31T05:46:18.380618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.854425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:46:18.380638Z","caller":"traceutil/trace.go:171","msg":"trace[723336536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1921; }","duration":"260.301094ms","start":"2026-05-31T05:46:18.120329Z","end":"2026-05-31T05:46:18.38063Z","steps":["trace[723336536] 'agreement among raft nodes before linearized reading' (duration: 260.238252ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:18.380665Z","caller":"traceutil/trace.go:171","msg":"trace[707219314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1921; }","duration":"498.956078ms","start":"2026-05-31T05:46:17.881696Z","end":"2026-05-31T05:46:18.380653Z","steps":["trace[707219314] 'agreement among raft nodes before linearized reading' (duration: 498.830375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.380701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:17.881673Z","time spent":"499.01929ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-31T05:46:18.380678Z","caller":"traceutil/trace.go:171","msg":"trace[2138997810] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1921; }","duration":"266.532264ms","start":"2026-05-31T05:46:18.114123Z","end":"2026-05-31T05:46:18.380655Z","steps":["trace[2138997810] 'agreement among raft nodes before linearized reading' (duration: 266.115832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.380504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:17.893007Z","time spent":"487.485419ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-31T05:46:18.380252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.273224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:46:18.380967Z","caller":"traceutil/trace.go:171","msg":"trace[602436865] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1921; }","duration":"470.065868ms","start":"2026-05-31T05:46:17.910881Z","end":"2026-05-31T05:46:18.380947Z","steps":["trace[602436865] 'agreement among raft nodes before linearized reading' (duration: 469.287845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.381005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.093486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-31T05:46:18.381042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:17.910847Z","time spent":"470.171931ms","remote":"127.0.0.1:55910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:46:18.38107Z","caller":"traceutil/trace.go:171","msg":"trace[721100847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1921; }","duration":"366.20237ms","start":"2026-05-31T05:46:18.014848Z","end":"2026-05-31T05:46:18.381051Z","steps":["trace[721100847] 'agreement among raft nodes before linearized reading' (duration: 366.024725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.381129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:18.014823Z","time spent":"366.291893ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T05:46:18.381162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.225938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-31T05:46:18.380615Z","caller":"traceutil/trace.go:171","msg":"trace[855476486] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-740d7e57-fedd-4516-ac7f-e44b26940ac6; range_end:; response_count:1; response_revision:1921; }","duration":"313.059806ms","start":"2026-05-31T05:46:18.067519Z","end":"2026-05-31T05:46:18.380578Z","steps":["trace[855476486] 'agreement among raft nodes before linearized reading' (duration: 312.569091ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:18.381276Z","caller":"traceutil/trace.go:171","msg":"trace[263908444] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1921; }","duration":"307.531007ms","start":"2026-05-31T05:46:18.073714Z","end":"2026-05-31T05:46:18.381245Z","steps":["trace[263908444] 'agreement among raft nodes before linearized reading' (duration: 306.323711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.381301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:18.067501Z","time spent":"313.772808ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-740d7e57-fedd-4516-ac7f-e44b26940ac6\" "} {"level":"warn","ts":"2026-05-31T05:46:18.381341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:18.073684Z","time spent":"307.640711ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-31T05:46:18.381632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.064016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-31T05:46:18.381713Z","caller":"traceutil/trace.go:171","msg":"trace[763432749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1921; }","duration":"134.19081ms","start":"2026-05-31T05:46:18.247506Z","end":"2026-05-31T05:46:18.381697Z","steps":["trace[763432749] 'agreement among raft nodes before linearized reading' (duration: 133.179419ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:18.499122Z","caller":"traceutil/trace.go:171","msg":"trace[1024259568] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"110.601779ms","start":"2026-05-31T05:46:18.388489Z","end":"2026-05-31T05:46:18.499091Z","steps":["trace[1024259568] 'process raft request' (duration: 110.496776ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:18.499143Z","caller":"traceutil/trace.go:171","msg":"trace[1782912694] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"110.869667ms","start":"2026-05-31T05:46:18.388228Z","end":"2026-05-31T05:46:18.499097Z","steps":["trace[1782912694] 'process raft request' (duration: 82.16097ms)","trace[1782912694] 'compare' (duration: 28.432589ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:46:18.499711Z","caller":"traceutil/trace.go:171","msg":"trace[765233768] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"111.084093ms","start":"2026-05-31T05:46:18.388609Z","end":"2026-05-31T05:46:18.499693Z","steps":["trace[765233768] 'process raft request' (duration: 110.434334ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:18.499759Z","caller":"traceutil/trace.go:171","msg":"trace[15303436] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"111.088854ms","start":"2026-05-31T05:46:18.38865Z","end":"2026-05-31T05:46:18.499739Z","steps":["trace[15303436] 'process raft request' (duration: 110.64596ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:18.499866Z","caller":"traceutil/trace.go:171","msg":"trace[1866531710] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"110.96112ms","start":"2026-05-31T05:46:18.388875Z","end":"2026-05-31T05:46:18.499836Z","steps":["trace[1866531710] 'process raft request' (duration: 110.608929ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:18.50054Z","caller":"traceutil/trace.go:171","msg":"trace[978661555] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"110.013182ms","start":"2026-05-31T05:46:18.390508Z","end":"2026-05-31T05:46:18.500521Z","steps":["trace[978661555] 'process raft request' (duration: 109.900399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.994154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.586443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:46:18.994378Z","caller":"traceutil/trace.go:171","msg":"trace[196066231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1928; }","duration":"117.853281ms","start":"2026-05-31T05:46:18.876475Z","end":"2026-05-31T05:46:18.994328Z","steps":["trace[196066231] 'range keys from in-memory index tree' (duration: 117.48241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:18.994674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.994721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-31T05:46:18.994764Z","caller":"traceutil/trace.go:171","msg":"trace[297602407] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"234.181086ms","start":"2026-05-31T05:46:18.760559Z","end":"2026-05-31T05:46:18.99474Z","steps":["trace[297602407] 'range keys from in-memory index tree' (duration: 233.537936ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:32.556313Z","caller":"traceutil/trace.go:171","msg":"trace[813647361] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"435.394644ms","start":"2026-05-31T05:46:32.120896Z","end":"2026-05-31T05:46:32.55629Z","steps":["trace[813647361] 'process raft request' (duration: 428.299772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:32.556472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:32.120876Z","time spent":"435.505427ms","remote":"127.0.0.1:43304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:46:32.556537Z","caller":"traceutil/trace.go:171","msg":"trace[1538695267] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"388.379731ms","start":"2026-05-31T05:46:32.168124Z","end":"2026-05-31T05:46:32.556503Z","steps":["trace[1538695267] 'process raft request' (duration: 387.791583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:32.556731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:32.168097Z","time spent":"388.537947ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:46:52.866329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.677966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:46:52.866454Z","caller":"traceutil/trace.go:171","msg":"trace[934356716] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2248; }","duration":"143.264312ms","start":"2026-05-31T05:46:52.72317Z","end":"2026-05-31T05:46:52.866435Z","steps":["trace[934356716] 'read index received' (duration: 4.322209ms)","trace[934356716] 'applied index is now lower than readState.Index' (duration: 138.941083ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:46:52.866618Z","caller":"traceutil/trace.go:171","msg":"trace[2023104435] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"199.018748ms","start":"2026-05-31T05:46:52.66752Z","end":"2026-05-31T05:46:52.866538Z","steps":["trace[2023104435] 'process raft request' (duration: 60.037793ms)","trace[2023104435] 'compare' (duration: 138.5233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:46:52.866923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.724526ms","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-05-31T05:46:52.867282Z","caller":"traceutil/trace.go:171","msg":"trace[1330319603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2164; }","duration":"144.15278ms","start":"2026-05-31T05:46:52.723105Z","end":"2026-05-31T05:46:52.867258Z","steps":["trace[1330319603] 'agreement among raft nodes before linearized reading' (duration: 143.543951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:52.867303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.455102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-31T05:46:52.866907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.36747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-31T05:46:52.867573Z","caller":"traceutil/trace.go:171","msg":"trace[861026537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2164; }","duration":"131.746112ms","start":"2026-05-31T05:46:52.735806Z","end":"2026-05-31T05:46:52.867552Z","steps":["trace[861026537] 'agreement among raft nodes before linearized reading' (duration: 131.36923ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:52.867805Z","caller":"traceutil/trace.go:171","msg":"trace[301406923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2164; }","duration":"132.216837ms","start":"2026-05-31T05:46:52.735487Z","end":"2026-05-31T05:46:52.867704Z","steps":["trace[301406923] 'agreement among raft nodes before linearized reading' (duration: 131.185334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:52.866979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.843025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-31T05:46:52.867043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.159751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-31T05:46:52.867984Z","caller":"traceutil/trace.go:171","msg":"trace[575083782] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2164; }","duration":"122.117322ms","start":"2026-05-31T05:46:52.745848Z","end":"2026-05-31T05:46:52.867965Z","steps":["trace[575083782] 'agreement among raft nodes before linearized reading' (duration: 121.078798ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:52.867926Z","caller":"traceutil/trace.go:171","msg":"trace[646253780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2164; }","duration":"128.813047ms","start":"2026-05-31T05:46:52.739091Z","end":"2026-05-31T05:46:52.867904Z","steps":["trace[646253780] 'agreement among raft nodes before linearized reading' (duration: 127.749262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:52.866924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.523082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:46:52.868111Z","caller":"traceutil/trace.go:171","msg":"trace[1535883397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2164; }","duration":"122.738662ms","start":"2026-05-31T05:46:52.745359Z","end":"2026-05-31T05:46:52.868098Z","steps":["trace[1535883397] 'agreement among raft nodes before linearized reading' (duration: 121.414189ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:53.204585Z","caller":"traceutil/trace.go:171","msg":"trace[1572138051] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"330.775416ms","start":"2026-05-31T05:46:52.873782Z","end":"2026-05-31T05:46:53.204557Z","steps":["trace[1572138051] 'process raft request' (duration: 330.671123ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:46:53.204694Z","caller":"traceutil/trace.go:171","msg":"trace[137813703] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"330.857769ms","start":"2026-05-31T05:46:52.873784Z","end":"2026-05-31T05:46:53.204642Z","steps":["trace[137813703] 'process raft request' (duration: 243.769352ms)","trace[137813703] 'compare' (duration: 86.772817ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:46:53.205062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:52.873755Z","time spent":"331.130218ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:46:53.205129Z","caller":"traceutil/trace.go:171","msg":"trace[2091742952] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"331.233411ms","start":"2026-05-31T05:46:52.873875Z","end":"2026-05-31T05:46:53.205108Z","steps":["trace[2091742952] 'process raft request' (duration: 330.633062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:53.205957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:52.873867Z","time spent":"331.987955ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:46:53.204777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:52.873755Z","time spent":"330.928741ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:46:53.206175Z","caller":"traceutil/trace.go:171","msg":"trace[273556180] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"331.003703ms","start":"2026-05-31T05:46:52.875148Z","end":"2026-05-31T05:46:53.206151Z","steps":["trace[273556180] 'process raft request' (duration: 330.930701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:53.206283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:52.875136Z","time spent":"331.097287ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:46:53.206415Z","caller":"traceutil/trace.go:171","msg":"trace[1901986716] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"332.421269ms","start":"2026-05-31T05:46:52.873978Z","end":"2026-05-31T05:46:53.206399Z","steps":["trace[1901986716] 'process raft request' (duration: 331.919463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:46:53.206502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:46:52.87397Z","time spent":"332.484971ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:46:53.768051Z","caller":"traceutil/trace.go:171","msg":"trace[197730904] linearizableReadLoop","detail":"{readStateIndex:2256; appliedIndex:2255; }","duration":"120.657039ms","start":"2026-05-31T05:46:53.647366Z","end":"2026-05-31T05:46:53.768023Z","steps":["trace[197730904] 'read index received' (duration: 87.258772ms)","trace[197730904] 'applied index is now lower than readState.Index' (duration: 33.396496ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:46:53.768163Z","caller":"traceutil/trace.go:171","msg":"trace[2031691284] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"173.870044ms","start":"2026-05-31T05:46:53.59425Z","end":"2026-05-31T05:46:53.76812Z","steps":["trace[2031691284] 'process raft request' (duration: 140.334223ms)","trace[2031691284] 'compare' (duration: 33.040185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:46:53.768228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.796883ms","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-05-31T05:46:53.768302Z","caller":"traceutil/trace.go:171","msg":"trace[2142422479] 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:2171; }","duration":"120.912826ms","start":"2026-05-31T05:46:53.64735Z","end":"2026-05-31T05:46:53.768263Z","steps":["trace[2142422479] 'agreement among raft nodes before linearized reading' (duration: 120.769592ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:47:57.137084Z","caller":"traceutil/trace.go:171","msg":"trace[1984518096] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"183.910703ms","start":"2026-05-31T05:47:56.953142Z","end":"2026-05-31T05:47:57.137052Z","steps":["trace[1984518096] 'process raft request' (duration: 183.575492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:48:30.366922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.946238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:48:30.367485Z","caller":"traceutil/trace.go:171","msg":"trace[424186836] linearizableReadLoop","detail":"{readStateIndex:2905; appliedIndex:2903; }","duration":"558.06566ms","start":"2026-05-31T05:48:29.809405Z","end":"2026-05-31T05:48:30.367471Z","steps":["trace[424186836] 'read index received' (duration: 253.469498ms)","trace[424186836] 'applied index is now lower than readState.Index' (duration: 304.595282ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:48:30.367552Z","caller":"traceutil/trace.go:171","msg":"trace[1645832842] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"567.665819ms","start":"2026-05-31T05:48:29.799845Z","end":"2026-05-31T05:48:30.367511Z","steps":["trace[1645832842] 'process raft request' (duration: 567.549889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:48:30.367754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.411707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:48:30.3678Z","caller":"traceutil/trace.go:171","msg":"trace[215489063] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"227.688163ms","start":"2026-05-31T05:48:30.14008Z","end":"2026-05-31T05:48:30.367768Z","steps":["trace[215489063] 'process raft request' (duration: 227.36872ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:48:30.367844Z","caller":"traceutil/trace.go:171","msg":"trace[1297413139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2799; }","duration":"489.541547ms","start":"2026-05-31T05:48:29.878264Z","end":"2026-05-31T05:48:30.367805Z","steps":["trace[1297413139] 'agreement among raft nodes before linearized reading' (duration: 489.388226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:48:30.367905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:48:29.878234Z","time spent":"489.661328ms","remote":"127.0.0.1:42796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-31T05:48:30.367907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:48:29.799828Z","time spent":"567.955062ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:48:30.367956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.536724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-31T05:48:30.36804Z","caller":"traceutil/trace.go:171","msg":"trace[1737521433] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2799; }","duration":"558.631464ms","start":"2026-05-31T05:48:29.809384Z","end":"2026-05-31T05:48:30.368015Z","steps":["trace[1737521433] 'agreement among raft nodes before linearized reading' (duration: 558.143441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:48:30.368093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:48:29.809375Z","time spent":"558.707006ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-31T05:48:30.367558Z","caller":"traceutil/trace.go:171","msg":"trace[800639343] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"569.45386ms","start":"2026-05-31T05:48:29.798032Z","end":"2026-05-31T05:48:30.367486Z","steps":["trace[800639343] 'process raft request' (duration: 264.868819ms)","trace[800639343] 'compare' (duration: 303.859637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:48:30.368362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:48:29.798012Z","time spent":"570.243047ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:48:35.548736Z","caller":"traceutil/trace.go:171","msg":"trace[77270807] linearizableReadLoop","detail":"{readStateIndex:2930; appliedIndex:2929; }","duration":"187.851987ms","start":"2026-05-31T05:48:35.360851Z","end":"2026-05-31T05:48:35.548703Z","steps":["trace[77270807] 'read index received' (duration: 114.824312ms)","trace[77270807] 'applied index is now lower than readState.Index' (duration: 73.026265ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:48:35.549243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.378761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b4905efc9b331f\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-31T05:48:35.549285Z","caller":"traceutil/trace.go:171","msg":"trace[14055255] transaction","detail":"{read_only:false; response_revision:2822; number_of_response:1; }","duration":"235.327066ms","start":"2026-05-31T05:48:35.31391Z","end":"2026-05-31T05:48:35.549237Z","steps":["trace[14055255] 'process raft request' (duration: 161.904358ms)","trace[14055255] 'compare' (duration: 72.743183ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:48:35.549362Z","caller":"traceutil/trace.go:171","msg":"trace[1243402423] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b4905efc9b331f; range_end:; response_count:1; response_revision:2822; }","duration":"188.541072ms","start":"2026-05-31T05:48:35.360791Z","end":"2026-05-31T05:48:35.549332Z","steps":["trace[1243402423] 'agreement among raft nodes before linearized reading' (duration: 188.113389ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:27.305924Z","caller":"traceutil/trace.go:171","msg":"trace[1974168690] transaction","detail":"{read_only:false; response_revision:3491; number_of_response:1; }","duration":"185.045672ms","start":"2026-05-31T05:50:27.120855Z","end":"2026-05-31T05:50:27.305901Z","steps":["trace[1974168690] 'process raft request' (duration: 184.907119ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:27.306114Z","caller":"traceutil/trace.go:171","msg":"trace[1990987328] linearizableReadLoop","detail":"{readStateIndex:3626; appliedIndex:3625; }","duration":"183.661732ms","start":"2026-05-31T05:50:27.122438Z","end":"2026-05-31T05:50:27.3061Z","steps":["trace[1990987328] 'read index received' (duration: 183.241223ms)","trace[1990987328] 'applied index is now lower than readState.Index' (duration: 419.159µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:50:27.306263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.805235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:50:27.306301Z","caller":"traceutil/trace.go:171","msg":"trace[1790222679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3491; }","duration":"183.873386ms","start":"2026-05-31T05:50:27.122419Z","end":"2026-05-31T05:50:27.306292Z","steps":["trace[1790222679] 'agreement among raft nodes before linearized reading' (duration: 183.740193ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:31.138577Z","caller":"traceutil/trace.go:171","msg":"trace[1743620421] transaction","detail":"{read_only:false; response_revision:3513; number_of_response:1; }","duration":"172.509054ms","start":"2026-05-31T05:50:30.966021Z","end":"2026-05-31T05:50:31.13853Z","steps":["trace[1743620421] 'process raft request' (duration: 172.32525ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:35.653949Z","caller":"traceutil/trace.go:171","msg":"trace[181159223] transaction","detail":"{read_only:false; response_revision:3540; number_of_response:1; }","duration":"241.952389ms","start":"2026-05-31T05:50:35.411976Z","end":"2026-05-31T05:50:35.653928Z","steps":["trace[181159223] 'process raft request' (duration: 241.695323ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:35.654059Z","caller":"traceutil/trace.go:171","msg":"trace[369407188] linearizableReadLoop","detail":"{readStateIndex:3677; appliedIndex:3676; }","duration":"221.92957ms","start":"2026-05-31T05:50:35.431928Z","end":"2026-05-31T05:50:35.653857Z","steps":["trace[369407188] 'read index received' (duration: 221.796518ms)","trace[369407188] 'applied index is now lower than readState.Index' (duration: 131.982µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:50:35.654275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.198554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-05-31T05:50:35.654318Z","caller":"traceutil/trace.go:171","msg":"trace[1958894999] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3540; }","duration":"210.291146ms","start":"2026-05-31T05:50:35.444015Z","end":"2026-05-31T05:50:35.654306Z","steps":["trace[1958894999] 'agreement among raft nodes before linearized reading' (duration: 210.131943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:35.654266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.280089ms","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-05-31T05:50:35.654406Z","caller":"traceutil/trace.go:171","msg":"trace[1919002343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3540; }","duration":"222.466712ms","start":"2026-05-31T05:50:35.431887Z","end":"2026-05-31T05:50:35.654354Z","steps":["trace[1919002343] 'agreement among raft nodes before linearized reading' (duration: 222.140265ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:36.834747Z","caller":"traceutil/trace.go:171","msg":"trace[332113286] linearizableReadLoop","detail":"{readStateIndex:3683; appliedIndex:3681; }","duration":"262.376786ms","start":"2026-05-31T05:50:36.572341Z","end":"2026-05-31T05:50:36.834718Z","steps":["trace[332113286] 'read index received' (duration: 80.403267ms)","trace[332113286] 'applied index is now lower than readState.Index' (duration: 181.972199ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:50:36.834765Z","caller":"traceutil/trace.go:171","msg":"trace[1717538307] transaction","detail":"{read_only:false; response_revision:3546; number_of_response:1; }","duration":"266.296334ms","start":"2026-05-31T05:50:36.56844Z","end":"2026-05-31T05:50:36.834736Z","steps":["trace[1717538307] 'process raft request' (duration: 243.689926ms)","trace[1717538307] 'compare' (duration: 22.28664ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:50:36.834931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.569211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:50:36.835141Z","caller":"traceutil/trace.go:171","msg":"trace[1804622633] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3546; }","duration":"262.833886ms","start":"2026-05-31T05:50:36.572293Z","end":"2026-05-31T05:50:36.835127Z","steps":["trace[1804622633] 'agreement among raft nodes before linearized reading' (duration: 262.582451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:50:36.835256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.697439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-31T05:50:36.835302Z","caller":"traceutil/trace.go:171","msg":"trace[672852673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3546; }","duration":"204.878893ms","start":"2026-05-31T05:50:36.630411Z","end":"2026-05-31T05:50:36.83529Z","steps":["trace[672852673] 'agreement among raft nodes before linearized reading' (duration: 204.619277ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:44.834013Z","caller":"traceutil/trace.go:171","msg":"trace[1051029368] linearizableReadLoop","detail":"{readStateIndex:3727; appliedIndex:3726; }","duration":"136.905986ms","start":"2026-05-31T05:50:44.697079Z","end":"2026-05-31T05:50:44.833985Z","steps":["trace[1051029368] 'read index received' (duration: 136.6403ms)","trace[1051029368] 'applied index is now lower than readState.Index' (duration: 264.036µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:50:44.834265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.166171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-05-31T05:50:44.834336Z","caller":"traceutil/trace.go:171","msg":"trace[414732875] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret; range_end:; response_count:1; response_revision:3589; }","duration":"137.281204ms","start":"2026-05-31T05:50:44.697037Z","end":"2026-05-31T05:50:44.834318Z","steps":["trace[414732875] 'agreement among raft nodes before linearized reading' (duration: 137.06842ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:50:44.83411Z","caller":"traceutil/trace.go:171","msg":"trace[948593664] transaction","detail":"{read_only:false; response_revision:3589; number_of_response:1; }","duration":"137.746594ms","start":"2026-05-31T05:50:44.696335Z","end":"2026-05-31T05:50:44.834082Z","steps":["trace[948593664] 'process raft request' (duration: 137.469968ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:09.075092Z","caller":"traceutil/trace.go:171","msg":"trace[74904971] transaction","detail":"{read_only:false; response_revision:3721; number_of_response:1; }","duration":"107.149029ms","start":"2026-05-31T05:51:08.967915Z","end":"2026-05-31T05:51:09.075064Z","steps":["trace[74904971] 'process raft request' (duration: 106.872403ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:23.324266Z","caller":"traceutil/trace.go:171","msg":"trace[136051675] linearizableReadLoop","detail":"{readStateIndex:3947; appliedIndex:3946; }","duration":"129.913107ms","start":"2026-05-31T05:51:23.19433Z","end":"2026-05-31T05:51:23.324243Z","steps":["trace[136051675] 'read index received' (duration: 129.715192ms)","trace[136051675] 'applied index is now lower than readState.Index' (duration: 197.095µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:23.324373Z","caller":"traceutil/trace.go:171","msg":"trace[900924096] transaction","detail":"{read_only:false; response_revision:3800; number_of_response:1; }","duration":"160.224688ms","start":"2026-05-31T05:51:23.164113Z","end":"2026-05-31T05:51:23.324337Z","steps":["trace[900924096] 'process raft request' (duration: 159.997532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:23.324591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.243234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:51:23.324686Z","caller":"traceutil/trace.go:171","msg":"trace[243918135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3800; }","duration":"130.390648ms","start":"2026-05-31T05:51:23.19427Z","end":"2026-05-31T05:51:23.32466Z","steps":["trace[243918135] 'agreement among raft nodes before linearized reading' (duration: 130.110341ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:46.475387Z","caller":"traceutil/trace.go:171","msg":"trace[437882011] transaction","detail":"{read_only:false; response_revision:3995; number_of_response:1; }","duration":"118.176359ms","start":"2026-05-31T05:51:46.357172Z","end":"2026-05-31T05:51:46.475348Z","steps":["trace[437882011] 'process raft request' (duration: 22.197325ms)","trace[437882011] 'compare' (duration: 95.681066ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:46.475595Z","caller":"traceutil/trace.go:171","msg":"trace[427443760] transaction","detail":"{read_only:false; response_revision:3996; number_of_response:1; }","duration":"117.779098ms","start":"2026-05-31T05:51:46.357776Z","end":"2026-05-31T05:51:46.475556Z","steps":["trace[427443760] 'process raft request' (duration: 117.518542ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:46.961915Z","caller":"traceutil/trace.go:171","msg":"trace[1335937147] linearizableReadLoop","detail":"{readStateIndex:4153; appliedIndex:4152; }","duration":"122.682776ms","start":"2026-05-31T05:51:46.83921Z","end":"2026-05-31T05:51:46.961893Z","steps":["trace[1335937147] 'read index received' (duration: 122.500652ms)","trace[1335937147] 'applied index is now lower than readState.Index' (duration: 181.284µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:46.961954Z","caller":"traceutil/trace.go:171","msg":"trace[2001924080] transaction","detail":"{read_only:false; response_revision:3998; number_of_response:1; }","duration":"122.897731ms","start":"2026-05-31T05:51:46.839034Z","end":"2026-05-31T05:51:46.961932Z","steps":["trace[2001924080] 'process raft request' (duration: 122.727227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:46.962071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.83752ms","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-05-31T05:51:46.962127Z","caller":"traceutil/trace.go:171","msg":"trace[557135064] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3998; }","duration":"122.908772ms","start":"2026-05-31T05:51:46.839205Z","end":"2026-05-31T05:51:46.962114Z","steps":["trace[557135064] 'agreement among raft nodes before linearized reading' (duration: 122.81029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:46.962105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.388581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:46.962218Z","caller":"traceutil/trace.go:171","msg":"trace[776027420] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:3998; }","duration":"109.529375ms","start":"2026-05-31T05:51:46.852672Z","end":"2026-05-31T05:51:46.962201Z","steps":["trace[776027420] 'agreement among raft nodes before linearized reading' (duration: 109.390462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:46.962426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.466708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:46.962484Z","caller":"traceutil/trace.go:171","msg":"trace[531371904] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:3998; }","duration":"108.54651ms","start":"2026-05-31T05:51:46.853924Z","end":"2026-05-31T05:51:46.96247Z","steps":["trace[531371904] 'agreement among raft nodes before linearized reading' (duration: 108.458718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:46.962509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.634988ms","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:5"} {"level":"info","ts":"2026-05-31T05:51:46.962629Z","caller":"traceutil/trace.go:171","msg":"trace[27434774] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:3998; }","duration":"109.74157ms","start":"2026-05-31T05:51:46.852806Z","end":"2026-05-31T05:51:46.962547Z","steps":["trace[27434774] 'agreement among raft nodes before linearized reading' (duration: 109.628187ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:50.703552Z","caller":"traceutil/trace.go:171","msg":"trace[753029078] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4017; }","duration":"176.140448ms","start":"2026-05-31T05:51:50.527387Z","end":"2026-05-31T05:51:50.703528Z","steps":["trace[753029078] 'process raft request' (duration: 175.955443ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:50.703967Z","caller":"traceutil/trace.go:171","msg":"trace[150604483] transaction","detail":"{read_only:false; response_revision:4017; number_of_response:1; }","duration":"194.459825ms","start":"2026-05-31T05:51:50.509473Z","end":"2026-05-31T05:51:50.703933Z","steps":["trace[150604483] 'process raft request' (duration: 161.769335ms)","trace[150604483] 'compare' (duration: 31.952201ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:05.960671Z","caller":"traceutil/trace.go:171","msg":"trace[999062795] transaction","detail":"{read_only:false; response_revision:4113; number_of_response:1; }","duration":"158.732576ms","start":"2026-05-31T05:52:05.801921Z","end":"2026-05-31T05:52:05.960654Z","steps":["trace[999062795] 'process raft request' (duration: 158.660984ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:05.960975Z","caller":"traceutil/trace.go:171","msg":"trace[896855004] transaction","detail":"{read_only:false; response_revision:4112; number_of_response:1; }","duration":"159.668971ms","start":"2026-05-31T05:52:05.801244Z","end":"2026-05-31T05:52:05.960913Z","steps":["trace[896855004] 'process raft request' (duration: 105.866988ms)","trace[896855004] 'compare' (duration: 53.355341ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:06.3234Z","caller":"traceutil/trace.go:171","msg":"trace[2057157310] transaction","detail":"{read_only:false; response_revision:4114; number_of_response:1; }","duration":"108.906624ms","start":"2026-05-31T05:52:06.214458Z","end":"2026-05-31T05:52:06.323365Z","steps":["trace[2057157310] 'process raft request' (duration: 108.692648ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:08.570008Z","caller":"traceutil/trace.go:171","msg":"trace[924454398] transaction","detail":"{read_only:false; response_revision:4130; number_of_response:1; }","duration":"100.386584ms","start":"2026-05-31T05:52:08.469599Z","end":"2026-05-31T05:52:08.569985Z","steps":["trace[924454398] 'process raft request' (duration: 100.139637ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:17.971189Z","caller":"traceutil/trace.go:171","msg":"trace[131681586] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"106.933451ms","start":"2026-05-31T05:52:17.864226Z","end":"2026-05-31T05:52:17.971159Z","steps":["trace[131681586] 'process raft request' (duration: 103.092887ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:17.97182Z","caller":"traceutil/trace.go:171","msg":"trace[690955139] linearizableReadLoop","detail":"{readStateIndex:4751; appliedIndex:4747; }","duration":"102.506671ms","start":"2026-05-31T05:52:17.869298Z","end":"2026-05-31T05:52:17.971804Z","steps":["trace[690955139] 'read index received' (duration: 98.0084ms)","trace[690955139] 'applied index is now lower than readState.Index' (duration: 4.497401ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:17.971928Z","caller":"traceutil/trace.go:171","msg":"trace[2089356806] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"107.478556ms","start":"2026-05-31T05:52:17.864419Z","end":"2026-05-31T05:52:17.971898Z","steps":["trace[2089356806] 'process raft request' (duration: 107.309021ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:17.971951Z","caller":"traceutil/trace.go:171","msg":"trace[1110839499] transaction","detail":"{read_only:false; response_revision:4568; number_of_response:1; }","duration":"107.67798ms","start":"2026-05-31T05:52:17.864255Z","end":"2026-05-31T05:52:17.971933Z","steps":["trace[1110839499] 'process raft request' (duration: 107.414333ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:17.972235Z","caller":"traceutil/trace.go:171","msg":"trace[292218414] transaction","detail":"{read_only:false; response_revision:4570; number_of_response:1; }","duration":"107.6592ms","start":"2026-05-31T05:52:17.864557Z","end":"2026-05-31T05:52:17.972216Z","steps":["trace[292218414] 'process raft request' (duration: 107.215798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:17.972366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.049355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-05-31T05:52:17.972426Z","caller":"traceutil/trace.go:171","msg":"trace[1678639235] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4570; }","duration":"103.140087ms","start":"2026-05-31T05:52:17.869273Z","end":"2026-05-31T05:52:17.972413Z","steps":["trace[1678639235] 'agreement among raft nodes before linearized reading' (duration: 102.947352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:19.207734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.640025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d39f401-4a56-427c-b04f-815b22c190b5\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-31T05:52:19.207816Z","caller":"traceutil/trace.go:171","msg":"trace[1919059258] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d39f401-4a56-427c-b04f-815b22c190b5; range_end:; response_count:1; response_revision:4635; }","duration":"194.770578ms","start":"2026-05-31T05:52:19.013029Z","end":"2026-05-31T05:52:19.207799Z","steps":["trace[1919059258] 'range keys from in-memory index tree' (duration: 194.48517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:19.208041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.086448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d39f401-4a56-427c-b04f-815b22c190b5\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-05-31T05:52:19.208088Z","caller":"traceutil/trace.go:171","msg":"trace[1821330148] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d39f401-4a56-427c-b04f-815b22c190b5; range_end:; response_count:1; response_revision:4635; }","duration":"112.17605ms","start":"2026-05-31T05:52:19.095899Z","end":"2026-05-31T05:52:19.208076Z","steps":["trace[1821330148] 'range keys from in-memory index tree' (duration: 111.993695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:19.208067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.17788ms","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-05-31T05:52:19.208144Z","caller":"traceutil/trace.go:171","msg":"trace[190830262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4635; }","duration":"335.299492ms","start":"2026-05-31T05:52:18.872827Z","end":"2026-05-31T05:52:19.208127Z","steps":["trace[190830262] 'range keys from in-memory index tree' (duration: 334.959593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:19.208185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:18.872812Z","time spent":"335.363795ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:52:19.208415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.516141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:19.208437Z","caller":"traceutil/trace.go:171","msg":"trace[185957212] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:4635; }","duration":"201.559673ms","start":"2026-05-31T05:52:19.00687Z","end":"2026-05-31T05:52:19.20843Z","steps":["trace[185957212] 'count revisions from in-memory index tree' (duration: 201.453489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:19.208801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.764347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-05-31T05:52:19.208849Z","caller":"traceutil/trace.go:171","msg":"trace[1870761393] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4635; }","duration":"212.867029ms","start":"2026-05-31T05:52:18.995969Z","end":"2026-05-31T05:52:19.208836Z","steps":["trace[1870761393] 'range keys from in-memory index tree' (duration: 211.983094ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:19.317273Z","caller":"traceutil/trace.go:171","msg":"trace[1754320505] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"105.219661ms","start":"2026-05-31T05:52:19.212014Z","end":"2026-05-31T05:52:19.317234Z","steps":["trace[1754320505] 'process raft request' (duration: 104.43613ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:19.470258Z","caller":"traceutil/trace.go:171","msg":"trace[744483141] linearizableReadLoop","detail":"{readStateIndex:4820; appliedIndex:4817; }","duration":"234.419113ms","start":"2026-05-31T05:52:19.235816Z","end":"2026-05-31T05:52:19.470235Z","steps":["trace[744483141] 'read index received' (duration: 80.642996ms)","trace[744483141] 'applied index is now lower than readState.Index' (duration: 153.775247ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:19.470323Z","caller":"traceutil/trace.go:171","msg":"trace[1318385744] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"252.479573ms","start":"2026-05-31T05:52:19.217806Z","end":"2026-05-31T05:52:19.470286Z","steps":["trace[1318385744] 'process raft request' (duration: 193.565706ms)","trace[1318385744] 'compare' (duration: 58.692571ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:19.470654Z","caller":"traceutil/trace.go:171","msg":"trace[8878089] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"249.195954ms","start":"2026-05-31T05:52:19.221443Z","end":"2026-05-31T05:52:19.470639Z","steps":["trace[8878089] 'process raft request' (duration: 248.741232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:19.470968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.137433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-31T05:52:19.471029Z","caller":"traceutil/trace.go:171","msg":"trace[78912579] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4638; }","duration":"235.235175ms","start":"2026-05-31T05:52:19.235778Z","end":"2026-05-31T05:52:19.471013Z","steps":["trace[78912579] 'agreement among raft nodes before linearized reading' (duration: 235.05532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:19.471303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.448489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91435701-a2b1-4ff5-9bcc-e0baef877279\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-05-31T05:52:19.471339Z","caller":"traceutil/trace.go:171","msg":"trace[871193680] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91435701-a2b1-4ff5-9bcc-e0baef877279; range_end:; response_count:1; response_revision:4638; }","duration":"184.543681ms","start":"2026-05-31T05:52:19.286785Z","end":"2026-05-31T05:52:19.471329Z","steps":["trace[871193680] 'agreement among raft nodes before linearized reading' (duration: 184.441028ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:21.719252Z","caller":"traceutil/trace.go:171","msg":"trace[144247237] linearizableReadLoop","detail":"{readStateIndex:4844; appliedIndex:4843; }","duration":"244.716548ms","start":"2026-05-31T05:52:21.474514Z","end":"2026-05-31T05:52:21.71923Z","steps":["trace[144247237] 'read index received' (duration: 244.531583ms)","trace[144247237] 'applied index is now lower than readState.Index' (duration: 184.075µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:21.719622Z","caller":"traceutil/trace.go:171","msg":"trace[151632763] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"397.101038ms","start":"2026-05-31T05:52:21.322489Z","end":"2026-05-31T05:52:21.71959Z","steps":["trace[151632763] 'process raft request' (duration: 396.604204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:21.719878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.060427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d39f401-4a56-427c-b04f-815b22c190b5\" ","response":"range_response_count:1 size:4080"} {"level":"info","ts":"2026-05-31T05:52:21.719904Z","caller":"traceutil/trace.go:171","msg":"trace[182758588] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d39f401-4a56-427c-b04f-815b22c190b5; range_end:; response_count:1; response_revision:4661; }","duration":"118.105869ms","start":"2026-05-31T05:52:21.601789Z","end":"2026-05-31T05:52:21.719894Z","steps":["trace[182758588] 'agreement among raft nodes before linearized reading' (duration: 118.049337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:21.719981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:21.322475Z","time spent":"397.422407ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:21.719737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.907729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91435701-a2b1-4ff5-9bcc-e0baef877279\" ","response":"range_response_count:1 size:4053"} {"level":"info","ts":"2026-05-31T05:52:21.72015Z","caller":"traceutil/trace.go:171","msg":"trace[1388094449] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91435701-a2b1-4ff5-9bcc-e0baef877279; range_end:; response_count:1; response_revision:4661; }","duration":"236.35077ms","start":"2026-05-31T05:52:21.483792Z","end":"2026-05-31T05:52:21.720143Z","steps":["trace[1388094449] 'agreement among raft nodes before linearized reading' (duration: 235.827906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:21.719817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.295713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-31T05:52:21.720235Z","caller":"traceutil/trace.go:171","msg":"trace[21792498] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4661; }","duration":"245.719205ms","start":"2026-05-31T05:52:21.47451Z","end":"2026-05-31T05:52:21.720229Z","steps":["trace[21792498] 'agreement among raft nodes before linearized reading' (duration: 245.250402ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:21.940043Z","caller":"traceutil/trace.go:171","msg":"trace[1738654343] linearizableReadLoop","detail":"{readStateIndex:4845; appliedIndex:4844; }","duration":"215.958577ms","start":"2026-05-31T05:52:21.72407Z","end":"2026-05-31T05:52:21.940029Z","steps":["trace[1738654343] 'read index received' (duration: 205.441851ms)","trace[1738654343] 'applied index is now lower than readState.Index' (duration: 10.516056ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:21.940156Z","caller":"traceutil/trace.go:171","msg":"trace[691709908] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"217.249102ms","start":"2026-05-31T05:52:21.722877Z","end":"2026-05-31T05:52:21.940127Z","steps":["trace[691709908] 'process raft request' (duration: 206.629323ms)","trace[691709908] 'compare' (duration: 10.28914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:21.940251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.173613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91435701-a2b1-4ff5-9bcc-e0baef877279\" ","response":"range_response_count:1 size:4053"} {"level":"info","ts":"2026-05-31T05:52:21.940275Z","caller":"traceutil/trace.go:171","msg":"trace[583641498] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91435701-a2b1-4ff5-9bcc-e0baef877279; range_end:; response_count:1; response_revision:4662; }","duration":"216.238484ms","start":"2026-05-31T05:52:21.72403Z","end":"2026-05-31T05:52:21.940268Z","steps":["trace[583641498] 'agreement among raft nodes before linearized reading' (duration: 216.151532ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:22.112766Z","caller":"traceutil/trace.go:171","msg":"trace[1359750421] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"357.236608ms","start":"2026-05-31T05:52:21.755515Z","end":"2026-05-31T05:52:22.112752Z","steps":["trace[1359750421] 'process raft request' (duration: 352.50923ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:22.112766Z","caller":"traceutil/trace.go:171","msg":"trace[1151961864] linearizableReadLoop","detail":"{readStateIndex:4847; appliedIndex:4845; }","duration":"172.659964ms","start":"2026-05-31T05:52:21.940084Z","end":"2026-05-31T05:52:22.112744Z","steps":["trace[1151961864] 'read index received' (duration: 167.884545ms)","trace[1151961864] 'applied index is now lower than readState.Index' (duration: 4.774389ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:22.112857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:21.755495Z","time spent":"357.312741ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:22.112962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.574703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-31T05:52:22.112976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.736987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-31T05:52:22.113017Z","caller":"traceutil/trace.go:171","msg":"trace[108429323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4664; }","duration":"235.647716ms","start":"2026-05-31T05:52:21.877354Z","end":"2026-05-31T05:52:22.113002Z","steps":["trace[108429323] 'agreement among raft nodes before linearized reading' (duration: 235.496882ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:22.113023Z","caller":"traceutil/trace.go:171","msg":"trace[410490106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4664; }","duration":"200.815328ms","start":"2026-05-31T05:52:21.912194Z","end":"2026-05-31T05:52:22.113009Z","steps":["trace[410490106] 'agreement among raft nodes before linearized reading' (duration: 200.612933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:22.113125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.943947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:22.113144Z","caller":"traceutil/trace.go:171","msg":"trace[1380984240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4664; }","duration":"237.997779ms","start":"2026-05-31T05:52:21.875141Z","end":"2026-05-31T05:52:22.113139Z","steps":["trace[1380984240] 'agreement among raft nodes before linearized reading' (duration: 237.972838ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:22.113121Z","caller":"traceutil/trace.go:171","msg":"trace[1863648365] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"202.040122ms","start":"2026-05-31T05:52:21.910931Z","end":"2026-05-31T05:52:22.112971Z","steps":["trace[1863648365] 'process raft request' (duration: 201.770665ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:23.791622Z","caller":"traceutil/trace.go:171","msg":"trace[114365123] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"520.660978ms","start":"2026-05-31T05:52:23.270952Z","end":"2026-05-31T05:52:23.791613Z","steps":["trace[114365123] 'process raft request' (duration: 520.02946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.791726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:23.270934Z","time spent":"520.7347ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4079,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:23.791465Z","caller":"traceutil/trace.go:171","msg":"trace[379408873] linearizableReadLoop","detail":"{readStateIndex:4897; appliedIndex:4896; }","duration":"474.185543ms","start":"2026-05-31T05:52:23.317262Z","end":"2026-05-31T05:52:23.791447Z","steps":["trace[379408873] 'read index received' (duration: 473.644967ms)","trace[379408873] 'applied index is now lower than readState.Index' (duration: 539.766µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:23.792917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.623141ms","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-05-31T05:52:23.79299Z","caller":"traceutil/trace.go:171","msg":"trace[391603982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4711; }","duration":"475.751555ms","start":"2026-05-31T05:52:23.317225Z","end":"2026-05-31T05:52:23.792976Z","steps":["trace[391603982] 'agreement among raft nodes before linearized reading' (duration: 474.950583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.792981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.628227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"warn","ts":"2026-05-31T05:52:23.793021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:23.317208Z","time spent":"475.802307ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:52:23.793029Z","caller":"traceutil/trace.go:171","msg":"trace[163546195] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4711; }","duration":"455.71646ms","start":"2026-05-31T05:52:23.337298Z","end":"2026-05-31T05:52:23.793015Z","steps":["trace[163546195] 'agreement among raft nodes before linearized reading' (duration: 454.912078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.79306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:23.337283Z","time spent":"455.771051ms","remote":"127.0.0.1:42918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2206995,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-05-31T05:52:23.937592Z","caller":"traceutil/trace.go:171","msg":"trace[219368225] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"140.818605ms","start":"2026-05-31T05:52:23.796749Z","end":"2026-05-31T05:52:23.937567Z","steps":["trace[219368225] 'process raft request' (duration: 140.732093ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:23.937632Z","caller":"traceutil/trace.go:171","msg":"trace[937760363] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"141.754571ms","start":"2026-05-31T05:52:23.795857Z","end":"2026-05-31T05:52:23.937612Z","steps":["trace[937760363] 'read index received' (duration: 140.688441ms)","trace[937760363] 'applied index is now lower than readState.Index' (duration: 1.064369ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:23.937599Z","caller":"traceutil/trace.go:171","msg":"trace[1142416932] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4711; }","duration":"144.648059ms","start":"2026-05-31T05:52:23.792876Z","end":"2026-05-31T05:52:23.937524Z","steps":["trace[1142416932] 'process raft request' (duration: 143.650371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.937855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.978186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91435701-a2b1-4ff5-9bcc-e0baef877279\" ","response":"range_response_count:1 size:4068"} {"level":"info","ts":"2026-05-31T05:52:23.937897Z","caller":"traceutil/trace.go:171","msg":"trace[372643847] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91435701-a2b1-4ff5-9bcc-e0baef877279; range_end:; response_count:1; response_revision:4712; }","duration":"142.067189ms","start":"2026-05-31T05:52:23.79582Z","end":"2026-05-31T05:52:23.937887Z","steps":["trace[372643847] 'agreement among raft nodes before linearized reading' (duration: 141.906015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.938069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.171993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-31T05:52:23.9381Z","caller":"traceutil/trace.go:171","msg":"trace[120121731] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4712; }","duration":"109.241024ms","start":"2026-05-31T05:52:23.828851Z","end":"2026-05-31T05:52:23.938092Z","steps":["trace[120121731] 'agreement among raft nodes before linearized reading' (duration: 109.145592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:23.938706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.940246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-31T05:52:23.938889Z","caller":"traceutil/trace.go:171","msg":"trace[848956709] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4712; }","duration":"120.161322ms","start":"2026-05-31T05:52:23.818713Z","end":"2026-05-31T05:52:23.938875Z","steps":["trace[848956709] 'agreement among raft nodes before linearized reading' (duration: 119.084934ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:25.509335Z","caller":"traceutil/trace.go:171","msg":"trace[1420521450] linearizableReadLoop","detail":"{readStateIndex:4937; appliedIndex:4936; }","duration":"354.154796ms","start":"2026-05-31T05:52:25.155158Z","end":"2026-05-31T05:52:25.509312Z","steps":["trace[1420521450] 'read index received' (duration: 353.908589ms)","trace[1420521450] 'applied index is now lower than readState.Index' (duration: 244.967µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:25.50941Z","caller":"traceutil/trace.go:171","msg":"trace[201650870] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"456.953291ms","start":"2026-05-31T05:52:25.052422Z","end":"2026-05-31T05:52:25.509375Z","steps":["trace[201650870] 'process raft request' (duration: 456.693584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:25.509589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.07079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-jddjr.18b4909a139def21\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-05-31T05:52:25.509627Z","caller":"traceutil/trace.go:171","msg":"trace[2083381026] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-jddjr.18b4909a139def21; range_end:; response_count:1; response_revision:4749; }","duration":"352.178662ms","start":"2026-05-31T05:52:25.157437Z","end":"2026-05-31T05:52:25.509616Z","steps":["trace[2083381026] 'agreement among raft nodes before linearized reading' (duration: 352.007288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:25.509656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:25.157414Z","time spent":"352.234393ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":984,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-jddjr.18b4909a139def21\" "} {"level":"warn","ts":"2026-05-31T05:52:25.509706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.456084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jddjr\" ","response":"range_response_count:1 size:9871"} {"level":"warn","ts":"2026-05-31T05:52:25.509669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:25.052399Z","time spent":"457.091135ms","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:25.509771Z","caller":"traceutil/trace.go:171","msg":"trace[708150246] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jddjr; range_end:; response_count:1; response_revision:4749; }","duration":"354.644809ms","start":"2026-05-31T05:52:25.155108Z","end":"2026-05-31T05:52:25.509753Z","steps":["trace[708150246] 'agreement among raft nodes before linearized reading' (duration: 354.29631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:25.509821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:25.155089Z","time spent":"354.717041ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9893,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jddjr\" "} {"level":"warn","ts":"2026-05-31T05:52:25.509814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.920353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:52:25.509879Z","caller":"traceutil/trace.go:171","msg":"trace[1904374782] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4749; }","duration":"301.13992ms","start":"2026-05-31T05:52:25.20872Z","end":"2026-05-31T05:52:25.50986Z","steps":["trace[1904374782] 'agreement among raft nodes before linearized reading' (duration: 300.912524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:25.509942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:25.208705Z","time spent":"301.221282ms","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:52:25.638151Z","caller":"traceutil/trace.go:171","msg":"trace[56491654] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"118.507015ms","start":"2026-05-31T05:52:25.519626Z","end":"2026-05-31T05:52:25.638133Z","steps":["trace[56491654] 'process raft request' (duration: 118.409182ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:31.309866Z","caller":"traceutil/trace.go:171","msg":"trace[1992950491] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"119.05088ms","start":"2026-05-31T05:52:31.190796Z","end":"2026-05-31T05:52:31.309847Z","steps":["trace[1992950491] 'process raft request' (duration: 118.934877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:35.834722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410758076317833,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T05:52:36.208344Z","caller":"traceutil/trace.go:171","msg":"trace[1626945259] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5060; }","duration":"874.185653ms","start":"2026-05-31T05:52:35.334133Z","end":"2026-05-31T05:52:36.208318Z","steps":["trace[1626945259] 'read index received' (duration: 873.994518ms)","trace[1626945259] 'applied index is now lower than readState.Index' (duration: 189.995µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:36.208429Z","caller":"traceutil/trace.go:171","msg":"trace[2078609900] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"985.583617ms","start":"2026-05-31T05:52:35.222804Z","end":"2026-05-31T05:52:36.208388Z","steps":["trace[2078609900] 'process raft request' (duration: 985.352981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:36.208527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.375319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-028a7101-9a8f-4f99-95b2-e7b81c18904b\" ","response":"range_response_count:1 size:3766"} {"level":"warn","ts":"2026-05-31T05:52:36.2087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:35.222784Z","time spent":"985.830144ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:36.208694Z","caller":"traceutil/trace.go:171","msg":"trace[1764655707] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-028a7101-9a8f-4f99-95b2-e7b81c18904b; range_end:; response_count:1; response_revision:4871; }","duration":"874.593835ms","start":"2026-05-31T05:52:35.334082Z","end":"2026-05-31T05:52:36.208676Z","steps":["trace[1764655707] 'agreement among raft nodes before linearized reading' (duration: 874.377339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:36.208915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:35.334063Z","time spent":"874.78228ms","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-028a7101-9a8f-4f99-95b2-e7b81c18904b\" "} {"level":"warn","ts":"2026-05-31T05:52:36.214129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.410669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:36.214197Z","caller":"traceutil/trace.go:171","msg":"trace[1506850521] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4872; }","duration":"837.603725ms","start":"2026-05-31T05:52:35.376577Z","end":"2026-05-31T05:52:36.214181Z","steps":["trace[1506850521] 'agreement among raft nodes before linearized reading' (duration: 837.397179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:36.214245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:35.376553Z","time spent":"837.681317ms","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:52:36.21472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.217771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:52:36.214763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.907014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-31T05:52:36.214805Z","caller":"traceutil/trace.go:171","msg":"trace[230929256] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4872; }","duration":"145.358945ms","start":"2026-05-31T05:52:36.069426Z","end":"2026-05-31T05:52:36.214785Z","steps":["trace[230929256] 'agreement among raft nodes before linearized reading' (duration: 145.202081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:36.214836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.869481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:52:36.214864Z","caller":"traceutil/trace.go:171","msg":"trace[1854846484] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4872; }","duration":"127.041138ms","start":"2026-05-31T05:52:36.087795Z","end":"2026-05-31T05:52:36.214836Z","steps":["trace[1854846484] 'agreement among raft nodes before linearized reading' (duration: 126.73275ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:36.214894Z","caller":"traceutil/trace.go:171","msg":"trace[464005167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4872; }","duration":"194.983455ms","start":"2026-05-31T05:52:36.019895Z","end":"2026-05-31T05:52:36.214879Z","steps":["trace[464005167] 'agreement among raft nodes before linearized reading' (duration: 194.742068ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:36.358116Z","caller":"traceutil/trace.go:171","msg":"trace[1198769066] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"138.638238ms","start":"2026-05-31T05:52:36.219451Z","end":"2026-05-31T05:52:36.358089Z","steps":["trace[1198769066] 'process raft request' (duration: 119.986824ms)","trace[1198769066] 'compare' (duration: 18.435178ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:36.358483Z","caller":"traceutil/trace.go:171","msg":"trace[1637172606] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"138.918237ms","start":"2026-05-31T05:52:36.219535Z","end":"2026-05-31T05:52:36.358453Z","steps":["trace[1637172606] 'process raft request' (duration: 138.507825ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:36.359106Z","caller":"traceutil/trace.go:171","msg":"trace[12176850] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"139.371129ms","start":"2026-05-31T05:52:36.219711Z","end":"2026-05-31T05:52:36.359082Z","steps":["trace[12176850] 'process raft request' (duration: 138.65822ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:36.359293Z","caller":"traceutil/trace.go:171","msg":"trace[1384441372] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"138.428304ms","start":"2026-05-31T05:52:36.220854Z","end":"2026-05-31T05:52:36.359282Z","steps":["trace[1384441372] 'process raft request' (duration: 138.104195ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:36.820708Z","caller":"traceutil/trace.go:171","msg":"trace[1272739429] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"163.141137ms","start":"2026-05-31T05:52:36.657545Z","end":"2026-05-31T05:52:36.820686Z","steps":["trace[1272739429] 'process raft request' (duration: 147.840654ms)","trace[1272739429] 'compare' (duration: 15.19178ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:40.36697Z","caller":"traceutil/trace.go:171","msg":"trace[816293578] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"102.534693ms","start":"2026-05-31T05:52:40.264408Z","end":"2026-05-31T05:52:40.366942Z","steps":["trace[816293578] 'process raft request' (duration: 102.333387ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.361901Z","caller":"traceutil/trace.go:171","msg":"trace[1086206614] linearizableReadLoop","detail":"{readStateIndex:5180; appliedIndex:5179; }","duration":"329.650217ms","start":"2026-05-31T05:52:44.03219Z","end":"2026-05-31T05:52:44.36184Z","steps":["trace[1086206614] 'read index received' (duration: 261.610302ms)","trace[1086206614] 'applied index is now lower than readState.Index' (duration: 68.038395ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:44.362246Z","caller":"traceutil/trace.go:171","msg":"trace[1906620928] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"379.471483ms","start":"2026-05-31T05:52:43.982762Z","end":"2026-05-31T05:52:44.362234Z","steps":["trace[1906620928] 'process raft request' (duration: 311.040348ms)","trace[1906620928] 'compare' (duration: 67.686704ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:44.362396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:43.982751Z","time spent":"379.557445ms","remote":"127.0.0.1:34914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4226,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:44.362736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.838289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-05-31T05:52:44.362836Z","caller":"traceutil/trace.go:171","msg":"trace[248284876] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4987; }","duration":"335.991033ms","start":"2026-05-31T05:52:44.026818Z","end":"2026-05-31T05:52:44.362809Z","steps":["trace[248284876] 'agreement among raft nodes before linearized reading' (duration: 335.595662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.3629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:44.026795Z","time spent":"336.092075ms","remote":"127.0.0.1:45676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3265,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-31T05:52:44.384852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.180083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-31T05:52:44.384916Z","caller":"traceutil/trace.go:171","msg":"trace[360479040] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4988; }","duration":"348.265505ms","start":"2026-05-31T05:52:44.036636Z","end":"2026-05-31T05:52:44.384901Z","steps":["trace[360479040] 'agreement among raft nodes before linearized reading' (duration: 348.08702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.384945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:44.036628Z","time spent":"348.310726ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1307,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-05-31T05:52:44.38686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.941599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-05-31T05:52:44.386949Z","caller":"traceutil/trace.go:171","msg":"trace[1262812472] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4988; }","duration":"286.068803ms","start":"2026-05-31T05:52:44.100857Z","end":"2026-05-31T05:52:44.386925Z","steps":["trace[1262812472] 'agreement among raft nodes before linearized reading' (duration: 284.026157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.54982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.727255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-31T05:52:44.549884Z","caller":"traceutil/trace.go:171","msg":"trace[1024897832] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4991; }","duration":"160.833678ms","start":"2026-05-31T05:52:44.389037Z","end":"2026-05-31T05:52:44.549871Z","steps":["trace[1024897832] 'agreement among raft nodes before linearized reading' (duration: 160.658693ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.549872Z","caller":"traceutil/trace.go:171","msg":"trace[570980676] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"172.230655ms","start":"2026-05-31T05:52:44.377625Z","end":"2026-05-31T05:52:44.549855Z","steps":["trace[570980676] 'process raft request' (duration: 172.001939ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.549894Z","caller":"traceutil/trace.go:171","msg":"trace[70826660] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"179.701043ms","start":"2026-05-31T05:52:44.370171Z","end":"2026-05-31T05:52:44.549872Z","steps":["trace[70826660] 'process raft request' (duration: 179.377184ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.549989Z","caller":"traceutil/trace.go:171","msg":"trace[1746550058] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5181; }","duration":"160.614972ms","start":"2026-05-31T05:52:44.389077Z","end":"2026-05-31T05:52:44.549692Z","steps":["trace[1746550058] 'read index received' (duration: 102.916935ms)","trace[1746550058] 'applied index is now lower than readState.Index' (duration: 57.697277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:44.55016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.759738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61525"} {"level":"info","ts":"2026-05-31T05:52:44.550182Z","caller":"traceutil/trace.go:171","msg":"trace[62119881] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4991; }","duration":"159.807608ms","start":"2026-05-31T05:52:44.390369Z","end":"2026-05-31T05:52:44.550177Z","steps":["trace[62119881] 'agreement among raft nodes before linearized reading' (duration: 159.687635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.550271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.699085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-31T05:52:44.550293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.320492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-31T05:52:44.550334Z","caller":"traceutil/trace.go:171","msg":"trace[464511842] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4991; }","duration":"114.435545ms","start":"2026-05-31T05:52:44.435885Z","end":"2026-05-31T05:52:44.550321Z","steps":["trace[464511842] 'agreement among raft nodes before linearized reading' (duration: 114.285021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.550361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.707851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"warn","ts":"2026-05-31T05:52:44.550384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.967796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-31T05:52:44.550389Z","caller":"traceutil/trace.go:171","msg":"trace[1984747999] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4991; }","duration":"138.765813ms","start":"2026-05-31T05:52:44.411617Z","end":"2026-05-31T05:52:44.550383Z","steps":["trace[1984747999] 'agreement among raft nodes before linearized reading' (duration: 138.698981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.550374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.180122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-31T05:52:44.550446Z","caller":"traceutil/trace.go:171","msg":"trace[239794550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4991; }","duration":"108.289845ms","start":"2026-05-31T05:52:44.442141Z","end":"2026-05-31T05:52:44.550431Z","steps":["trace[239794550] 'agreement among raft nodes before linearized reading' (duration: 108.066969ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.550403Z","caller":"traceutil/trace.go:171","msg":"trace[1758744003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4991; }","duration":"159.012126ms","start":"2026-05-31T05:52:44.391386Z","end":"2026-05-31T05:52:44.550398Z","steps":["trace[1758744003] 'agreement among raft nodes before linearized reading' (duration: 158.964805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.550337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.914454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-31T05:52:44.550597Z","caller":"traceutil/trace.go:171","msg":"trace[221394915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4991; }","duration":"154.201473ms","start":"2026-05-31T05:52:44.396387Z","end":"2026-05-31T05:52:44.550589Z","steps":["trace[221394915] 'agreement among raft nodes before linearized reading' (duration: 153.907374ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.550303Z","caller":"traceutil/trace.go:171","msg":"trace[52016520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4991; }","duration":"150.775287ms","start":"2026-05-31T05:52:44.39952Z","end":"2026-05-31T05:52:44.550296Z","steps":["trace[52016520] 'agreement among raft nodes before linearized reading' (duration: 150.658214ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.551256Z","caller":"traceutil/trace.go:171","msg":"trace[1441691592] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"180.903866ms","start":"2026-05-31T05:52:44.368759Z","end":"2026-05-31T05:52:44.549663Z","steps":["trace[1441691592] 'process raft request' (duration: 123.311472ms)","trace[1441691592] 'compare' (duration: 57.199572ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:44.738107Z","caller":"traceutil/trace.go:171","msg":"trace[1212042975] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"181.04603ms","start":"2026-05-31T05:52:44.557037Z","end":"2026-05-31T05:52:44.738083Z","steps":["trace[1212042975] 'process raft request' (duration: 180.981528ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.73811Z","caller":"traceutil/trace.go:171","msg":"trace[537035487] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"181.734569ms","start":"2026-05-31T05:52:44.556333Z","end":"2026-05-31T05:52:44.738068Z","steps":["trace[537035487] 'process raft request' (duration: 132.409306ms)","trace[537035487] 'compare' (duration: 49.094117ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:44.738117Z","caller":"traceutil/trace.go:171","msg":"trace[1997215872] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"181.123402ms","start":"2026-05-31T05:52:44.556973Z","end":"2026-05-31T05:52:44.738097Z","steps":["trace[1997215872] 'process raft request' (duration: 180.992789ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.738664Z","caller":"traceutil/trace.go:171","msg":"trace[643418226] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"180.617408ms","start":"2026-05-31T05:52:44.557999Z","end":"2026-05-31T05:52:44.738616Z","steps":["trace[643418226] 'process raft request' (duration: 180.050132ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.740619Z","caller":"traceutil/trace.go:171","msg":"trace[1265029033] linearizableReadLoop","detail":"{readStateIndex:5189; appliedIndex:5184; }","duration":"178.707985ms","start":"2026-05-31T05:52:44.561899Z","end":"2026-05-31T05:52:44.740607Z","steps":["trace[1265029033] 'read index received' (duration: 52.981725ms)","trace[1265029033] 'applied index is now lower than readState.Index' (duration: 125.72537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:44.740761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.848009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:44.740823Z","caller":"traceutil/trace.go:171","msg":"trace[938052294] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:0; response_revision:4997; }","duration":"178.942191ms","start":"2026-05-31T05:52:44.561871Z","end":"2026-05-31T05:52:44.740813Z","steps":["trace[938052294] 'agreement among raft nodes before linearized reading' (duration: 178.848078ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:44.740784Z","caller":"traceutil/trace.go:171","msg":"trace[970940406] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"176.811702ms","start":"2026-05-31T05:52:44.563889Z","end":"2026-05-31T05:52:44.740701Z","steps":["trace[970940406] 'process raft request' (duration: 174.757634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.740996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.460252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:44.741035Z","caller":"traceutil/trace.go:171","msg":"trace[1575725754] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4997; }","duration":"121.532894ms","start":"2026-05-31T05:52:44.619493Z","end":"2026-05-31T05:52:44.741026Z","steps":["trace[1575725754] 'agreement among raft nodes before linearized reading' (duration: 121.467522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.741156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.467999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-jddjr.18b49099d774749e\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-05-31T05:52:44.741254Z","caller":"traceutil/trace.go:171","msg":"trace[1129688458] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-jddjr.18b49099d774749e; range_end:; response_count:1; response_revision:4997; }","duration":"136.598372ms","start":"2026-05-31T05:52:44.604632Z","end":"2026-05-31T05:52:44.741231Z","steps":["trace[1129688458] 'agreement among raft nodes before linearized reading' (duration: 136.338905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:44.741679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.376154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-31T05:52:44.741729Z","caller":"traceutil/trace.go:171","msg":"trace[1322282275] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4997; }","duration":"155.454657ms","start":"2026-05-31T05:52:44.586261Z","end":"2026-05-31T05:52:44.741715Z","steps":["trace[1322282275] 'agreement among raft nodes before linearized reading' (duration: 154.671005ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:47.550038Z","caller":"traceutil/trace.go:171","msg":"trace[1976435978] linearizableReadLoop","detail":"{readStateIndex:5226; appliedIndex:5225; }","duration":"205.879308ms","start":"2026-05-31T05:52:47.344076Z","end":"2026-05-31T05:52:47.549955Z","steps":["trace[1976435978] 'read index received' (duration: 205.657451ms)","trace[1976435978] 'applied index is now lower than readState.Index' (duration: 220.597µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:47.550076Z","caller":"traceutil/trace.go:171","msg":"trace[80326096] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"217.285836ms","start":"2026-05-31T05:52:47.332766Z","end":"2026-05-31T05:52:47.550052Z","steps":["trace[80326096] 'process raft request' (duration: 217.037379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:47.550311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.404794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-05-31T05:52:47.550306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.175886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:47.550363Z","caller":"traceutil/trace.go:171","msg":"trace[1184803284] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5030; }","duration":"162.504987ms","start":"2026-05-31T05:52:47.387845Z","end":"2026-05-31T05:52:47.55035Z","steps":["trace[1184803284] 'agreement among raft nodes before linearized reading' (duration: 162.337032ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:47.550403Z","caller":"traceutil/trace.go:171","msg":"trace[1335669921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:5030; }","duration":"206.340231ms","start":"2026-05-31T05:52:47.34403Z","end":"2026-05-31T05:52:47.55037Z","steps":["trace[1335669921] 'agreement among raft nodes before linearized reading' (duration: 206.150375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:51.885617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.042863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:52:51.885817Z","caller":"traceutil/trace.go:171","msg":"trace[2143781747] linearizableReadLoop","detail":"{readStateIndex:5263; appliedIndex:5262; }","duration":"422.999042ms","start":"2026-05-31T05:52:51.462796Z","end":"2026-05-31T05:52:51.885795Z","steps":["trace[2143781747] 'read index received' (duration: 287.596649ms)","trace[2143781747] 'applied index is now lower than readState.Index' (duration: 135.400783ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:51.885856Z","caller":"traceutil/trace.go:171","msg":"trace[1508403814] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"425.318797ms","start":"2026-05-31T05:52:51.460511Z","end":"2026-05-31T05:52:51.88583Z","steps":["trace[1508403814] 'process raft request' (duration: 289.975465ms)","trace[1508403814] 'compare' (duration: 134.731105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:51.88594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:51.460501Z","time spent":"425.398638ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:51.886037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.228608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-31T05:52:51.886099Z","caller":"traceutil/trace.go:171","msg":"trace[729850072] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5066; }","duration":"423.298169ms","start":"2026-05-31T05:52:51.462782Z","end":"2026-05-31T05:52:51.88608Z","steps":["trace[729850072] 'agreement among raft nodes before linearized reading' (duration: 423.113314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:51.886144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:51.462778Z","time spent":"423.355231ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-31T05:52:52.178885Z","caller":"traceutil/trace.go:171","msg":"trace[663260033] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"276.655026ms","start":"2026-05-31T05:52:51.902202Z","end":"2026-05-31T05:52:52.178857Z","steps":["trace[663260033] 'process raft request' (duration: 212.834246ms)","trace[663260033] 'compare' (duration: 63.625195ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:52.498743Z","caller":"traceutil/trace.go:171","msg":"trace[1157765617] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"105.793567ms","start":"2026-05-31T05:52:52.392917Z","end":"2026-05-31T05:52:52.49871Z","steps":["trace[1157765617] 'process raft request' (duration: 105.469558ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:52.499057Z","caller":"traceutil/trace.go:171","msg":"trace[402567449] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"105.927551ms","start":"2026-05-31T05:52:52.393109Z","end":"2026-05-31T05:52:52.499036Z","steps":["trace[402567449] 'process raft request' (duration: 105.51505ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:52.4995Z","caller":"traceutil/trace.go:171","msg":"trace[1631097567] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"106.123516ms","start":"2026-05-31T05:52:52.393359Z","end":"2026-05-31T05:52:52.499483Z","steps":["trace[1631097567] 'process raft request' (duration: 105.598332ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:52.499873Z","caller":"traceutil/trace.go:171","msg":"trace[1295230899] linearizableReadLoop","detail":"{readStateIndex:5270; appliedIndex:5266; }","duration":"104.699967ms","start":"2026-05-31T05:52:52.395157Z","end":"2026-05-31T05:52:52.499857Z","steps":["trace[1295230899] 'read index received' (duration: 33.595462ms)","trace[1295230899] 'applied index is now lower than readState.Index' (duration: 71.102995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:52.500046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.866901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-91435701-a2b1-4ff5-9bcc-e0baef877279\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-31T05:52:52.500089Z","caller":"traceutil/trace.go:171","msg":"trace[641626416] range","detail":"{range_begin:/registry/persistentvolumes/pvc-91435701-a2b1-4ff5-9bcc-e0baef877279; range_end:; response_count:1; response_revision:5075; }","duration":"104.936894ms","start":"2026-05-31T05:52:52.395137Z","end":"2026-05-31T05:52:52.500074Z","steps":["trace[641626416] 'agreement among raft nodes before linearized reading' (duration: 104.776929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:11.933128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.733327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:53:11.933212Z","caller":"traceutil/trace.go:171","msg":"trace[258401194] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"254.565781ms","start":"2026-05-31T05:53:11.678632Z","end":"2026-05-31T05:53:11.933198Z","steps":["trace[258401194] 'process raft request' (duration: 132.696249ms)","trace[258401194] 'compare' (duration: 121.516991ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:11.937235Z","caller":"traceutil/trace.go:171","msg":"trace[1116555855] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"258.316108ms","start":"2026-05-31T05:53:11.6789Z","end":"2026-05-31T05:53:11.937216Z","steps":["trace[1116555855] 'process raft request' (duration: 258.143233ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:11.937271Z","caller":"traceutil/trace.go:171","msg":"trace[1909120664] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"258.241385ms","start":"2026-05-31T05:53:11.679013Z","end":"2026-05-31T05:53:11.937255Z","steps":["trace[1909120664] 'process raft request' (duration: 258.141102ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:11.937582Z","caller":"traceutil/trace.go:171","msg":"trace[33141429] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"214.362805ms","start":"2026-05-31T05:53:11.723211Z","end":"2026-05-31T05:53:11.937574Z","steps":["trace[33141429] 'process raft request' (duration: 214.18681ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:11.937583Z","caller":"traceutil/trace.go:171","msg":"trace[1064658776] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"252.534863ms","start":"2026-05-31T05:53:11.685037Z","end":"2026-05-31T05:53:11.937572Z","steps":["trace[1064658776] 'process raft request' (duration: 252.164233ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:24.383172Z","caller":"traceutil/trace.go:171","msg":"trace[626730092] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"107.666206ms","start":"2026-05-31T05:53:24.275474Z","end":"2026-05-31T05:53:24.38314Z","steps":["trace[626730092] 'process raft request' (duration: 107.375288ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:24.684987Z","caller":"traceutil/trace.go:171","msg":"trace[359987680] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"249.793222ms","start":"2026-05-31T05:53:24.435169Z","end":"2026-05-31T05:53:24.684962Z","steps":["trace[359987680] 'process raft request' (duration: 228.919472ms)","trace[359987680] 'compare' (duration: 20.590832ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:36.607327Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2831} {"level":"warn","ts":"2026-05-31T05:53:36.76685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.296298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-31T05:53:36.766943Z","caller":"traceutil/trace.go:171","msg":"trace[851574632] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5433; }","duration":"136.433233ms","start":"2026-05-31T05:53:36.630496Z","end":"2026-05-31T05:53:36.766929Z","steps":["trace[851574632] 'range keys from in-memory index tree' (duration: 136.124864ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:36.94038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2831,"took":"331.553125ms","hash":1357407106,"current-db-size-bytes":53002240,"current-db-size":"53 MB","current-db-size-in-use-bytes":28344320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-31T05:53:36.940443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1357407106,"revision":2831,"compact-revision":-1} {"level":"info","ts":"2026-05-31T05:53:37.349705Z","caller":"traceutil/trace.go:171","msg":"trace[859973631] linearizableReadLoop","detail":"{readStateIndex:5646; appliedIndex:5645; }","duration":"196.421464ms","start":"2026-05-31T05:53:37.153247Z","end":"2026-05-31T05:53:37.349669Z","steps":["trace[859973631] 'read index received' (duration: 155.770526ms)","trace[859973631] 'applied index is now lower than readState.Index' (duration: 40.649988ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:37.349674Z","caller":"traceutil/trace.go:171","msg":"trace[87967482] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"218.374711ms","start":"2026-05-31T05:53:37.131264Z","end":"2026-05-31T05:53:37.349639Z","steps":["trace[87967482] 'process raft request' (duration: 177.743033ms)","trace[87967482] 'compare' (duration: 40.497494ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:37.34997Z","caller":"traceutil/trace.go:171","msg":"trace[846522071] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"104.120629ms","start":"2026-05-31T05:53:37.24583Z","end":"2026-05-31T05:53:37.349951Z","steps":["trace[846522071] 'process raft request' (duration: 103.800629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:37.350319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.055143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-31T05:53:37.350404Z","caller":"traceutil/trace.go:171","msg":"trace[355994611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5437; }","duration":"197.191797ms","start":"2026-05-31T05:53:37.153188Z","end":"2026-05-31T05:53:37.350379Z","steps":["trace[355994611] 'agreement among raft nodes before linearized reading' (duration: 196.564878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:37.350713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.727226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-31T05:53:37.350799Z","caller":"traceutil/trace.go:171","msg":"trace[682371859] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5439; }","duration":"193.839789ms","start":"2026-05-31T05:53:37.156946Z","end":"2026-05-31T05:53:37.350785Z","steps":["trace[682371859] 'agreement among raft nodes before linearized reading' (duration: 193.655994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:37.35121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.175336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-31T05:53:37.352376Z","caller":"traceutil/trace.go:171","msg":"trace[1112273492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5439; }","duration":"196.30315ms","start":"2026-05-31T05:53:37.154958Z","end":"2026-05-31T05:53:37.351261Z","steps":["trace[1112273492] 'agreement among raft nodes before linearized reading' (duration: 195.460966ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:47.340812Z","caller":"traceutil/trace.go:171","msg":"trace[1489690801] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"191.758532ms","start":"2026-05-31T05:53:47.149028Z","end":"2026-05-31T05:53:47.340787Z","steps":["trace[1489690801] 'process raft request' (duration: 191.595297ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:50.849891Z","caller":"traceutil/trace.go:171","msg":"trace[1129540390] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"110.222508ms","start":"2026-05-31T05:53:50.739644Z","end":"2026-05-31T05:53:50.849867Z","steps":["trace[1129540390] 'process raft request' (duration: 110.064044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:51.226968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.915539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-31T05:53:51.227042Z","caller":"traceutil/trace.go:171","msg":"trace[81137404] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5535; }","duration":"178.033663ms","start":"2026-05-31T05:53:51.048992Z","end":"2026-05-31T05:53:51.227025Z","steps":["trace[81137404] 'range keys from in-memory index tree' (duration: 177.726164ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:52.03492Z","caller":"traceutil/trace.go:171","msg":"trace[908634537] linearizableReadLoop","detail":"{readStateIndex:5756; appliedIndex:5755; }","duration":"161.005785ms","start":"2026-05-31T05:53:51.873889Z","end":"2026-05-31T05:53:52.034895Z","steps":["trace[908634537] 'read index received' (duration: 160.790519ms)","trace[908634537] 'applied index is now lower than readState.Index' (duration: 214.096µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:52.035011Z","caller":"traceutil/trace.go:171","msg":"trace[732135814] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"174.199901ms","start":"2026-05-31T05:53:51.860785Z","end":"2026-05-31T05:53:52.034985Z","steps":["trace[732135814] 'process raft request' (duration: 173.960603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:52.035068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.162471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:52.035101Z","caller":"traceutil/trace.go:171","msg":"trace[492317048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5544; }","duration":"161.253503ms","start":"2026-05-31T05:53:51.873838Z","end":"2026-05-31T05:53:52.035091Z","steps":["trace[492317048] 'agreement among raft nodes before linearized reading' (duration: 161.184141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:52.035273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.130238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:52.035312Z","caller":"traceutil/trace.go:171","msg":"trace[900168948] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5544; }","duration":"124.19506ms","start":"2026-05-31T05:53:51.911104Z","end":"2026-05-31T05:53:52.035299Z","steps":["trace[900168948] 'agreement among raft nodes before linearized reading' (duration: 124.116118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:52.035289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.261502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:52.035444Z","caller":"traceutil/trace.go:171","msg":"trace[1085943514] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5544; }","duration":"124.469838ms","start":"2026-05-31T05:53:51.910955Z","end":"2026-05-31T05:53:52.035425Z","steps":["trace[1085943514] 'agreement among raft nodes before linearized reading' (duration: 124.249772ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:59.632451Z","caller":"traceutil/trace.go:171","msg":"trace[1636827827] transaction","detail":"{read_only:false; response_revision:5605; number_of_response:1; }","duration":"208.791316ms","start":"2026-05-31T05:53:59.423626Z","end":"2026-05-31T05:53:59.632417Z","steps":["trace[1636827827] 'process raft request' (duration: 142.781779ms)","trace[1636827827] 'compare' (duration: 65.751719ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:59.632772Z","caller":"traceutil/trace.go:171","msg":"trace[974337648] linearizableReadLoop","detail":"{readStateIndex:5819; appliedIndex:5817; }","duration":"136.080253ms","start":"2026-05-31T05:53:59.496675Z","end":"2026-05-31T05:53:59.632755Z","steps":["trace[974337648] 'read index received' (duration: 69.684445ms)","trace[974337648] 'applied index is now lower than readState.Index' (duration: 66.393588ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:59.633167Z","caller":"traceutil/trace.go:171","msg":"trace[865096615] transaction","detail":"{read_only:false; response_revision:5606; number_of_response:1; }","duration":"209.427916ms","start":"2026-05-31T05:53:59.423724Z","end":"2026-05-31T05:53:59.633152Z","steps":["trace[865096615] 'process raft request' (duration: 208.637903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:59.633623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.932368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-31T05:53:59.634305Z","caller":"traceutil/trace.go:171","msg":"trace[1975095140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5606; }","duration":"137.661769ms","start":"2026-05-31T05:53:59.496625Z","end":"2026-05-31T05:53:59.634287Z","steps":["trace[1975095140] 'agreement among raft nodes before linearized reading' (duration: 136.918477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:59.63384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.697065ms","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-05-31T05:53:59.634546Z","caller":"traceutil/trace.go:171","msg":"trace[1449026483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5606; }","duration":"130.406856ms","start":"2026-05-31T05:53:59.504117Z","end":"2026-05-31T05:53:59.634524Z","steps":["trace[1449026483] 'agreement among raft nodes before linearized reading' (duration: 129.17903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:59.633923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.261794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-31T05:53:59.634732Z","caller":"traceutil/trace.go:171","msg":"trace[1920573170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5606; }","duration":"128.088828ms","start":"2026-05-31T05:53:59.506629Z","end":"2026-05-31T05:53:59.634718Z","steps":["trace[1920573170] 'agreement among raft nodes before linearized reading' (duration: 127.237673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:59.634236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.362407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-31T05:53:59.635107Z","caller":"traceutil/trace.go:171","msg":"trace[904210238] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5606; }","duration":"129.253522ms","start":"2026-05-31T05:53:59.505814Z","end":"2026-05-31T05:53:59.635068Z","steps":["trace[904210238] 'agreement among raft nodes before linearized reading' (duration: 128.1348ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:55:16.454118Z","caller":"traceutil/trace.go:171","msg":"trace[305816379] transaction","detail":"{read_only:false; response_revision:6076; number_of_response:1; }","duration":"122.53718ms","start":"2026-05-31T05:55:16.331559Z","end":"2026-05-31T05:55:16.454097Z","steps":["trace[305816379] 'process raft request' (duration: 49.413583ms)","trace[305816379] 'compare' (duration: 73.022344ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:55:16.454528Z","caller":"traceutil/trace.go:171","msg":"trace[1084434809] transaction","detail":"{read_only:false; response_revision:6077; number_of_response:1; }","duration":"122.772217ms","start":"2026-05-31T05:55:16.331731Z","end":"2026-05-31T05:55:16.454503Z","steps":["trace[1084434809] 'process raft request' (duration: 122.56378ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:55:16.45467Z","caller":"traceutil/trace.go:171","msg":"trace[1506965265] transaction","detail":"{read_only:false; response_revision:6078; number_of_response:1; }","duration":"122.808847ms","start":"2026-05-31T05:55:16.331846Z","end":"2026-05-31T05:55:16.454654Z","steps":["trace[1506965265] 'process raft request' (duration: 122.609041ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:55:16.455145Z","caller":"traceutil/trace.go:171","msg":"trace[559385805] transaction","detail":"{read_only:false; response_revision:6079; number_of_response:1; }","duration":"123.266862ms","start":"2026-05-31T05:55:16.331848Z","end":"2026-05-31T05:55:16.455115Z","steps":["trace[559385805] 'process raft request' (duration: 122.762776ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:58:33.503727Z","caller":"traceutil/trace.go:171","msg":"trace[600686103] transaction","detail":"{read_only:false; response_revision:7356; number_of_response:1; }","duration":"106.016365ms","start":"2026-05-31T05:58:33.397668Z","end":"2026-05-31T05:58:33.503684Z","steps":["trace[600686103] 'process raft request' (duration: 97.413449ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:58:36.612016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5433} {"level":"info","ts":"2026-05-31T05:58:36.686485Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5433,"took":"73.333434ms","hash":1027084474,"current-db-size-bytes":53002240,"current-db-size":"53 MB","current-db-size-in-use-bytes":18554880,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-05-31T05:58:36.686539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1027084474,"revision":5433,"compact-revision":2831} {"level":"info","ts":"2026-05-31T06:00:06.435063Z","caller":"traceutil/trace.go:171","msg":"trace[1638539526] transaction","detail":"{read_only:false; response_revision:7826; number_of_response:1; }","duration":"124.28436ms","start":"2026-05-31T06:00:06.310756Z","end":"2026-05-31T06:00:06.435041Z","steps":["trace[1638539526] 'process raft request' (duration: 124.170407ms)"],"step_count":1}