{"level":"warn","ts":"2026-09-08T15:48:17.906188Z","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-08T15:48:17.906303Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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-08T15:48:17.906388Z","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-08T15:48:17.906398Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-08T15:48:17.906419Z","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-08T15:48:17.907438Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-09-08T15:48:17.90753Z","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":16,"max-cpu-available":16,"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.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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-08T15:48:17.912251Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.419206ms"} {"level":"info","ts":"2026-09-08T15:48:17.920106Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-09-08T15:48:17.920205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:48:17.920228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-09-08T15:48:17.920246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:48:17.920259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-09-08T15:48:17.920303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-09-08T15:48:17.92925Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:48:17.931185Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:48:17.932654Z","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-08T15:48:17.935333Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:48:17.935754Z","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-08T15:48:17.935833Z","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-08T15:48:17.935852Z","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-08T15:48:17.935727Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:48:17.938511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-09-08T15:48:17.938696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-08T15:48:17.94074Z","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-08T15:48:17.940917Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-08T15:48:17.940979Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-08T15:48:17.941144Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:48:17.941181Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:48:18.920577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:48:18.92067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:48:18.920692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-09-08T15:48:18.92071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:48:18.920721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-08T15:48:18.920737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-09-08T15:48:18.92075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-08T15:48:18.922378Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:48:18.92239Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:48:18.922425Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:48:18.922425Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:18.922816Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:48:18.922842Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:48:18.923992Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:18.924088Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:18.924124Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:18.924453Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T15:48:18.926174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"warn","ts":"2026-09-08T15:48:36.418405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.995058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:48:36.418609Z","caller":"traceutil/trace.go:171","msg":"trace[1639675369] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"101.482331ms","start":"2026-09-08T15:48:36.317093Z","end":"2026-09-08T15:48:36.418576Z","steps":["trace[1639675369] 'read index received' (duration: 52.361µs)","trace[1639675369] 'applied index is now lower than readState.Index' (duration: 101.42842ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:36.418694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.595794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:48:36.418666Z","caller":"traceutil/trace.go:171","msg":"trace[1882701263] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"386.958707ms","start":"2026-09-08T15:48:36.031688Z","end":"2026-09-08T15:48:36.418647Z","steps":["trace[1882701263] 'process raft request' (duration: 264.009487ms)","trace[1882701263] 'compare' (duration: 121.753703ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:36.418728Z","caller":"traceutil/trace.go:171","msg":"trace[649623670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"101.672556ms","start":"2026-09-08T15:48:36.317045Z","end":"2026-09-08T15:48:36.418717Z","steps":["trace[649623670] 'agreement among raft nodes before linearized reading' (duration: 101.609404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:36.41878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:36.031668Z","time spent":"387.065589ms","remote":"127.0.0.1:55156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:48:53.086415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.207177ms","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-09-08T15:48:53.086493Z","caller":"traceutil/trace.go:171","msg":"trace[281139022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"241.317649ms","start":"2026-09-08T15:48:52.84516Z","end":"2026-09-08T15:48:53.086478Z","steps":["trace[281139022] 'range keys from in-memory index tree' (duration: 241.065334ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:53.380238Z","caller":"traceutil/trace.go:171","msg":"trace[1281541395] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"289.913393ms","start":"2026-09-08T15:48:53.090299Z","end":"2026-09-08T15:48:53.380213Z","steps":["trace[1281541395] 'process raft request' (duration: 289.76884ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:53.500362Z","caller":"traceutil/trace.go:171","msg":"trace[1918673554] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:472; }","duration":"182.190446ms","start":"2026-09-08T15:48:53.318146Z","end":"2026-09-08T15:48:53.500336Z","steps":["trace[1918673554] 'read index received' (duration: 61.837763ms)","trace[1918673554] 'applied index is now lower than readState.Index' (duration: 120.351393ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:53.500509Z","caller":"traceutil/trace.go:171","msg":"trace[1081980365] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"410.094482ms","start":"2026-09-08T15:48:53.090378Z","end":"2026-09-08T15:48:53.500472Z","steps":["trace[1081980365] 'process raft request' (duration: 348.475704ms)","trace[1081980365] 'compare' (duration: 61.350782ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:53.500625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.418361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:48:53.50069Z","caller":"traceutil/trace.go:171","msg":"trace[195018926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"182.555694ms","start":"2026-09-08T15:48:53.318119Z","end":"2026-09-08T15:48:53.500675Z","steps":["trace[195018926] 'agreement among raft nodes before linearized reading' (duration: 182.39555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:53.500702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.879707ms","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-08T15:48:53.50071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:53.090354Z","time spent":"410.266105ms","remote":"127.0.0.1:55234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:48:53.500772Z","caller":"traceutil/trace.go:171","msg":"trace[1638039290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:457; }","duration":"153.944568ms","start":"2026-09-08T15:48:53.346803Z","end":"2026-09-08T15:48:53.500748Z","steps":["trace[1638039290] 'agreement among raft nodes before linearized reading' (duration: 153.824795ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:55.64648Z","caller":"traceutil/trace.go:171","msg":"trace[1627836879] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"105.013907ms","start":"2026-09-08T15:48:55.541437Z","end":"2026-09-08T15:48:55.646451Z","steps":["trace[1627836879] 'process raft request' (duration: 28.193907ms)","trace[1627836879] 'compare' (duration: 76.699687ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:37.354227Z","caller":"traceutil/trace.go:171","msg":"trace[1809340905] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"175.435572ms","start":"2026-09-08T15:49:37.178755Z","end":"2026-09-08T15:49:37.354191Z","steps":["trace[1809340905] 'process raft request' (duration: 175.220737ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:37.401628Z","caller":"traceutil/trace.go:171","msg":"trace[1670356211] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"152.33069ms","start":"2026-09-08T15:49:37.249237Z","end":"2026-09-08T15:49:37.401568Z","steps":["trace[1670356211] 'process raft request' (duration: 151.684445ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:37.819675Z","caller":"traceutil/trace.go:171","msg":"trace[1885311661] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"271.05635ms","start":"2026-09-08T15:49:37.548584Z","end":"2026-09-08T15:49:37.81964Z","steps":["trace[1885311661] 'read index received' (duration: 176.313521ms)","trace[1885311661] 'applied index is now lower than readState.Index' (duration: 94.741469ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:37.81982Z","caller":"traceutil/trace.go:171","msg":"trace[822124986] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"340.245973ms","start":"2026-09-08T15:49:37.479533Z","end":"2026-09-08T15:49:37.819779Z","steps":["trace[822124986] 'process raft request' (duration: 245.399912ms)","trace[822124986] 'compare' (duration: 94.526144ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:37.819922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.335756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qxvw9\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-09-08T15:49:37.819959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:37.479501Z","time spent":"340.386846ms","remote":"127.0.0.1:55208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T15:49:37.819977Z","caller":"traceutil/trace.go:171","msg":"trace[1736668895] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qxvw9; range_end:; response_count:1; response_revision:717; }","duration":"271.446328ms","start":"2026-09-08T15:49:37.548516Z","end":"2026-09-08T15:49:37.819962Z","steps":["trace[1736668895] 'agreement among raft nodes before linearized reading' (duration: 271.293724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:38.086122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.289424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:38.086336Z","caller":"traceutil/trace.go:171","msg":"trace[468143213] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"259.978219ms","start":"2026-09-08T15:49:37.826331Z","end":"2026-09-08T15:49:38.086309Z","steps":["trace[468143213] 'read index received' (duration: 116.398759ms)","trace[468143213] 'applied index is now lower than readState.Index' (duration: 143.57766ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:38.086332Z","caller":"traceutil/trace.go:171","msg":"trace[1013196282] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"260.074391ms","start":"2026-09-08T15:49:37.826193Z","end":"2026-09-08T15:49:38.086267Z","steps":["trace[1013196282] 'process raft request' (duration: 116.560243ms)","trace[1013196282] 'compare' (duration: 143.14862ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:38.086448Z","caller":"traceutil/trace.go:171","msg":"trace[1679493638] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"247.269993ms","start":"2026-09-08T15:49:37.83914Z","end":"2026-09-08T15:49:38.08641Z","steps":["trace[1679493638] 'process raft request' (duration: 247.086399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:38.102264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.679499ms","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-09-08T15:49:38.102331Z","caller":"traceutil/trace.go:171","msg":"trace[517342195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"141.785922ms","start":"2026-09-08T15:49:37.960527Z","end":"2026-09-08T15:49:38.102313Z","steps":["trace[517342195] 'agreement among raft nodes before linearized reading' (duration: 141.613128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:38.102265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.916055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-08T15:49:38.102422Z","caller":"traceutil/trace.go:171","msg":"trace[1208762606] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:719; }","duration":"276.083139ms","start":"2026-09-08T15:49:37.826304Z","end":"2026-09-08T15:49:38.102387Z","steps":["trace[1208762606] 'agreement among raft nodes before linearized reading' (duration: 260.118042ms)","trace[1208762606] 'range keys from in-memory index tree' (duration: 15.755112ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:40.870931Z","caller":"traceutil/trace.go:171","msg":"trace[1679189748] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"142.624549ms","start":"2026-09-08T15:49:40.728273Z","end":"2026-09-08T15:49:40.870898Z","steps":["trace[1679189748] 'process raft request' (duration: 110.983402ms)","trace[1679189748] 'compare' (duration: 31.476463ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:44.362709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.89418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:44.362869Z","caller":"traceutil/trace.go:171","msg":"trace[219589957] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"201.700853ms","start":"2026-09-08T15:49:44.161145Z","end":"2026-09-08T15:49:44.362846Z","steps":["trace[219589957] 'read index received' (duration: 49.513545ms)","trace[219589957] 'applied index is now lower than readState.Index' (duration: 152.184837ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:44.362937Z","caller":"traceutil/trace.go:171","msg":"trace[2066359687] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"202.713056ms","start":"2026-09-08T15:49:44.160182Z","end":"2026-09-08T15:49:44.362895Z","steps":["trace[2066359687] 'process raft request' (duration: 50.472577ms)","trace[2066359687] 'compare' (duration: 151.670555ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:44.363076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.925107ms","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-09-08T15:49:44.363116Z","caller":"traceutil/trace.go:171","msg":"trace[1206141564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"201.99045ms","start":"2026-09-08T15:49:44.161113Z","end":"2026-09-08T15:49:44.363103Z","steps":["trace[1206141564] 'agreement among raft nodes before linearized reading' (duration: 201.821786ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:44.371781Z","caller":"traceutil/trace.go:171","msg":"trace[1213721985] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"195.646421ms","start":"2026-09-08T15:49:44.176108Z","end":"2026-09-08T15:49:44.371755Z","steps":["trace[1213721985] 'process raft request' (duration: 195.351835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.596128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.274816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:44.596743Z","caller":"traceutil/trace.go:171","msg":"trace[1093418762] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"229.518847ms","start":"2026-09-08T15:49:44.36717Z","end":"2026-09-08T15:49:44.596689Z","steps":["trace[1093418762] 'process raft request' (duration: 101.590257ms)","trace[1093418762] 'compare' (duration: 127.086482ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:44.597051Z","caller":"traceutil/trace.go:171","msg":"trace[1391403349] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"140.318509ms","start":"2026-09-08T15:49:44.456572Z","end":"2026-09-08T15:49:44.59689Z","steps":["trace[1391403349] 'process raft request' (duration: 140.005033ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:25.83828Z","caller":"traceutil/trace.go:171","msg":"trace[1922525991] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"201.575841ms","start":"2026-09-08T15:50:25.636678Z","end":"2026-09-08T15:50:25.838254Z","steps":["trace[1922525991] 'process raft request' (duration: 201.400967ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:49.130391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.989176ms","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-08T15:50:49.130502Z","caller":"traceutil/trace.go:171","msg":"trace[1963782415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1550; }","duration":"141.171551ms","start":"2026-09-08T15:50:48.989315Z","end":"2026-09-08T15:50:49.130486Z","steps":["trace[1963782415] 'range keys from in-memory index tree' (duration: 140.811892ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:14.977626Z","caller":"traceutil/trace.go:171","msg":"trace[2019618411] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2042; }","duration":"121.463371ms","start":"2026-09-08T15:51:14.856142Z","end":"2026-09-08T15:51:14.977605Z","steps":["trace[2019618411] 'read index received' (duration: 58.954487ms)","trace[2019618411] 'applied index is now lower than readState.Index' (duration: 62.508013ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:14.97787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.712297ms","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-09-08T15:51:14.977909Z","caller":"traceutil/trace.go:171","msg":"trace[854097241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1966; }","duration":"121.795818ms","start":"2026-09-08T15:51:14.856103Z","end":"2026-09-08T15:51:14.977898Z","steps":["trace[854097241] 'agreement among raft nodes before linearized reading' (duration: 121.632094ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:14.977868Z","caller":"traceutil/trace.go:171","msg":"trace[1727400435] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"122.78154ms","start":"2026-09-08T15:51:14.855048Z","end":"2026-09-08T15:51:14.97783Z","steps":["trace[1727400435] 'process raft request' (duration: 103.661586ms)","trace[1727400435] 'compare' (duration: 18.798877ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:15.371585Z","caller":"traceutil/trace.go:171","msg":"trace[1730745400] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2047; }","duration":"112.943267ms","start":"2026-09-08T15:51:15.258626Z","end":"2026-09-08T15:51:15.37157Z","steps":["trace[1730745400] 'read index received' (duration: 110.695578ms)","trace[1730745400] 'applied index is now lower than readState.Index' (duration: 2.246799ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:15.371711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.06575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:51:15.371734Z","caller":"traceutil/trace.go:171","msg":"trace[811634096] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1970; }","duration":"113.134402ms","start":"2026-09-08T15:51:15.258593Z","end":"2026-09-08T15:51:15.371727Z","steps":["trace[811634096] 'agreement among raft nodes before linearized reading' (duration: 113.07853ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:15.37173Z","caller":"traceutil/trace.go:171","msg":"trace[995719459] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"129.064436ms","start":"2026-09-08T15:51:15.242628Z","end":"2026-09-08T15:51:15.371692Z","steps":["trace[995719459] 'process raft request' (duration: 126.641983ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:28.959231Z","caller":"traceutil/trace.go:171","msg":"trace[407791807] transaction","detail":"{read_only:false; response_revision:2936; number_of_response:1; }","duration":"135.080811ms","start":"2026-09-08T15:53:28.82412Z","end":"2026-09-08T15:53:28.959201Z","steps":["trace[407791807] 'process raft request' (duration: 134.862877ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:35.139183Z","caller":"traceutil/trace.go:171","msg":"trace[376602617] transaction","detail":"{read_only:false; response_revision:2972; number_of_response:1; }","duration":"147.453717ms","start":"2026-09-08T15:53:34.991688Z","end":"2026-09-08T15:53:35.139142Z","steps":["trace[376602617] 'process raft request' (duration: 147.223742ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:14.647639Z","caller":"traceutil/trace.go:171","msg":"trace[1662091319] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"125.44021ms","start":"2026-09-08T15:58:14.52218Z","end":"2026-09-08T15:58:14.64762Z","steps":["trace[1662091319] 'process raft request' (duration: 125.053261ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:17.162478Z","caller":"traceutil/trace.go:171","msg":"trace[776734817] transaction","detail":"{read_only:false; response_revision:5226; number_of_response:1; }","duration":"123.433587ms","start":"2026-09-08T15:58:17.039009Z","end":"2026-09-08T15:58:17.162443Z","steps":["trace[776734817] 'process raft request' (duration: 109.63074ms)","trace[776734817] 'compare' (duration: 13.540431ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:18.950786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2881} {"level":"info","ts":"2026-09-08T15:58:19.072016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2881,"took":"120.259868ms","hash":2278424717,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":29683712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T15:58:19.072071Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2278424717,"revision":2881,"compact-revision":-1} {"level":"info","ts":"2026-09-08T15:58:20.502482Z","caller":"traceutil/trace.go:171","msg":"trace[939728141] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"234.894816ms","start":"2026-09-08T15:58:20.267556Z","end":"2026-09-08T15:58:20.502451Z","steps":["trace[939728141] 'process raft request' (duration: 202.10119ms)","trace[939728141] 'compare' (duration: 32.670603ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:20.532953Z","caller":"traceutil/trace.go:171","msg":"trace[325103573] linearizableReadLoop","detail":"{readStateIndex:5611; appliedIndex:5609; }","duration":"230.943031ms","start":"2026-09-08T15:58:20.301977Z","end":"2026-09-08T15:58:20.53292Z","steps":["trace[325103573] 'read index received' (duration: 167.870533ms)","trace[325103573] 'applied index is now lower than readState.Index' (duration: 63.071118ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:20.533273Z","caller":"traceutil/trace.go:171","msg":"trace[100475965] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"263.975061ms","start":"2026-09-08T15:58:20.269281Z","end":"2026-09-08T15:58:20.533256Z","steps":["trace[100475965] 'process raft request' (duration: 263.493421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:20.533589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.593494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-57e96f47-af09-4e32-826e-f641e4cee9f2\" ","response":"range_response_count:1 size:3717"} {"level":"info","ts":"2026-09-08T15:58:20.533628Z","caller":"traceutil/trace.go:171","msg":"trace[194569673] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-57e96f47-af09-4e32-826e-f641e4cee9f2; range_end:; response_count:1; response_revision:5401; }","duration":"231.676526ms","start":"2026-09-08T15:58:20.301939Z","end":"2026-09-08T15:58:20.533615Z","steps":["trace[194569673] 'agreement among raft nodes before linearized reading' (duration: 231.560684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:20.533818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.873987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:58:20.533873Z","caller":"traceutil/trace.go:171","msg":"trace[1256552855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5401; }","duration":"215.960508ms","start":"2026-09-08T15:58:20.317904Z","end":"2026-09-08T15:58:20.533864Z","steps":["trace[1256552855] 'agreement among raft nodes before linearized reading' (duration: 215.889086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:20.534204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.076129ms","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-09-08T15:58:20.534239Z","caller":"traceutil/trace.go:171","msg":"trace[1927666449] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5401; }","duration":"162.16532ms","start":"2026-09-08T15:58:20.372062Z","end":"2026-09-08T15:58:20.534227Z","steps":["trace[1927666449] 'agreement among raft nodes before linearized reading' (duration: 162.050608ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:22.596564Z","caller":"traceutil/trace.go:171","msg":"trace[1141122300] linearizableReadLoop","detail":"{readStateIndex:5649; appliedIndex:5648; }","duration":"278.788469ms","start":"2026-09-08T15:58:22.317747Z","end":"2026-09-08T15:58:22.596536Z","steps":["trace[1141122300] 'read index received' (duration: 192.743998ms)","trace[1141122300] 'applied index is now lower than readState.Index' (duration: 86.043011ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:22.596628Z","caller":"traceutil/trace.go:171","msg":"trace[1739624481] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"300.477927ms","start":"2026-09-08T15:58:22.29613Z","end":"2026-09-08T15:58:22.596608Z","steps":["trace[1739624481] 'process raft request' (duration: 214.310293ms)","trace[1739624481] 'compare' (duration: 85.945319ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:22.596737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:22.296102Z","time spent":"300.5583ms","remote":"127.0.0.1:55156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11824,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:58:22.596757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.014995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:58:22.596795Z","caller":"traceutil/trace.go:171","msg":"trace[849157021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5437; }","duration":"279.096887ms","start":"2026-09-08T15:58:22.317687Z","end":"2026-09-08T15:58:22.596784Z","steps":["trace[849157021] 'agreement among raft nodes before linearized reading' (duration: 279.014756ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:29.529052Z","caller":"traceutil/trace.go:171","msg":"trace[1984093253] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"121.516745ms","start":"2026-09-08T15:58:29.407503Z","end":"2026-09-08T15:58:29.52902Z","steps":["trace[1984093253] 'process raft request' (duration: 121.207469ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:29.528852Z","caller":"traceutil/trace.go:171","msg":"trace[1454362408] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"130.138811ms","start":"2026-09-08T15:58:29.398662Z","end":"2026-09-08T15:58:29.528801Z","steps":["trace[1454362408] 'process raft request' (duration: 129.892526ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:29.641476Z","caller":"traceutil/trace.go:171","msg":"trace[649824153] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"104.902997ms","start":"2026-09-08T15:58:29.536552Z","end":"2026-09-08T15:58:29.641455Z","steps":["trace[649824153] 'process raft request' (duration: 104.393236ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:29.641583Z","caller":"traceutil/trace.go:171","msg":"trace[2127809822] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"103.669582ms","start":"2026-09-08T15:58:29.537902Z","end":"2026-09-08T15:58:29.641571Z","steps":["trace[2127809822] 'process raft request' (duration: 103.500578ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:30.863505Z","caller":"traceutil/trace.go:171","msg":"trace[1752918508] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"100.993513ms","start":"2026-09-08T15:58:30.762463Z","end":"2026-09-08T15:58:30.863456Z","steps":["trace[1752918508] 'process raft request' (duration: 100.764088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:32.947504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.700963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-08T15:58:32.947575Z","caller":"traceutil/trace.go:171","msg":"trace[936982376] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:5622; }","duration":"215.809395ms","start":"2026-09-08T15:58:32.731751Z","end":"2026-09-08T15:58:32.94756Z","steps":["trace[936982376] 'range keys from in-memory index tree' (duration: 215.529809ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:41.972945Z","caller":"traceutil/trace.go:171","msg":"trace[987373058] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"154.847951ms","start":"2026-09-08T15:58:41.81807Z","end":"2026-09-08T15:58:41.972918Z","steps":["trace[987373058] 'process raft request' (duration: 130.246221ms)","trace[987373058] 'compare' (duration: 24.361445ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:59:35.298622Z","caller":"traceutil/trace.go:171","msg":"trace[79470825] transaction","detail":"{read_only:false; response_revision:6326; number_of_response:1; }","duration":"157.821456ms","start":"2026-09-08T15:59:35.140777Z","end":"2026-09-08T15:59:35.298599Z","steps":["trace[79470825] 'process raft request' (duration: 157.561211ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:03:18.957615Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5353} {"level":"info","ts":"2026-09-08T16:03:19.047254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5353,"took":"87.911661ms","hash":1250999812,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":27922432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T16:03:19.047365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1250999812,"revision":5353,"compact-revision":2881} {"level":"info","ts":"2026-09-08T16:04:54.716635Z","caller":"traceutil/trace.go:171","msg":"trace[968401476] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"185.262286ms","start":"2026-09-08T16:04:54.531352Z","end":"2026-09-08T16:04:54.716614Z","steps":["trace[968401476] 'process raft request' (duration: 185.117953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:06.144252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.105622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pb52m\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-09-08T16:05:06.144304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.942994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T16:05:06.144349Z","caller":"traceutil/trace.go:171","msg":"trace[2100438802] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pb52m; range_end:; response_count:1; response_revision:8739; }","duration":"139.251096ms","start":"2026-09-08T16:05:06.005075Z","end":"2026-09-08T16:05:06.144327Z","steps":["trace[2100438802] 'range keys from in-memory index tree' (duration: 138.881178ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:06.144414Z","caller":"traceutil/trace.go:171","msg":"trace[1562152718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8739; }","duration":"202.089748ms","start":"2026-09-08T16:05:05.942285Z","end":"2026-09-08T16:05:06.144374Z","steps":["trace[1562152718] 'range keys from in-memory index tree' (duration: 201.70712ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:06.874528Z","caller":"traceutil/trace.go:171","msg":"trace[2015780264] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"115.311061ms","start":"2026-09-08T16:05:06.759173Z","end":"2026-09-08T16:05:06.874484Z","steps":["trace[2015780264] 'process raft request' (duration: 115.091836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:07.356013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.862824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-08T16:05:07.35609Z","caller":"traceutil/trace.go:171","msg":"trace[1935688379] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8743; }","duration":"116.006077ms","start":"2026-09-08T16:05:07.240066Z","end":"2026-09-08T16:05:07.356072Z","steps":["trace[1935688379] 'range keys from in-memory index tree' (duration: 115.627228ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:32.897107Z","caller":"traceutil/trace.go:171","msg":"trace[2056721535] linearizableReadLoop","detail":"{readStateIndex:9257; appliedIndex:9256; }","duration":"112.734474ms","start":"2026-09-08T16:05:32.784346Z","end":"2026-09-08T16:05:32.89708Z","steps":["trace[2056721535] 'read index received' (duration: 112.619142ms)","trace[2056721535] 'applied index is now lower than readState.Index' (duration: 113.442µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:32.897239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.877438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/csiaddonsnodes.csiaddons.openshift.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:05:32.897281Z","caller":"traceutil/trace.go:171","msg":"trace[2057494897] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/csiaddonsnodes.csiaddons.openshift.io; range_end:; response_count:0; response_revision:8932; }","duration":"112.968409ms","start":"2026-09-08T16:05:32.784302Z","end":"2026-09-08T16:05:32.89727Z","steps":["trace[2057494897] 'agreement among raft nodes before linearized reading' (duration: 112.872917ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:36.637083Z","caller":"traceutil/trace.go:171","msg":"trace[135468465] linearizableReadLoop","detail":"{readStateIndex:9280; appliedIndex:9279; }","duration":"268.939194ms","start":"2026-09-08T16:05:36.368117Z","end":"2026-09-08T16:05:36.637056Z","steps":["trace[135468465] 'read index received' (duration: 268.693149ms)","trace[135468465] 'applied index is now lower than readState.Index' (duration: 244.705µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:36.637625Z","caller":"traceutil/trace.go:171","msg":"trace[783974630] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"273.044352ms","start":"2026-09-08T16:05:36.364565Z","end":"2026-09-08T16:05:36.637609Z","steps":["trace[783974630] 'process raft request' (duration: 272.320666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:36.637783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.579525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T16:05:36.637841Z","caller":"traceutil/trace.go:171","msg":"trace[1887064730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8955; }","duration":"261.690147ms","start":"2026-09-08T16:05:36.376136Z","end":"2026-09-08T16:05:36.637826Z","steps":["trace[1887064730] 'agreement among raft nodes before linearized reading' (duration: 261.518234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:36.638103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.955667ms","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-09-08T16:05:36.638213Z","caller":"traceutil/trace.go:171","msg":"trace[1116321051] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8955; }","duration":"270.094679ms","start":"2026-09-08T16:05:36.368091Z","end":"2026-09-08T16:05:36.638186Z","steps":["trace[1116321051] 'agreement among raft nodes before linearized reading' (duration: 269.636548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:36.63812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.728667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pb52m\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-09-08T16:05:36.63838Z","caller":"traceutil/trace.go:171","msg":"trace[656262425] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pb52m; range_end:; response_count:1; response_revision:8955; }","duration":"254.011503ms","start":"2026-09-08T16:05:36.384351Z","end":"2026-09-08T16:05:36.638362Z","steps":["trace[656262425] 'agreement among raft nodes before linearized reading' (duration: 253.4752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:44.159173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.337978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:05:44.159301Z","caller":"traceutil/trace.go:171","msg":"trace[1762494339] transaction","detail":"{read_only:false; response_revision:8996; number_of_response:1; }","duration":"162.375642ms","start":"2026-09-08T16:05:43.996898Z","end":"2026-09-08T16:05:44.159273Z","steps":["trace[1762494339] 'process raft request' (duration: 41.85355ms)","trace[1762494339] 'compare' (duration: 120.146354ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:44.438954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.183376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:05:44.439017Z","caller":"traceutil/trace.go:171","msg":"trace[1098349042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8996; }","duration":"121.282188ms","start":"2026-09-08T16:05:44.317715Z","end":"2026-09-08T16:05:44.438997Z","steps":["trace[1098349042] 'range keys from in-memory index tree' (duration: 121.122615ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:44.439267Z","caller":"traceutil/trace.go:171","msg":"trace[1767326443] transaction","detail":"{read_only:false; response_revision:8997; number_of_response:1; }","duration":"107.587714ms","start":"2026-09-08T16:05:44.331652Z","end":"2026-09-08T16:05:44.43924Z","steps":["trace[1767326443] 'process raft request' (duration: 100.283077ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:49.931422Z","caller":"traceutil/trace.go:171","msg":"trace[765028593] transaction","detail":"{read_only:false; response_revision:9054; number_of_response:1; }","duration":"183.868395ms","start":"2026-09-08T16:05:49.747526Z","end":"2026-09-08T16:05:49.931394Z","steps":["trace[765028593] 'process raft request' (duration: 147.791049ms)","trace[765028593] 'compare' (duration: 35.875152ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:49.950345Z","caller":"traceutil/trace.go:171","msg":"trace[1378844821] transaction","detail":"{read_only:false; response_revision:9055; number_of_response:1; }","duration":"202.616088ms","start":"2026-09-08T16:05:49.747693Z","end":"2026-09-08T16:05:49.950309Z","steps":["trace[1378844821] 'process raft request' (duration: 202.311261ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:49.950423Z","caller":"traceutil/trace.go:171","msg":"trace[1432082379] transaction","detail":"{read_only:false; response_revision:9058; number_of_response:1; }","duration":"102.657579ms","start":"2026-09-08T16:05:49.847756Z","end":"2026-09-08T16:05:49.950413Z","steps":["trace[1432082379] 'process raft request' (duration: 102.545026ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:49.950354Z","caller":"traceutil/trace.go:171","msg":"trace[1039175723] linearizableReadLoop","detail":"{readStateIndex:9385; appliedIndex:9383; }","duration":"201.018324ms","start":"2026-09-08T16:05:49.749319Z","end":"2026-09-08T16:05:49.950337Z","steps":["trace[1039175723] 'read index received' (duration: 146.009811ms)","trace[1039175723] 'applied index is now lower than readState.Index' (duration: 55.007513ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:49.950616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.277189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112171"} {"level":"info","ts":"2026-09-08T16:05:49.950354Z","caller":"traceutil/trace.go:171","msg":"trace[16226280] transaction","detail":"{read_only:false; response_revision:9056; number_of_response:1; }","duration":"192.445329ms","start":"2026-09-08T16:05:49.757882Z","end":"2026-09-08T16:05:49.950328Z","steps":["trace[16226280] 'process raft request' (duration: 192.307866ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:49.950655Z","caller":"traceutil/trace.go:171","msg":"trace[570895360] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:9058; }","duration":"201.34359ms","start":"2026-09-08T16:05:49.749301Z","end":"2026-09-08T16:05:49.950644Z","steps":["trace[570895360] 'agreement among raft nodes before linearized reading' (duration: 201.098725ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:49.950422Z","caller":"traceutil/trace.go:171","msg":"trace[1033183066] transaction","detail":"{read_only:false; response_revision:9057; number_of_response:1; }","duration":"102.726479ms","start":"2026-09-08T16:05:49.847681Z","end":"2026-09-08T16:05:49.950408Z","steps":["trace[1033183066] 'process raft request' (duration: 102.575046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:49.950794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.980938ms","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-09-08T16:05:49.950823Z","caller":"traceutil/trace.go:171","msg":"trace[1786702758] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9058; }","duration":"198.028109ms","start":"2026-09-08T16:05:49.752785Z","end":"2026-09-08T16:05:49.950813Z","steps":["trace[1786702758] 'agreement among raft nodes before linearized reading' (duration: 197.934137ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:50.085576Z","caller":"traceutil/trace.go:171","msg":"trace[961234129] linearizableReadLoop","detail":"{readStateIndex:9389; appliedIndex:9388; }","duration":"126.91816ms","start":"2026-09-08T16:05:49.958633Z","end":"2026-09-08T16:05:50.085551Z","steps":["trace[961234129] 'read index received' (duration: 94.355819ms)","trace[961234129] 'applied index is now lower than readState.Index' (duration: 32.560961ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:50.085707Z","caller":"traceutil/trace.go:171","msg":"trace[1168193952] transaction","detail":"{read_only:false; response_revision:9059; number_of_response:1; }","duration":"129.649188ms","start":"2026-09-08T16:05:49.956046Z","end":"2026-09-08T16:05:50.085695Z","steps":["trace[1168193952] 'process raft request' (duration: 97.032376ms)","trace[1168193952] 'compare' (duration: 32.341516ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:50.085996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.351929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:05:50.086019Z","caller":"traceutil/trace.go:171","msg":"trace[1132769908] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:0; response_revision:9059; }","duration":"127.41486ms","start":"2026-09-08T16:05:49.958598Z","end":"2026-09-08T16:05:50.086013Z","steps":["trace[1132769908] 'agreement among raft nodes before linearized reading' (duration: 127.362728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:50.086182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.157718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-09-08T16:05:50.086004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.828348ms","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:4725"} {"level":"info","ts":"2026-09-08T16:05:50.086217Z","caller":"traceutil/trace.go:171","msg":"trace[598256913] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:9059; }","duration":"109.193588ms","start":"2026-09-08T16:05:49.976998Z","end":"2026-09-08T16:05:50.086191Z","steps":["trace[598256913] 'agreement among raft nodes before linearized reading' (duration: 109.142007ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:50.086244Z","caller":"traceutil/trace.go:171","msg":"trace[378092127] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:9059; }","duration":"120.108663ms","start":"2026-09-08T16:05:49.966108Z","end":"2026-09-08T16:05:50.086217Z","steps":["trace[378092127] 'agreement among raft nodes before linearized reading' (duration: 119.653863ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:50.281055Z","caller":"traceutil/trace.go:171","msg":"trace[1457526968] transaction","detail":"{read_only:false; response_revision:9060; number_of_response:1; }","duration":"190.902456ms","start":"2026-09-08T16:05:50.090129Z","end":"2026-09-08T16:05:50.281032Z","steps":["trace[1457526968] 'process raft request' (duration: 190.733723ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:50.291423Z","caller":"traceutil/trace.go:171","msg":"trace[1066338363] linearizableReadLoop","detail":"{readStateIndex:9392; appliedIndex:9389; }","duration":"173.967572ms","start":"2026-09-08T16:05:50.117407Z","end":"2026-09-08T16:05:50.291374Z","steps":["trace[1066338363] 'read index received' (duration: 163.58449ms)","trace[1066338363] 'applied index is now lower than readState.Index' (duration: 10.381522ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:50.291818Z","caller":"traceutil/trace.go:171","msg":"trace[1314853401] transaction","detail":"{read_only:false; response_revision:9061; number_of_response:1; }","duration":"201.510803ms","start":"2026-09-08T16:05:50.090293Z","end":"2026-09-08T16:05:50.291803Z","steps":["trace[1314853401] 'process raft request' (duration: 200.89751ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:50.292143Z","caller":"traceutil/trace.go:171","msg":"trace[2136052430] transaction","detail":"{read_only:false; response_revision:9062; number_of_response:1; }","duration":"201.3095ms","start":"2026-09-08T16:05:50.090819Z","end":"2026-09-08T16:05:50.292128Z","steps":["trace[2136052430] 'process raft request' (duration: 200.503592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:50.292507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.082717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-09-08T16:05:50.292561Z","caller":"traceutil/trace.go:171","msg":"trace[543593461] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:9062; }","duration":"175.185928ms","start":"2026-09-08T16:05:50.11736Z","end":"2026-09-08T16:05:50.292546Z","steps":["trace[543593461] 'agreement among raft nodes before linearized reading' (duration: 174.978173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:50.292741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.588284ms","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-09-08T16:05:50.292803Z","caller":"traceutil/trace.go:171","msg":"trace[2108689417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9062; }","duration":"107.690527ms","start":"2026-09-08T16:05:50.185098Z","end":"2026-09-08T16:05:50.292789Z","steps":["trace[2108689417] 'agreement among raft nodes before linearized reading' (duration: 107.495793ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:06:05.122525Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002542.wal"} {"level":"info","ts":"2026-09-08T16:06:49.749148Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:06:49.765211Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-09-08T16:06:49.76619Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-09-08T16:07:14.42922Z","caller":"traceutil/trace.go:171","msg":"trace[1113427458] transaction","detail":"{read_only:false; response_revision:9856; number_of_response:1; }","duration":"143.466976ms","start":"2026-09-08T16:07:14.285726Z","end":"2026-09-08T16:07:14.429193Z","steps":["trace[1113427458] 'process raft request' (duration: 143.283012ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:07:14.442549Z","caller":"traceutil/trace.go:171","msg":"trace[181771643] linearizableReadLoop","detail":"{readStateIndex:10211; appliedIndex:10209; }","duration":"123.872505ms","start":"2026-09-08T16:07:14.318657Z","end":"2026-09-08T16:07:14.44253Z","steps":["trace[181771643] 'read index received' (duration: 110.447316ms)","trace[181771643] 'applied index is now lower than readState.Index' (duration: 13.423939ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:07:14.442601Z","caller":"traceutil/trace.go:171","msg":"trace[393223618] transaction","detail":"{read_only:false; response_revision:9857; number_of_response:1; }","duration":"155.597817ms","start":"2026-09-08T16:07:14.286986Z","end":"2026-09-08T16:07:14.442583Z","steps":["trace[393223618] 'process raft request' (duration: 155.463024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:07:14.442746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.07172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:07:14.442838Z","caller":"traceutil/trace.go:171","msg":"trace[2006545311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9857; }","duration":"124.220383ms","start":"2026-09-08T16:07:14.318597Z","end":"2026-09-08T16:07:14.442817Z","steps":["trace[2006545311] 'agreement among raft nodes before linearized reading' (duration: 124.041329ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:08:18.964185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7891} {"level":"info","ts":"2026-09-08T16:08:19.059917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7891,"took":"93.45277ms","hash":1413132385,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":25698304,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-08T16:08:19.060016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1413132385,"revision":7891,"compact-revision":5353} {"level":"info","ts":"2026-09-08T16:10:35.9433Z","caller":"traceutil/trace.go:171","msg":"trace[2105817395] transaction","detail":"{read_only:false; response_revision:11584; number_of_response:1; }","duration":"118.830728ms","start":"2026-09-08T16:10:35.824447Z","end":"2026-09-08T16:10:35.943278Z","steps":["trace[2105817395] 'process raft request' (duration: 118.45223ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:10:55.933254Z","caller":"traceutil/trace.go:171","msg":"trace[638962679] transaction","detail":"{read_only:false; response_revision:11695; number_of_response:1; }","duration":"107.918042ms","start":"2026-09-08T16:10:55.825304Z","end":"2026-09-08T16:10:55.933223Z","steps":["trace[638962679] 'process raft request' (duration: 107.726298ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:56.131474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.094732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:10:56.131581Z","caller":"traceutil/trace.go:171","msg":"trace[1700819499] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11695; }","duration":"112.351319ms","start":"2026-09-08T16:10:56.019208Z","end":"2026-09-08T16:10:56.131559Z","steps":["trace[1700819499] 'range keys from in-memory index tree' (duration: 111.626901ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:56.131717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.094855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:10:56.131784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.553564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:10:56.131881Z","caller":"traceutil/trace.go:171","msg":"trace[10800201] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11695; }","duration":"113.307949ms","start":"2026-09-08T16:10:56.018533Z","end":"2026-09-08T16:10:56.131841Z","steps":["trace[10800201] 'range keys from in-memory index tree' (duration: 113.024823ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:10:56.131889Z","caller":"traceutil/trace.go:171","msg":"trace[1607362820] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11695; }","duration":"113.699887ms","start":"2026-09-08T16:10:56.018161Z","end":"2026-09-08T16:10:56.131861Z","steps":["trace[1607362820] 'range keys from in-memory index tree' (duration: 113.473963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:56.13192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.515265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-09-08T16:10:56.131962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.832069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:10:56.132006Z","caller":"traceutil/trace.go:171","msg":"trace[307245415] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11695; }","duration":"108.615297ms","start":"2026-09-08T16:10:56.023348Z","end":"2026-09-08T16:10:56.131963Z","steps":["trace[307245415] 'range keys from in-memory index tree' (duration: 108.334522ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:10:56.132017Z","caller":"traceutil/trace.go:171","msg":"trace[1829603880] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11695; }","duration":"112.89687ms","start":"2026-09-08T16:10:56.019105Z","end":"2026-09-08T16:10:56.132002Z","steps":["trace[1829603880] 'range keys from in-memory index tree' (duration: 112.745807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:56.132151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.980402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-08T16:10:56.13217Z","caller":"traceutil/trace.go:171","msg":"trace[283534316] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11695; }","duration":"113.020973ms","start":"2026-09-08T16:10:56.019143Z","end":"2026-09-08T16:10:56.132164Z","steps":["trace[283534316] 'range keys from in-memory index tree' (duration: 112.837929ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:13:18.971998Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10313} {"level":"info","ts":"2026-09-08T16:13:19.061527Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10313,"took":"87.15023ms","hash":2911806902,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":27922432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T16:13:19.061597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2911806902,"revision":10313,"compact-revision":7891} {"level":"info","ts":"2026-09-08T16:17:08.426441Z","caller":"traceutil/trace.go:171","msg":"trace[1727387295] linearizableReadLoop","detail":"{readStateIndex:14700; appliedIndex:14699; }","duration":"108.046226ms","start":"2026-09-08T16:17:08.318367Z","end":"2026-09-08T16:17:08.426413Z","steps":["trace[1727387295] 'read index received' (duration: 107.75386ms)","trace[1727387295] 'applied index is now lower than readState.Index' (duration: 290.956µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:08.426534Z","caller":"traceutil/trace.go:171","msg":"trace[1406581869] transaction","detail":"{read_only:false; response_revision:14205; number_of_response:1; }","duration":"156.036429ms","start":"2026-09-08T16:17:08.270462Z","end":"2026-09-08T16:17:08.426499Z","steps":["trace[1406581869] 'process raft request' (duration: 155.754313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:08.426629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.25385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:17:08.42669Z","caller":"traceutil/trace.go:171","msg":"trace[1271753090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14205; }","duration":"108.325501ms","start":"2026-09-08T16:17:08.318347Z","end":"2026-09-08T16:17:08.426673Z","steps":["trace[1271753090] 'agreement among raft nodes before linearized reading' (duration: 108.221849ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:08.566112Z","caller":"traceutil/trace.go:171","msg":"trace[859955752] transaction","detail":"{read_only:false; response_revision:14206; number_of_response:1; }","duration":"134.993945ms","start":"2026-09-08T16:17:08.431096Z","end":"2026-09-08T16:17:08.56609Z","steps":["trace[859955752] 'process raft request' (duration: 134.861332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:19.52433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.690137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-08T16:17:19.524478Z","caller":"traceutil/trace.go:171","msg":"trace[2023209428] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14260; }","duration":"113.900292ms","start":"2026-09-08T16:17:19.410552Z","end":"2026-09-08T16:17:19.524452Z","steps":["trace[2023209428] 'range keys from in-memory index tree' (duration: 113.521474ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:26.107362Z","caller":"traceutil/trace.go:171","msg":"trace[165281971] linearizableReadLoop","detail":"{readStateIndex:14809; appliedIndex:14808; }","duration":"218.953293ms","start":"2026-09-08T16:17:25.888356Z","end":"2026-09-08T16:17:26.107309Z","steps":["trace[165281971] 'read index received' (duration: 218.674806ms)","trace[165281971] 'applied index is now lower than readState.Index' (duration: 276.887µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:26.107493Z","caller":"traceutil/trace.go:171","msg":"trace[951804166] transaction","detail":"{read_only:false; response_revision:14309; number_of_response:1; }","duration":"260.451496ms","start":"2026-09-08T16:17:25.846996Z","end":"2026-09-08T16:17:26.107447Z","steps":["trace[951804166] 'process raft request' (duration: 260.107747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:26.107638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.316933ms","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-08T16:17:26.107692Z","caller":"traceutil/trace.go:171","msg":"trace[902788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14309; }","duration":"135.439686ms","start":"2026-09-08T16:17:25.972241Z","end":"2026-09-08T16:17:26.107681Z","steps":["trace[902788] 'agreement among raft nodes before linearized reading' (duration: 135.258752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:26.10771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.349221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:17:26.107827Z","caller":"traceutil/trace.go:171","msg":"trace[79151386] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:14309; }","duration":"219.492524ms","start":"2026-09-08T16:17:25.888307Z","end":"2026-09-08T16:17:26.1078Z","steps":["trace[79151386] 'agreement among raft nodes before linearized reading' (duration: 219.175517ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:50.152528Z","caller":"traceutil/trace.go:171","msg":"trace[1449193983] linearizableReadLoop","detail":"{readStateIndex:15138; appliedIndex:15137; }","duration":"141.775201ms","start":"2026-09-08T16:17:50.010726Z","end":"2026-09-08T16:17:50.152501Z","steps":["trace[1449193983] 'read index received' (duration: 141.481495ms)","trace[1449193983] 'applied index is now lower than readState.Index' (duration: 291.936µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:50.152916Z","caller":"traceutil/trace.go:171","msg":"trace[1288941020] transaction","detail":"{read_only:false; response_revision:14626; number_of_response:1; }","duration":"143.523879ms","start":"2026-09-08T16:17:50.009372Z","end":"2026-09-08T16:17:50.152895Z","steps":["trace[1288941020] 'process raft request' (duration: 142.903945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:50.15364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.903236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:630408"} {"level":"info","ts":"2026-09-08T16:17:50.153727Z","caller":"traceutil/trace.go:171","msg":"trace[464752537] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:14626; }","duration":"143.048039ms","start":"2026-09-08T16:17:50.01066Z","end":"2026-09-08T16:17:50.153708Z","steps":["trace[464752537] 'agreement among raft nodes before linearized reading' (duration: 142.17447ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:18:18.980096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12671} {"level":"info","ts":"2026-09-08T16:18:19.065823Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12671,"took":"82.736741ms","hash":2422517243,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":29036544,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T16:18:19.065893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2422517243,"revision":12671,"compact-revision":10313} {"level":"info","ts":"2026-09-08T16:19:06.060322Z","caller":"traceutil/trace.go:171","msg":"trace[1901916945] transaction","detail":"{read_only:false; response_revision:15388; number_of_response:1; }","duration":"109.565549ms","start":"2026-09-08T16:19:05.950717Z","end":"2026-09-08T16:19:06.060282Z","steps":["trace[1901916945] 'process raft request' (duration: 101.220009ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:22:12.118692Z","caller":"traceutil/trace.go:171","msg":"trace[705841301] transaction","detail":"{read_only:false; response_revision:16867; number_of_response:1; }","duration":"100.271089ms","start":"2026-09-08T16:22:12.018373Z","end":"2026-09-08T16:22:12.118645Z","steps":["trace[705841301] 'process raft request' (duration: 99.929362ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:23:18.987454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14823} {"level":"info","ts":"2026-09-08T16:23:19.09061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14823,"took":"100.990736ms","hash":1877616269,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":30855168,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-08T16:23:19.090737Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1877616269,"revision":14823,"compact-revision":12671} {"level":"info","ts":"2026-09-08T16:25:05.395526Z","caller":"traceutil/trace.go:171","msg":"trace[1790363134] transaction","detail":"{read_only:false; response_revision:18023; number_of_response:1; }","duration":"126.759791ms","start":"2026-09-08T16:25:05.268741Z","end":"2026-09-08T16:25:05.395501Z","steps":["trace[1790363134] 'process raft request' (duration: 126.458214ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:12.30221Z","caller":"traceutil/trace.go:171","msg":"trace[423371542] transaction","detail":"{read_only:false; response_revision:18060; number_of_response:1; }","duration":"234.761309ms","start":"2026-09-08T16:25:12.067418Z","end":"2026-09-08T16:25:12.302179Z","steps":["trace[423371542] 'process raft request' (duration: 234.557354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:12.692645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.073723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:25:12.692743Z","caller":"traceutil/trace.go:171","msg":"trace[1771532649] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:18060; }","duration":"294.188975ms","start":"2026-09-08T16:25:12.398529Z","end":"2026-09-08T16:25:12.692718Z","steps":["trace[1771532649] 'count revisions from in-memory index tree' (duration: 293.959131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:12.693553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.63162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-08T16:25:12.69366Z","caller":"traceutil/trace.go:171","msg":"trace[224313389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18060; }","duration":"113.802064ms","start":"2026-09-08T16:25:12.579834Z","end":"2026-09-08T16:25:12.693636Z","steps":["trace[224313389] 'range keys from in-memory index tree' (duration: 113.490807ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:13.142519Z","caller":"traceutil/trace.go:171","msg":"trace[1552295488] linearizableReadLoop","detail":"{readStateIndex:18685; appliedIndex:18684; }","duration":"421.074949ms","start":"2026-09-08T16:25:12.721421Z","end":"2026-09-08T16:25:13.142496Z","steps":["trace[1552295488] 'read index received' (duration: 420.931336ms)","trace[1552295488] 'applied index is now lower than readState.Index' (duration: 142.593µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:25:13.142726Z","caller":"traceutil/trace.go:171","msg":"trace[361493236] transaction","detail":"{read_only:false; response_revision:18062; number_of_response:1; }","duration":"444.817918ms","start":"2026-09-08T16:25:12.697876Z","end":"2026-09-08T16:25:13.142694Z","steps":["trace[361493236] 'process raft request' (duration: 444.506811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:13.142808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:25:12.697844Z","time spent":"444.90299ms","remote":"127.0.0.1:55234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:25:13.142983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.535318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:25:13.14309Z","caller":"traceutil/trace.go:171","msg":"trace[611816461] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:18062; }","duration":"421.676282ms","start":"2026-09-08T16:25:12.721384Z","end":"2026-09-08T16:25:13.14306Z","steps":["trace[611816461] 'agreement among raft nodes before linearized reading' (duration: 421.444357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:13.143145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:25:12.721344Z","time spent":"421.786955ms","remote":"127.0.0.1:55282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-09-08T16:25:13.143532Z","caller":"traceutil/trace.go:171","msg":"trace[1810446592] transaction","detail":"{read_only:false; response_revision:18063; number_of_response:1; }","duration":"276.774452ms","start":"2026-09-08T16:25:12.866737Z","end":"2026-09-08T16:25:13.143512Z","steps":["trace[1810446592] 'process raft request' (duration: 276.469196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:13.143637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.490468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-08T16:25:13.143682Z","caller":"traceutil/trace.go:171","msg":"trace[1638114124] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18064; }","duration":"162.555399ms","start":"2026-09-08T16:25:12.981105Z","end":"2026-09-08T16:25:13.14366Z","steps":["trace[1638114124] 'agreement among raft nodes before linearized reading' (duration: 162.459437ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:13.143739Z","caller":"traceutil/trace.go:171","msg":"trace[2115630854] transaction","detail":"{read_only:false; response_revision:18064; number_of_response:1; }","duration":"247.82475ms","start":"2026-09-08T16:25:12.89587Z","end":"2026-09-08T16:25:13.143694Z","steps":["trace[2115630854] 'process raft request' (duration: 247.425012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:13.143802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.82398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-08T16:25:13.14387Z","caller":"traceutil/trace.go:171","msg":"trace[1146011303] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:18064; }","duration":"315.939772ms","start":"2026-09-08T16:25:12.827913Z","end":"2026-09-08T16:25:13.143853Z","steps":["trace[1146011303] 'agreement among raft nodes before linearized reading' (duration: 315.707047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:13.14391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:25:12.827894Z","time spent":"316.007464ms","remote":"127.0.0.1:54980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.93\" "} {"level":"warn","ts":"2026-09-08T16:25:13.14391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.244232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-08T16:25:13.143948Z","caller":"traceutil/trace.go:171","msg":"trace[667364627] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18064; }","duration":"373.315143ms","start":"2026-09-08T16:25:12.770622Z","end":"2026-09-08T16:25:13.143937Z","steps":["trace[667364627] 'agreement among raft nodes before linearized reading' (duration: 372.885884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:13.14397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:25:12.770605Z","time spent":"373.358585ms","remote":"127.0.0.1:55234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-08T16:25:21.484473Z","caller":"traceutil/trace.go:171","msg":"trace[1010638611] transaction","detail":"{read_only:false; response_revision:18187; number_of_response:1; }","duration":"115.804256ms","start":"2026-09-08T16:25:21.368651Z","end":"2026-09-08T16:25:21.484455Z","steps":["trace[1010638611] 'process raft request' (duration: 24.413723ms)","trace[1010638611] 'compare' (duration: 91.121707ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:25:21.48446Z","caller":"traceutil/trace.go:171","msg":"trace[527041235] linearizableReadLoop","detail":"{readStateIndex:18812; appliedIndex:18811; }","duration":"103.84848ms","start":"2026-09-08T16:25:21.380551Z","end":"2026-09-08T16:25:21.484399Z","steps":["trace[527041235] 'read index received' (duration: 12.523569ms)","trace[527041235] 'applied index is now lower than readState.Index' (duration: 91.32329ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:25:21.484772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.197327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-lcg86-vnc\" ","response":"range_response_count:1 size:911"} {"level":"info","ts":"2026-09-08T16:25:21.484856Z","caller":"traceutil/trace.go:171","msg":"trace[139511596] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-lcg86-vnc; range_end:; response_count:1; response_revision:18187; }","duration":"104.315429ms","start":"2026-09-08T16:25:21.380522Z","end":"2026-09-08T16:25:21.484838Z","steps":["trace[139511596] 'agreement among raft nodes before linearized reading' (duration: 104.049683ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:27:38.008138Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:27:38.013653Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-09-08T16:27:38.013751Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-09-08T16:28:18.995961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17304} {"level":"info","ts":"2026-09-08T16:28:19.095279Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17304,"took":"96.693986ms","hash":34530499,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":34254848,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-08T16:28:19.095339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":34530499,"revision":17304,"compact-revision":14823} {"level":"info","ts":"2026-09-08T16:30:40.234033Z","caller":"traceutil/trace.go:171","msg":"trace[988841878] transaction","detail":"{read_only:false; response_revision:20648; number_of_response:1; }","duration":"150.198845ms","start":"2026-09-08T16:30:40.083811Z","end":"2026-09-08T16:30:40.234009Z","steps":["trace[988841878] 'process raft request' (duration: 150.046392ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:01.225276Z","caller":"traceutil/trace.go:171","msg":"trace[1324927509] transaction","detail":"{read_only:false; response_revision:20752; number_of_response:1; }","duration":"129.109391ms","start":"2026-09-08T16:31:01.096144Z","end":"2026-09-08T16:31:01.225253Z","steps":["trace[1324927509] 'process raft request' (duration: 128.762664ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:01.435026Z","caller":"traceutil/trace.go:171","msg":"trace[617897917] linearizableReadLoop","detail":"{readStateIndex:21464; appliedIndex:21463; }","duration":"199.652047ms","start":"2026-09-08T16:31:01.235346Z","end":"2026-09-08T16:31:01.434998Z","steps":["trace[617897917] 'read index received' (duration: 152.816411ms)","trace[617897917] 'applied index is now lower than readState.Index' (duration: 46.834276ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:31:01.435056Z","caller":"traceutil/trace.go:171","msg":"trace[43784476] transaction","detail":"{read_only:false; response_revision:20753; number_of_response:1; }","duration":"205.631645ms","start":"2026-09-08T16:31:01.22938Z","end":"2026-09-08T16:31:01.435012Z","steps":["trace[43784476] 'process raft request' (duration: 158.84385ms)","trace[43784476] 'compare' (duration: 46.644722ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:31:01.435212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.858111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-08T16:31:01.435268Z","caller":"traceutil/trace.go:171","msg":"trace[1172337130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20753; }","duration":"199.953513ms","start":"2026-09-08T16:31:01.235299Z","end":"2026-09-08T16:31:01.435253Z","steps":["trace[1172337130] 'agreement among raft nodes before linearized reading' (duration: 199.79096ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:01.593428Z","caller":"traceutil/trace.go:171","msg":"trace[26560841] linearizableReadLoop","detail":"{readStateIndex:21465; appliedIndex:21464; }","duration":"158.305677ms","start":"2026-09-08T16:31:01.435094Z","end":"2026-09-08T16:31:01.5934Z","steps":["trace[26560841] 'read index received' (duration: 134.66159ms)","trace[26560841] 'applied index is now lower than readState.Index' (duration: 23.642437ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:31:01.593491Z","caller":"traceutil/trace.go:171","msg":"trace[1675420927] transaction","detail":"{read_only:false; response_revision:20754; number_of_response:1; }","duration":"176.56786ms","start":"2026-09-08T16:31:01.416897Z","end":"2026-09-08T16:31:01.593465Z","steps":["trace[1675420927] 'process raft request' (duration: 152.869282ms)","trace[1675420927] 'compare' (duration: 23.502324ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:31:01.593648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.622355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-08T16:31:01.593701Z","caller":"traceutil/trace.go:171","msg":"trace[2111029688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20754; }","duration":"247.733417ms","start":"2026-09-08T16:31:01.345953Z","end":"2026-09-08T16:31:01.593686Z","steps":["trace[2111029688] 'agreement among raft nodes before linearized reading' (duration: 247.550084ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:01.91366Z","caller":"traceutil/trace.go:171","msg":"trace[1453734398] linearizableReadLoop","detail":"{readStateIndex:21466; appliedIndex:21465; }","duration":"320.125632ms","start":"2026-09-08T16:31:01.593506Z","end":"2026-09-08T16:31:01.913632Z","steps":["trace[1453734398] 'read index received' (duration: 260.112674ms)","trace[1453734398] 'applied index is now lower than readState.Index' (duration: 60.011658ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:31:01.913738Z","caller":"traceutil/trace.go:171","msg":"trace[1190920650] transaction","detail":"{read_only:false; response_revision:20755; number_of_response:1; }","duration":"473.055655ms","start":"2026-09-08T16:31:01.440621Z","end":"2026-09-08T16:31:01.913676Z","steps":["trace[1190920650] 'process raft request' (duration: 412.904434ms)","trace[1190920650] 'compare' (duration: 59.819843ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:31:01.913909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:31:01.440595Z","time spent":"473.232428ms","remote":"127.0.0.1:55234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:31:01.913879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.778921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:31:01.914015Z","caller":"traceutil/trace.go:171","msg":"trace[955849059] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:20755; }","duration":"405.955754ms","start":"2026-09-08T16:31:01.508032Z","end":"2026-09-08T16:31:01.913988Z","steps":["trace[955849059] 'agreement among raft nodes before linearized reading' (duration: 405.77593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:31:01.914123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:31:01.508013Z","time spent":"406.091717ms","remote":"127.0.0.1:41638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"info","ts":"2026-09-08T16:31:01.941307Z","caller":"traceutil/trace.go:171","msg":"trace[161239821] transaction","detail":"{read_only:false; response_revision:20756; number_of_response:1; }","duration":"343.147405ms","start":"2026-09-08T16:31:01.598135Z","end":"2026-09-08T16:31:01.941283Z","steps":["trace[161239821] 'process raft request' (duration: 342.948281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:31:01.941493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:31:01.598111Z","time spent":"343.255058ms","remote":"127.0.0.1:55234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:31:02.193675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.388164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:31:02.193848Z","caller":"traceutil/trace.go:171","msg":"trace[1306264228] transaction","detail":"{read_only:false; response_revision:20757; number_of_response:1; }","duration":"224.637743ms","start":"2026-09-08T16:31:01.969184Z","end":"2026-09-08T16:31:02.193822Z","steps":["trace[1306264228] 'process raft request' (duration: 96.976632ms)","trace[1306264228] 'compare' (duration: 127.255422ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:31:02.485015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.39539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:31:02.485112Z","caller":"traceutil/trace.go:171","msg":"trace[1170719944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20757; }","duration":"165.517274ms","start":"2026-09-08T16:31:02.319576Z","end":"2026-09-08T16:31:02.485093Z","steps":["trace[1170719944] 'range keys from in-memory index tree' (duration: 165.198216ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:02.651768Z","caller":"traceutil/trace.go:171","msg":"trace[1953002311] transaction","detail":"{read_only:false; response_revision:20758; number_of_response:1; }","duration":"130.306078ms","start":"2026-09-08T16:31:02.521435Z","end":"2026-09-08T16:31:02.651741Z","steps":["trace[1953002311] 'process raft request' (duration: 130.088223ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:02.796979Z","caller":"traceutil/trace.go:171","msg":"trace[585750542] transaction","detail":"{read_only:false; response_revision:20760; number_of_response:1; }","duration":"200.133917ms","start":"2026-09-08T16:31:02.596821Z","end":"2026-09-08T16:31:02.796955Z","steps":["trace[585750542] 'process raft request' (duration: 200.086076ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:31:02.797087Z","caller":"traceutil/trace.go:171","msg":"trace[213671163] transaction","detail":"{read_only:false; response_revision:20759; number_of_response:1; }","duration":"272.460018ms","start":"2026-09-08T16:31:02.524596Z","end":"2026-09-08T16:31:02.797056Z","steps":["trace[213671163] 'process raft request' (duration: 272.122701ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:33:19.002685Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19609} {"level":"info","ts":"2026-09-08T16:33:19.119652Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19609,"took":"114.695441ms","hash":2567573356,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":29716480,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:33:19.119702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2567573356,"revision":19609,"compact-revision":17304} {"level":"info","ts":"2026-09-08T16:34:59.848708Z","caller":"traceutil/trace.go:171","msg":"trace[386560164] transaction","detail":"{read_only:false; response_revision:23202; number_of_response:1; }","duration":"219.745955ms","start":"2026-09-08T16:34:59.628928Z","end":"2026-09-08T16:34:59.848674Z","steps":["trace[386560164] 'process raft request' (duration: 219.548851ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:34:59.874126Z","caller":"traceutil/trace.go:171","msg":"trace[1548379050] transaction","detail":"{read_only:false; response_revision:23203; number_of_response:1; }","duration":"109.266314ms","start":"2026-09-08T16:34:59.764821Z","end":"2026-09-08T16:34:59.874087Z","steps":["trace[1548379050] 'process raft request' (duration: 109.015538ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:38:19.00796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21846} {"level":"info","ts":"2026-09-08T16:38:19.125862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21846,"took":"114.878855ms","hash":3941060215,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":43171840,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-08T16:38:19.126034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3941060215,"revision":21846,"compact-revision":19609} {"level":"info","ts":"2026-09-08T16:43:19.021923Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25009} {"level":"info","ts":"2026-09-08T16:43:19.211903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25009,"took":"185.789054ms","hash":579993200,"current-db-size-bytes":54071296,"current-db-size":"54 MB","current-db-size-in-use-bytes":30076928,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:43:19.211975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":579993200,"revision":25009,"compact-revision":21846}