{"level":"warn","ts":"2026-09-10T11:04:47.316487Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T11:04:47.316618Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.153:2380","--initial-cluster=instance=https://162.253.55.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.153: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-09-10T11:04:47.316704Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-10T11:04:47.316717Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.153:2380"]} {"level":"info","ts":"2026-09-10T11:04:47.316745Z","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-09-10T11:04:47.317544Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"]} {"level":"info","ts":"2026-09-10T11:04:47.317812Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.153:2380"],"listen-peer-urls":["https://162.253.55.153:2380"],"advertise-client-urls":["https://162.253.55.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.153: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-09-10T11:04:47.324282Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.974981ms"} {"level":"info","ts":"2026-09-10T11:04:47.332951Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"522eb07aa35b671b","cluster-id":"49de17121e7ddda"} {"level":"info","ts":"2026-09-10T11:04:47.333066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T11:04:47.33309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became follower at term 0"} {"level":"info","ts":"2026-09-10T11:04:47.333111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 522eb07aa35b671b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T11:04:47.333123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became follower at term 1"} {"level":"info","ts":"2026-09-10T11:04:47.333189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=(5921864600811955995)"} {"level":"warn","ts":"2026-09-10T11:04:47.339413Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T11:04:47.341137Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T11:04:47.342775Z","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-09-10T11:04:47.344817Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"522eb07aa35b671b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T11:04:47.344959Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"522eb07aa35b671b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T11:04:47.345219Z","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-09-10T11:04:47.345354Z","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-09-10T11:04:47.345384Z","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-09-10T11:04:47.346138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b switched to configuration voters=(5921864600811955995)"} {"level":"info","ts":"2026-09-10T11:04:47.346426Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49de17121e7ddda","local-member-id":"522eb07aa35b671b","added-peer-id":"522eb07aa35b671b","added-peer-peer-urls":["https://162.253.55.153:2380"]} {"level":"info","ts":"2026-09-10T11:04:47.348177Z","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-09-10T11:04:47.348204Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.153:2380"} {"level":"info","ts":"2026-09-10T11:04:47.348258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.153:2380"} {"level":"info","ts":"2026-09-10T11:04:47.34842Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"522eb07aa35b671b","initial-advertise-peer-urls":["https://162.253.55.153:2380"],"listen-peer-urls":["https://162.253.55.153:2380"],"advertise-client-urls":["https://162.253.55.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T11:04:47.348453Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T11:04:48.13343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T11:04:48.133493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T11:04:48.133525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b received MsgPreVoteResp from 522eb07aa35b671b at term 1"} {"level":"info","ts":"2026-09-10T11:04:48.133546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became candidate at term 2"} {"level":"info","ts":"2026-09-10T11:04:48.133582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b received MsgVoteResp from 522eb07aa35b671b at term 2"} {"level":"info","ts":"2026-09-10T11:04:48.133607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"522eb07aa35b671b became leader at term 2"} {"level":"info","ts":"2026-09-10T11:04:48.133636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 522eb07aa35b671b elected leader 522eb07aa35b671b at term 2"} {"level":"info","ts":"2026-09-10T11:04:48.134929Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:04:48.135178Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"522eb07aa35b671b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.153:2379]}","request-path":"/0/members/522eb07aa35b671b/attributes","cluster-id":"49de17121e7ddda","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T11:04:48.135186Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T11:04:48.135527Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T11:04:48.135766Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T11:04:48.135797Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T11:04:48.136802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49de17121e7ddda","local-member-id":"522eb07aa35b671b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:04:48.136857Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:04:48.136872Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:04:48.138998Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-10T11:04:48.142263Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.153:2379"} {"level":"info","ts":"2026-09-10T11:05:44.89775Z","caller":"traceutil/trace.go:171","msg":"trace[1224709458] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"125.90529ms","start":"2026-09-10T11:05:44.771827Z","end":"2026-09-10T11:05:44.897732Z","steps":["trace[1224709458] 'process raft request' (duration: 71.003643ms)","trace[1224709458] 'compare' (duration: 54.750242ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:05:50.931352Z","caller":"traceutil/trace.go:171","msg":"trace[1131161105] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"118.914753ms","start":"2026-09-10T11:05:50.81241Z","end":"2026-09-10T11:05:50.931325Z","steps":["trace[1131161105] 'process raft request' (duration: 118.235753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:05:51.519395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.012986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:05:51.519517Z","caller":"traceutil/trace.go:171","msg":"trace[1402050537] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"369.934438ms","start":"2026-09-10T11:05:51.149567Z","end":"2026-09-10T11:05:51.519501Z","steps":["trace[1402050537] 'read index received' (duration: 182.252042ms)","trace[1402050537] 'applied index is now lower than readState.Index' (duration: 187.681286ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:05:51.519561Z","caller":"traceutil/trace.go:171","msg":"trace[1905760106] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"506.980624ms","start":"2026-09-10T11:05:51.012546Z","end":"2026-09-10T11:05:51.519527Z","steps":["trace[1905760106] 'process raft request' (duration: 319.290118ms)","trace[1905760106] 'compare' (duration: 186.914703ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:05:51.519643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.069943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.153\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-09-10T11:05:51.519643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:05:51.012522Z","time spent":"507.084757ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T11:05:51.519688Z","caller":"traceutil/trace.go:171","msg":"trace[2077644408] range","detail":"{range_begin:/registry/masterleases/162.253.55.153; range_end:; response_count:1; response_revision:677; }","duration":"370.159805ms","start":"2026-09-10T11:05:51.14952Z","end":"2026-09-10T11:05:51.519679Z","steps":["trace[2077644408] 'agreement among raft nodes before linearized reading' (duration: 370.056412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:05:51.519838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:05:51.1495Z","time spent":"370.32733ms","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.153\" "} {"level":"warn","ts":"2026-09-10T11:05:51.519853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.915746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-89rj9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-10T11:05:51.519929Z","caller":"traceutil/trace.go:171","msg":"trace[705892632] range","detail":"{range_begin:/registry/pods/kube-system/cilium-89rj9; range_end:; response_count:1; response_revision:677; }","duration":"320.033069ms","start":"2026-09-10T11:05:51.199876Z","end":"2026-09-10T11:05:51.51991Z","steps":["trace[705892632] 'agreement among raft nodes before linearized reading' (duration: 319.817063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:05:51.519966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:05:51.19986Z","time spent":"320.099942ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-89rj9\" "} {"level":"info","ts":"2026-09-10T11:06:03.807967Z","caller":"traceutil/trace.go:171","msg":"trace[1209043565] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"137.204498ms","start":"2026-09-10T11:06:03.670737Z","end":"2026-09-10T11:06:03.807942Z","steps":["trace[1209043565] 'process raft request' (duration: 123.62494ms)","trace[1209043565] 'compare' (duration: 13.434914ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:06:08.553931Z","caller":"traceutil/trace.go:171","msg":"trace[167608754] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"239.67275ms","start":"2026-09-10T11:06:08.314153Z","end":"2026-09-10T11:06:08.553825Z","steps":["trace[167608754] 'process raft request' (duration: 239.435974ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:06:08.710507Z","caller":"traceutil/trace.go:171","msg":"trace[1458551220] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"150.101718ms","start":"2026-09-10T11:06:08.560376Z","end":"2026-09-10T11:06:08.710478Z","steps":["trace[1458551220] 'process raft request' (duration: 144.302957ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:06:08.710495Z","caller":"traceutil/trace.go:171","msg":"trace[2053980303] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"146.659429ms","start":"2026-09-10T11:06:08.563799Z","end":"2026-09-10T11:06:08.710459Z","steps":["trace[2053980303] 'read index received' (duration: 140.763226ms)","trace[2053980303] 'applied index is now lower than readState.Index' (duration: 5.894223ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:06:08.710936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.11456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:06:08.71101Z","caller":"traceutil/trace.go:171","msg":"trace[394938985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"147.259335ms","start":"2026-09-10T11:06:08.563737Z","end":"2026-09-10T11:06:08.710997Z","steps":["trace[394938985] 'agreement among raft nodes before linearized reading' (duration: 146.826573ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:29.466518Z","caller":"traceutil/trace.go:171","msg":"trace[33770912] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"322.248421ms","start":"2026-09-10T11:07:29.144255Z","end":"2026-09-10T11:07:29.466503Z","steps":["trace[33770912] 'process raft request' (duration: 322.054358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:29.466856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:07:29.144231Z","time spent":"322.352754ms","remote":"127.0.0.1:39604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:07:29.466464Z","caller":"traceutil/trace.go:171","msg":"trace[529788319] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"247.973142ms","start":"2026-09-10T11:07:29.218472Z","end":"2026-09-10T11:07:29.466445Z","steps":["trace[529788319] 'read index received' (duration: 247.785119ms)","trace[529788319] 'applied index is now lower than readState.Index' (duration: 187.033µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:07:29.467118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.903612ms","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-09-10T11:07:29.467008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.531503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-09-10T11:07:29.467369Z","caller":"traceutil/trace.go:171","msg":"trace[788666222] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1912; }","duration":"248.939259ms","start":"2026-09-10T11:07:29.218413Z","end":"2026-09-10T11:07:29.467352Z","steps":["trace[788666222] 'agreement among raft nodes before linearized reading' (duration: 248.193237ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:29.467152Z","caller":"traceutil/trace.go:171","msg":"trace[1597749969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1912; }","duration":"244.979323ms","start":"2026-09-10T11:07:29.222163Z","end":"2026-09-10T11:07:29.467142Z","steps":["trace[1597749969] 'agreement among raft nodes before linearized reading' (duration: 244.886262ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:29.815109Z","caller":"traceutil/trace.go:171","msg":"trace[1156283578] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"127.096048ms","start":"2026-09-10T11:07:29.687989Z","end":"2026-09-10T11:07:29.815085Z","steps":["trace[1156283578] 'process raft request' (duration: 126.931156ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:29.826467Z","caller":"traceutil/trace.go:171","msg":"trace[382232288] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"116.584204ms","start":"2026-09-10T11:07:29.709863Z","end":"2026-09-10T11:07:29.826448Z","steps":["trace[382232288] 'process raft request' (duration: 116.500222ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:29.826485Z","caller":"traceutil/trace.go:171","msg":"trace[1979549212] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"135.237774ms","start":"2026-09-10T11:07:29.691206Z","end":"2026-09-10T11:07:29.826444Z","steps":["trace[1979549212] 'process raft request' (duration: 135.04311ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:35.950141Z","caller":"traceutil/trace.go:171","msg":"trace[1669176499] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"111.957279ms","start":"2026-09-10T11:07:35.838163Z","end":"2026-09-10T11:07:35.950121Z","steps":["trace[1669176499] 'process raft request' (duration: 111.836397ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:38.503968Z","caller":"traceutil/trace.go:171","msg":"trace[1432085228] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"199.934076ms","start":"2026-09-10T11:07:38.304009Z","end":"2026-09-10T11:07:38.503943Z","steps":["trace[1432085228] 'process raft request' (duration: 199.206235ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:02.171491Z","caller":"traceutil/trace.go:171","msg":"trace[2023137700] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"119.980724ms","start":"2026-09-10T11:08:02.05148Z","end":"2026-09-10T11:08:02.171461Z","steps":["trace[2023137700] 'process raft request' (duration: 119.775211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:02.605369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.191578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-09-10T11:08:02.605444Z","caller":"traceutil/trace.go:171","msg":"trace[897881653] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2188; }","duration":"278.308749ms","start":"2026-09-10T11:08:02.327114Z","end":"2026-09-10T11:08:02.605422Z","steps":["trace[897881653] 'range keys from in-memory index tree' (duration: 278.012175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:02.605839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.707467ms","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-09-10T11:08:02.605899Z","caller":"traceutil/trace.go:171","msg":"trace[1670094071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2188; }","duration":"338.820189ms","start":"2026-09-10T11:08:02.267066Z","end":"2026-09-10T11:08:02.605886Z","steps":["trace[1670094071] 'range keys from in-memory index tree' (duration: 338.27187ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:02.606037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:08:02.267035Z","time spent":"338.881609ms","remote":"127.0.0.1:39604","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-09-10T11:08:02.606025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.783922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-09-10T11:08:02.606111Z","caller":"traceutil/trace.go:171","msg":"trace[1251664949] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2188; }","duration":"337.910474ms","start":"2026-09-10T11:08:02.268178Z","end":"2026-09-10T11:08:02.606088Z","steps":["trace[1251664949] 'range keys from in-memory index tree' (duration: 337.581909ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:02.606175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:08:02.268164Z","time spent":"337.997376ms","remote":"127.0.0.1:39458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":666,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-10T11:08:02.791248Z","caller":"traceutil/trace.go:171","msg":"trace[699067226] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2264; }","duration":"128.3182ms","start":"2026-09-10T11:08:02.662904Z","end":"2026-09-10T11:08:02.791223Z","steps":["trace[699067226] 'read index received' (duration: 117.371744ms)","trace[699067226] 'applied index is now lower than readState.Index' (duration: 10.945226ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:08:02.791479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.552024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-10T11:08:02.791548Z","caller":"traceutil/trace.go:171","msg":"trace[804516909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"128.635164ms","start":"2026-09-10T11:08:02.662869Z","end":"2026-09-10T11:08:02.791504Z","steps":["trace[804516909] 'agreement among raft nodes before linearized reading' (duration: 128.444382ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:02.791658Z","caller":"traceutil/trace.go:171","msg":"trace[1031848134] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"178.252978ms","start":"2026-09-10T11:08:02.613365Z","end":"2026-09-10T11:08:02.791618Z","steps":["trace[1031848134] 'process raft request' (duration: 166.878616ms)","trace[1031848134] 'compare' (duration: 10.877175ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:08:03.004781Z","caller":"traceutil/trace.go:171","msg":"trace[1097257606] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"187.142444ms","start":"2026-09-10T11:08:02.817616Z","end":"2026-09-10T11:08:03.004758Z","steps":["trace[1097257606] 'process raft request' (duration: 182.95995ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:08:54.102768Z","caller":"traceutil/trace.go:171","msg":"trace[741370540] linearizableReadLoop","detail":"{readStateIndex:2741; appliedIndex:2740; }","duration":"228.757891ms","start":"2026-09-10T11:08:53.873992Z","end":"2026-09-10T11:08:54.10275Z","steps":["trace[741370540] 'read index received' (duration: 228.588719ms)","trace[741370540] 'applied index is now lower than readState.Index' (duration: 168.482µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:08:54.102816Z","caller":"traceutil/trace.go:171","msg":"trace[6715441] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"245.00759ms","start":"2026-09-10T11:08:53.857778Z","end":"2026-09-10T11:08:54.102786Z","steps":["trace[6715441] 'process raft request' (duration: 244.862338ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:54.102958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.954466ms","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-09-10T11:08:54.10298Z","caller":"traceutil/trace.go:171","msg":"trace[646566167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2643; }","duration":"229.029926ms","start":"2026-09-10T11:08:53.873943Z","end":"2026-09-10T11:08:54.102973Z","steps":["trace[646566167] 'agreement among raft nodes before linearized reading' (duration: 228.913984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:08:54.103746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.609924ms","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-09-10T11:08:54.103828Z","caller":"traceutil/trace.go:171","msg":"trace[296267215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2643; }","duration":"184.736505ms","start":"2026-09-10T11:08:53.919071Z","end":"2026-09-10T11:08:54.103807Z","steps":["trace[296267215] 'agreement among raft nodes before linearized reading' (duration: 183.922565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:21.778261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.650868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:09:21.778363Z","caller":"traceutil/trace.go:171","msg":"trace[494600130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2836; }","duration":"215.793786ms","start":"2026-09-10T11:09:21.562548Z","end":"2026-09-10T11:09:21.778341Z","steps":["trace[494600130] 'range keys from in-memory index tree' (duration: 215.515062ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:09:21.932716Z","caller":"traceutil/trace.go:171","msg":"trace[1016980461] linearizableReadLoop","detail":"{readStateIndex:2948; appliedIndex:2947; }","duration":"143.714183ms","start":"2026-09-10T11:09:21.78898Z","end":"2026-09-10T11:09:21.932694Z","steps":["trace[1016980461] 'read index received' (duration: 143.271681ms)","trace[1016980461] 'applied index is now lower than readState.Index' (duration: 441.652µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:09:21.932953Z","caller":"traceutil/trace.go:171","msg":"trace[738754324] transaction","detail":"{read_only:false; response_revision:2837; number_of_response:1; }","duration":"146.413451ms","start":"2026-09-10T11:09:21.78651Z","end":"2026-09-10T11:09:21.932924Z","steps":["trace[738754324] 'process raft request' (duration: 145.995399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:09:21.933143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.153475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:5908"} {"level":"info","ts":"2026-09-10T11:09:21.933203Z","caller":"traceutil/trace.go:171","msg":"trace[862974094] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2837; }","duration":"144.254551ms","start":"2026-09-10T11:09:21.788934Z","end":"2026-09-10T11:09:21.933188Z","steps":["trace[862974094] 'agreement among raft nodes before linearized reading' (duration: 144.072632ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:52.06055Z","caller":"traceutil/trace.go:171","msg":"trace[1819559269] transaction","detail":"{read_only:false; response_revision:4509; number_of_response:1; }","duration":"142.335299ms","start":"2026-09-10T11:11:51.918185Z","end":"2026-09-10T11:11:52.06052Z","steps":["trace[1819559269] 'process raft request' (duration: 131.283312ms)","trace[1819559269] 'compare' (duration: 10.839681ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:11:57.697453Z","caller":"traceutil/trace.go:171","msg":"trace[1777560891] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"176.986252ms","start":"2026-09-10T11:11:57.520433Z","end":"2026-09-10T11:11:57.69742Z","steps":["trace[1777560891] 'process raft request' (duration: 174.302299ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:57.697491Z","caller":"traceutil/trace.go:171","msg":"trace[1839356931] linearizableReadLoop","detail":"{readStateIndex:4822; appliedIndex:4820; }","duration":"176.908501ms","start":"2026-09-10T11:11:57.520493Z","end":"2026-09-10T11:11:57.697401Z","steps":["trace[1839356931] 'read index received' (duration: 27.994486ms)","trace[1839356931] 'applied index is now lower than readState.Index' (duration: 148.912375ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:11:57.697715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.202718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9440"} {"level":"info","ts":"2026-09-10T11:11:57.69778Z","caller":"traceutil/trace.go:171","msg":"trace[1487924783] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4624; }","duration":"177.318211ms","start":"2026-09-10T11:11:57.520445Z","end":"2026-09-10T11:11:57.697763Z","steps":["trace[1487924783] 'agreement among raft nodes before linearized reading' (duration: 177.088175ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:57.903819Z","caller":"traceutil/trace.go:171","msg":"trace[1891882075] linearizableReadLoop","detail":"{readStateIndex:4824; appliedIndex:4822; }","duration":"206.037588ms","start":"2026-09-10T11:11:57.697752Z","end":"2026-09-10T11:11:57.90379Z","steps":["trace[1891882075] 'read index received' (duration: 196.841727ms)","trace[1891882075] 'applied index is now lower than readState.Index' (duration: 9.194271ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:11:57.904041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.328665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T11:11:57.904188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.000627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-09-10T11:11:57.904273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.086955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-09-10T11:11:57.904361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.833083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3178"} {"level":"info","ts":"2026-09-10T11:11:57.904369Z","caller":"traceutil/trace.go:171","msg":"trace[99043047] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4626; }","duration":"347.487019ms","start":"2026-09-10T11:11:57.556646Z","end":"2026-09-10T11:11:57.904133Z","steps":["trace[99043047] 'agreement among raft nodes before linearized reading' (duration: 347.298564ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:57.904391Z","caller":"traceutil/trace.go:171","msg":"trace[1670857178] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4626; }","duration":"383.881514ms","start":"2026-09-10T11:11:57.520494Z","end":"2026-09-10T11:11:57.904376Z","steps":["trace[1670857178] 'agreement among raft nodes before linearized reading' (duration: 383.810952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:57.904422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:11:57.52048Z","time spent":"383.935195ms","remote":"127.0.0.1:48064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3200,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-09-10T11:11:57.90444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:11:57.556637Z","time spent":"347.780517ms","remote":"127.0.0.1:41268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-09-10T11:11:57.904489Z","caller":"traceutil/trace.go:171","msg":"trace[1794280497] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"269.261557ms","start":"2026-09-10T11:11:57.635199Z","end":"2026-09-10T11:11:57.90446Z","steps":["trace[1794280497] 'process raft request' (duration: 268.470695ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:57.90469Z","caller":"traceutil/trace.go:171","msg":"trace[444270401] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4626; }","duration":"259.173562ms","start":"2026-09-10T11:11:57.645086Z","end":"2026-09-10T11:11:57.90426Z","steps":["trace[444270401] 'agreement among raft nodes before linearized reading' (duration: 258.851373ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:57.904876Z","caller":"traceutil/trace.go:171","msg":"trace[748811080] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"352.238089ms","start":"2026-09-10T11:11:57.552039Z","end":"2026-09-10T11:11:57.904277Z","steps":["trace[748811080] 'process raft request' (duration: 342.611085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:57.904963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:11:57.552023Z","time spent":"352.878955ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1436,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:11:57.905016Z","caller":"traceutil/trace.go:171","msg":"trace[99597820] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4626; }","duration":"382.151737ms","start":"2026-09-10T11:11:57.522149Z","end":"2026-09-10T11:11:57.904301Z","steps":["trace[99597820] 'agreement among raft nodes before linearized reading' (duration: 382.006243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:57.905085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:11:57.522142Z","time spent":"382.930468ms","remote":"127.0.0.1:39548","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-09-10T11:11:57.906787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.355466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360980"} {"level":"info","ts":"2026-09-10T11:11:57.906911Z","caller":"traceutil/trace.go:171","msg":"trace[1393530428] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4626; }","duration":"267.51718ms","start":"2026-09-10T11:11:57.639369Z","end":"2026-09-10T11:11:57.906887Z","steps":["trace[1393530428] 'agreement among raft nodes before linearized reading' (duration: 264.625561ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:57.911227Z","caller":"traceutil/trace.go:171","msg":"trace[1261111289] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"193.098364ms","start":"2026-09-10T11:11:57.718106Z","end":"2026-09-10T11:11:57.911204Z","steps":["trace[1261111289] 'process raft request' (duration: 192.93275ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.176589Z","caller":"traceutil/trace.go:171","msg":"trace[1132129654] linearizableReadLoop","detail":"{readStateIndex:4836; appliedIndex:4834; }","duration":"108.011147ms","start":"2026-09-10T11:11:58.068557Z","end":"2026-09-10T11:11:58.176568Z","steps":["trace[1132129654] 'read index received' (duration: 107.75727ms)","trace[1132129654] 'applied index is now lower than readState.Index' (duration: 252.927µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:11:58.176705Z","caller":"traceutil/trace.go:171","msg":"trace[831032547] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"121.527995ms","start":"2026-09-10T11:11:58.055168Z","end":"2026-09-10T11:11:58.176696Z","steps":["trace[831032547] 'process raft request' (duration: 121.199256ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.177032Z","caller":"traceutil/trace.go:171","msg":"trace[1590637169] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"108.870601ms","start":"2026-09-10T11:11:58.068132Z","end":"2026-09-10T11:11:58.177003Z","steps":["trace[1590637169] 'process raft request' (duration: 108.364726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.177122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.385684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-09-10T11:11:58.177196Z","caller":"traceutil/trace.go:171","msg":"trace[1109688208] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4638; }","duration":"102.489457ms","start":"2026-09-10T11:11:58.074689Z","end":"2026-09-10T11:11:58.177178Z","steps":["trace[1109688208] 'agreement among raft nodes before linearized reading' (duration: 102.214709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.177318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.011295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4849"} {"level":"info","ts":"2026-09-10T11:11:58.177364Z","caller":"traceutil/trace.go:171","msg":"trace[373186384] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4638; }","duration":"101.088738ms","start":"2026-09-10T11:11:58.076262Z","end":"2026-09-10T11:11:58.177351Z","steps":["trace[373186384] 'agreement among raft nodes before linearized reading' (duration: 100.915213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.17748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.64913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-dab7f92c-d60f-4373-87e5-8029aec15098\" ","response":"range_response_count:1 size:1435"} {"level":"warn","ts":"2026-09-10T11:11:58.177508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.552479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-09-10T11:11:58.177644Z","caller":"traceutil/trace.go:171","msg":"trace[850621468] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4638; }","duration":"120.692083ms","start":"2026-09-10T11:11:58.056851Z","end":"2026-09-10T11:11:58.177543Z","steps":["trace[850621468] 'agreement among raft nodes before linearized reading' (duration: 120.508628ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.177519Z","caller":"traceutil/trace.go:171","msg":"trace[1504313548] range","detail":"{range_begin:/registry/persistentvolumes/pvc-dab7f92c-d60f-4373-87e5-8029aec15098; range_end:; response_count:1; response_revision:4638; }","duration":"102.705842ms","start":"2026-09-10T11:11:58.0748Z","end":"2026-09-10T11:11:58.177506Z","steps":["trace[1504313548] 'agreement among raft nodes before linearized reading' (duration: 102.61032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.178636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.491308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ef6094cf-59cb-4fc5-97d5-e2adca665634\" ","response":"range_response_count:1 size:2580"} {"level":"info","ts":"2026-09-10T11:11:58.178693Z","caller":"traceutil/trace.go:171","msg":"trace[1677093852] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ef6094cf-59cb-4fc5-97d5-e2adca665634; range_end:; response_count:1; response_revision:4638; }","duration":"120.611761ms","start":"2026-09-10T11:11:58.058068Z","end":"2026-09-10T11:11:58.178679Z","steps":["trace[1677093852] 'agreement among raft nodes before linearized reading' (duration: 118.834132ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.290772Z","caller":"traceutil/trace.go:171","msg":"trace[1190551467] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"102.97082ms","start":"2026-09-10T11:11:58.187777Z","end":"2026-09-10T11:11:58.290748Z","steps":["trace[1190551467] 'process raft request' (duration: 102.850207ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.291166Z","caller":"traceutil/trace.go:171","msg":"trace[955734327] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"103.269517ms","start":"2026-09-10T11:11:58.187836Z","end":"2026-09-10T11:11:58.291106Z","steps":["trace[955734327] 'process raft request' (duration: 102.870427ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.291274Z","caller":"traceutil/trace.go:171","msg":"trace[915376827] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"103.332069ms","start":"2026-09-10T11:11:58.187906Z","end":"2026-09-10T11:11:58.291238Z","steps":["trace[915376827] 'process raft request' (duration: 103.064892ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.291281Z","caller":"traceutil/trace.go:171","msg":"trace[680238646] linearizableReadLoop","detail":"{readStateIndex:4838; appliedIndex:4836; }","duration":"103.777551ms","start":"2026-09-10T11:11:58.18748Z","end":"2026-09-10T11:11:58.291257Z","steps":["trace[680238646] 'read index received' (duration: 29.511695ms)","trace[680238646] 'applied index is now lower than readState.Index' (duration: 74.264756ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:11:58.291404Z","caller":"traceutil/trace.go:171","msg":"trace[1307070484] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"103.271678ms","start":"2026-09-10T11:11:58.187422Z","end":"2026-09-10T11:11:58.290694Z","steps":["trace[1307070484] 'process raft request' (duration: 99.236497ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.291494Z","caller":"traceutil/trace.go:171","msg":"trace[365532356] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"103.497664ms","start":"2026-09-10T11:11:58.187986Z","end":"2026-09-10T11:11:58.291483Z","steps":["trace[365532356] 'process raft request' (duration: 103.144684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.291523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.019718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"warn","ts":"2026-09-10T11:11:58.291701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.0802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-41ebe27c-1d0d-476c-a73c-c3684a9b9392\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-10T11:11:58.291757Z","caller":"traceutil/trace.go:171","msg":"trace[1424626254] range","detail":"{range_begin:/registry/persistentvolumes/pvc-41ebe27c-1d0d-476c-a73c-c3684a9b9392; range_end:; response_count:1; response_revision:4645; }","duration":"104.167391ms","start":"2026-09-10T11:11:58.187575Z","end":"2026-09-10T11:11:58.291742Z","steps":["trace[1424626254] 'agreement among raft nodes before linearized reading' (duration: 103.994357ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.291565Z","caller":"traceutil/trace.go:171","msg":"trace[1489610742] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4645; }","duration":"104.11348ms","start":"2026-09-10T11:11:58.187441Z","end":"2026-09-10T11:11:58.291554Z","steps":["trace[1489610742] 'agreement among raft nodes before linearized reading' (duration: 103.940336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.291906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.132039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9761"} {"level":"info","ts":"2026-09-10T11:11:58.292016Z","caller":"traceutil/trace.go:171","msg":"trace[1071950553] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4645; }","duration":"101.408877ms","start":"2026-09-10T11:11:58.190593Z","end":"2026-09-10T11:11:58.292002Z","steps":["trace[1071950553] 'agreement among raft nodes before linearized reading' (duration: 101.046147ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.484805Z","caller":"traceutil/trace.go:171","msg":"trace[344054633] transaction","detail":"{read_only:false; response_revision:4649; number_of_response:1; }","duration":"182.112219ms","start":"2026-09-10T11:11:58.302669Z","end":"2026-09-10T11:11:58.484781Z","steps":["trace[344054633] 'process raft request' (duration: 181.997986ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.484902Z","caller":"traceutil/trace.go:171","msg":"trace[1354129505] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"182.54659ms","start":"2026-09-10T11:11:58.302323Z","end":"2026-09-10T11:11:58.484869Z","steps":["trace[1354129505] 'process raft request' (duration: 133.869973ms)","trace[1354129505] 'compare' (duration: 47.919077ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:11:58.485138Z","caller":"traceutil/trace.go:171","msg":"trace[1869506885] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"182.748817ms","start":"2026-09-10T11:11:58.302378Z","end":"2026-09-10T11:11:58.485127Z","steps":["trace[1869506885] 'process raft request' (duration: 182.192611ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.485424Z","caller":"traceutil/trace.go:171","msg":"trace[1665731767] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4843; }","duration":"178.779028ms","start":"2026-09-10T11:11:58.30662Z","end":"2026-09-10T11:11:58.485399Z","steps":["trace[1665731767] 'read index received' (duration: 1.307446ms)","trace[1665731767] 'applied index is now lower than readState.Index' (duration: 177.470402ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:11:58.485544Z","caller":"traceutil/trace.go:171","msg":"trace[1661842484] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"179.074276ms","start":"2026-09-10T11:11:58.30646Z","end":"2026-09-10T11:11:58.485534Z","steps":["trace[1661842484] 'process raft request' (duration: 178.775578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.485757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.772528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-09-10T11:11:58.48579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.153398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-10T11:11:58.485802Z","caller":"traceutil/trace.go:171","msg":"trace[1096586856] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4651; }","duration":"130.87208ms","start":"2026-09-10T11:11:58.354919Z","end":"2026-09-10T11:11:58.485791Z","steps":["trace[1096586856] 'agreement among raft nodes before linearized reading' (duration: 130.733127ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.48583Z","caller":"traceutil/trace.go:171","msg":"trace[833281925] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4651; }","duration":"179.2328ms","start":"2026-09-10T11:11:58.306586Z","end":"2026-09-10T11:11:58.485818Z","steps":["trace[833281925] 'agreement among raft nodes before linearized reading' (duration: 179.069476ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:11:58.486558Z","caller":"traceutil/trace.go:171","msg":"trace[439558121] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"183.624111ms","start":"2026-09-10T11:11:58.302915Z","end":"2026-09-10T11:11:58.48654Z","steps":["trace[439558121] 'process raft request' (duration: 181.822671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:11:58.486256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.557114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-09-10T11:11:58.487755Z","caller":"traceutil/trace.go:171","msg":"trace[2093202] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4651; }","duration":"179.129776ms","start":"2026-09-10T11:11:58.308602Z","end":"2026-09-10T11:11:58.487731Z","steps":["trace[2093202] 'agreement among raft nodes before linearized reading' (duration: 176.962257ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:12:01.283195Z","caller":"traceutil/trace.go:171","msg":"trace[592183378] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"196.581778ms","start":"2026-09-10T11:12:01.086589Z","end":"2026-09-10T11:12:01.28317Z","steps":["trace[592183378] 'process raft request' (duration: 196.404693ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:12:01.287965Z","caller":"traceutil/trace.go:171","msg":"trace[460890129] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"201.283905ms","start":"2026-09-10T11:12:01.086658Z","end":"2026-09-10T11:12:01.287942Z","steps":["trace[460890129] 'process raft request' (duration: 200.819662ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:12:01.288118Z","caller":"traceutil/trace.go:171","msg":"trace[1331425116] linearizableReadLoop","detail":"{readStateIndex:4885; appliedIndex:4882; }","duration":"119.066691ms","start":"2026-09-10T11:12:01.169017Z","end":"2026-09-10T11:12:01.288084Z","steps":["trace[1331425116] 'read index received' (duration: 113.973844ms)","trace[1331425116] 'applied index is now lower than readState.Index' (duration: 5.090947ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:12:01.288267Z","caller":"traceutil/trace.go:171","msg":"trace[579274740] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"201.478671ms","start":"2026-09-10T11:12:01.086777Z","end":"2026-09-10T11:12:01.288256Z","steps":["trace[579274740] 'process raft request' (duration: 201.10969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:12:01.28878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.74703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-10T11:12:01.288836Z","caller":"traceutil/trace.go:171","msg":"trace[1731591363] range","detail":"{range_begin:/registry/masterleases/162.253.55.153; range_end:; response_count:1; response_revision:4686; }","duration":"119.836043ms","start":"2026-09-10T11:12:01.168988Z","end":"2026-09-10T11:12:01.288824Z","steps":["trace[1731591363] 'agreement among raft nodes before linearized reading' (duration: 119.678519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:12:09.592825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.040418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:12:09.593074Z","caller":"traceutil/trace.go:171","msg":"trace[1095728957] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"346.247843ms","start":"2026-09-10T11:12:09.246777Z","end":"2026-09-10T11:12:09.593024Z","steps":["trace[1095728957] 'process raft request' (duration: 180.902927ms)","trace[1095728957] 'compare' (duration: 164.903154ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:12:09.593154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:12:09.246749Z","time spent":"346.372186ms","remote":"127.0.0.1:39804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:12:09.593264Z","caller":"traceutil/trace.go:171","msg":"trace[804445729] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"345.510724ms","start":"2026-09-10T11:12:09.247724Z","end":"2026-09-10T11:12:09.593234Z","steps":["trace[804445729] 'process raft request' (duration: 345.215027ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:12:09.59327Z","caller":"traceutil/trace.go:171","msg":"trace[483653253] linearizableReadLoop","detail":"{readStateIndex:5026; appliedIndex:5024; }","duration":"342.084683ms","start":"2026-09-10T11:12:09.251134Z","end":"2026-09-10T11:12:09.593219Z","steps":["trace[483653253] 'read index received' (duration: 176.515091ms)","trace[483653253] 'applied index is now lower than readState.Index' (duration: 165.567922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:12:09.593448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:12:09.2477Z","time spent":"345.671758ms","remote":"127.0.0.1:39424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T11:12:09.59367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.440163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-10T11:12:09.593702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.023935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-10T11:12:09.593718Z","caller":"traceutil/trace.go:171","msg":"trace[6004148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4820; }","duration":"268.525595ms","start":"2026-09-10T11:12:09.325181Z","end":"2026-09-10T11:12:09.593707Z","steps":["trace[6004148] 'agreement among raft nodes before linearized reading' (duration: 268.359091ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:12:09.593755Z","caller":"traceutil/trace.go:171","msg":"trace[1261525800] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4820; }","duration":"324.105397ms","start":"2026-09-10T11:12:09.269633Z","end":"2026-09-10T11:12:09.593738Z","steps":["trace[1261525800] 'agreement among raft nodes before linearized reading' (duration: 323.896212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:12:09.593795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:12:09.269623Z","time spent":"324.164589ms","remote":"127.0.0.1:39518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-09-10T11:12:09.593796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.600248ms","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-09-10T11:12:09.593901Z","caller":"traceutil/trace.go:171","msg":"trace[1466746038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4820; }","duration":"268.692291ms","start":"2026-09-10T11:12:09.325167Z","end":"2026-09-10T11:12:09.59386Z","steps":["trace[1466746038] 'agreement among raft nodes before linearized reading' (duration: 268.502165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:12:09.593997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.863056ms","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-09-10T11:12:09.594029Z","caller":"traceutil/trace.go:171","msg":"trace[1555625682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4820; }","duration":"268.928997ms","start":"2026-09-10T11:12:09.325091Z","end":"2026-09-10T11:12:09.59402Z","steps":["trace[1555625682] 'agreement among raft nodes before linearized reading' (duration: 268.841785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:12:09.59443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.293335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17205"} {"level":"info","ts":"2026-09-10T11:12:09.594483Z","caller":"traceutil/trace.go:171","msg":"trace[516680539] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4820; }","duration":"343.371397ms","start":"2026-09-10T11:12:09.251097Z","end":"2026-09-10T11:12:09.594468Z","steps":["trace[516680539] 'agreement among raft nodes before linearized reading' (duration: 342.205556ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:12:09.594519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:12:09.251073Z","time spent":"343.436859ms","remote":"127.0.0.1:39542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":17227,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-09-10T11:12:47.804529Z","caller":"traceutil/trace.go:171","msg":"trace[1297534502] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"129.869962ms","start":"2026-09-10T11:12:47.674633Z","end":"2026-09-10T11:12:47.804503Z","steps":["trace[1297534502] 'process raft request' (duration: 129.703208ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:14:48.163086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3010} {"level":"info","ts":"2026-09-10T11:14:48.282643Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3010,"took":"118.259123ms","hash":3412360131,"current-db-size-bytes":57835520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30773248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-10T11:14:48.282704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3412360131,"revision":3010,"compact-revision":-1} {"level":"info","ts":"2026-09-10T11:16:08.435912Z","caller":"traceutil/trace.go:171","msg":"trace[1162459033] transaction","detail":"{read_only:false; response_revision:6906; number_of_response:1; }","duration":"107.599887ms","start":"2026-09-10T11:16:08.328284Z","end":"2026-09-10T11:16:08.435884Z","steps":["trace[1162459033] 'process raft request' (duration: 107.296101ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:08.435976Z","caller":"traceutil/trace.go:171","msg":"trace[75922469] transaction","detail":"{read_only:false; response_revision:6905; number_of_response:1; }","duration":"110.250667ms","start":"2026-09-10T11:16:08.32569Z","end":"2026-09-10T11:16:08.435941Z","steps":["trace[75922469] 'process raft request' (duration: 91.296392ms)","trace[75922469] 'compare' (duration: 18.445386ms)"],"step_count":2}