{"level":"warn","ts":"2026-09-29T03:39:34.733646Z","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-29T03:39:34.733739Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.111: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.111:2380","--initial-cluster=instance=https://162.253.55.111:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.111:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.111: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-29T03:39:34.733796Z","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-29T03:39:34.733808Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-29T03:39:34.733828Z","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-29T03:39:34.734581Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"]} {"level":"info","ts":"2026-09-29T03:39:34.73466Z","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.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.111: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-29T03:39:34.738482Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.295228ms"} {"level":"info","ts":"2026-09-29T03:39:34.745407Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"299bf3744060cbd","cluster-id":"83d94f04ddaf7996"} {"level":"info","ts":"2026-09-29T03:39:34.745481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=()"} {"level":"info","ts":"2026-09-29T03:39:34.745502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 0"} {"level":"info","ts":"2026-09-29T03:39:34.745516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 299bf3744060cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-29T03:39:34.745526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became follower at term 1"} {"level":"info","ts":"2026-09-29T03:39:34.745567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"warn","ts":"2026-09-29T03:39:34.751618Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-29T03:39:34.753267Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-29T03:39:34.755198Z","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-29T03:39:34.757463Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"299bf3744060cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-29T03:39:34.757701Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"299bf3744060cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-29T03:39:34.757986Z","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-29T03:39:34.759512Z","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-29T03:39:34.759553Z","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-29T03:39:34.760857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd switched to configuration voters=(187391103597939901)"} {"level":"info","ts":"2026-09-29T03:39:34.761678Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","added-peer-id":"299bf3744060cbd","added-peer-peer-urls":["https://162.253.55.111:2380"]} {"level":"info","ts":"2026-09-29T03:39:34.769213Z","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-29T03:39:34.769439Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-29T03:39:34.769501Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.111:2380"} {"level":"info","ts":"2026-09-29T03:39:34.769623Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"299bf3744060cbd","initial-advertise-peer-urls":["https://162.253.55.111:2380"],"listen-peer-urls":["https://162.253.55.111:2380"],"advertise-client-urls":["https://162.253.55.111:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.111:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-29T03:39:34.769675Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-29T03:39:35.446559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd is starting a new election at term 1"} {"level":"info","ts":"2026-09-29T03:39:35.446653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became pre-candidate at term 1"} {"level":"info","ts":"2026-09-29T03:39:35.446696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgPreVoteResp from 299bf3744060cbd at term 1"} {"level":"info","ts":"2026-09-29T03:39:35.446718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became candidate at term 2"} {"level":"info","ts":"2026-09-29T03:39:35.446729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd received MsgVoteResp from 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-29T03:39:35.446745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"299bf3744060cbd became leader at term 2"} {"level":"info","ts":"2026-09-29T03:39:35.446765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 299bf3744060cbd elected leader 299bf3744060cbd at term 2"} {"level":"info","ts":"2026-09-29T03:39:35.44806Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"299bf3744060cbd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.111:2379]}","request-path":"/0/members/299bf3744060cbd/attributes","cluster-id":"83d94f04ddaf7996","publish-timeout":"7s"} {"level":"info","ts":"2026-09-29T03:39:35.448074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T03:39:35.448094Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:39:35.448825Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T03:39:35.449607Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"83d94f04ddaf7996","local-member-id":"299bf3744060cbd","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:39:35.44974Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:39:35.449775Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T03:39:35.451281Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-29T03:39:35.451512Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-29T03:39:35.451547Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.111:2379"} {"level":"info","ts":"2026-09-29T03:39:35.452494Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-29T03:40:15.587694Z","caller":"traceutil/trace.go:171","msg":"trace[1633069495] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"122.668475ms","start":"2026-09-29T03:40:15.464988Z","end":"2026-09-29T03:40:15.587656Z","steps":["trace[1633069495] 'process raft request' (duration: 74.375054ms)","trace[1633069495] 'compare' (duration: 48.050185ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:40:15.587856Z","caller":"traceutil/trace.go:171","msg":"trace[1589601953] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"102.073294ms","start":"2026-09-29T03:40:15.485735Z","end":"2026-09-29T03:40:15.587809Z","steps":["trace[1589601953] 'process raft request' (duration: 101.834378ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:40:21.578641Z","caller":"traceutil/trace.go:171","msg":"trace[769352163] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"166.635103ms","start":"2026-09-29T03:40:21.411973Z","end":"2026-09-29T03:40:21.578608Z","steps":["trace[769352163] 'process raft request' (duration: 166.182483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:40:22.052889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.020717ms","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-29T03:40:22.053063Z","caller":"traceutil/trace.go:171","msg":"trace[2093548083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"275.255493ms","start":"2026-09-29T03:40:21.777777Z","end":"2026-09-29T03:40:22.053032Z","steps":["trace[2093548083] 'range keys from in-memory index tree' (duration: 274.727909ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:40:22.058055Z","caller":"traceutil/trace.go:171","msg":"trace[172288293] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"257.636172ms","start":"2026-09-29T03:40:21.800358Z","end":"2026-09-29T03:40:22.057994Z","steps":["trace[172288293] 'process raft request' (duration: 257.413377ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:40:38.341135Z","caller":"traceutil/trace.go:171","msg":"trace[798751796] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"196.295431ms","start":"2026-09-29T03:40:38.14481Z","end":"2026-09-29T03:40:38.341105Z","steps":["trace[798751796] 'process raft request' (duration: 196.143087ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:40:38.421992Z","caller":"traceutil/trace.go:171","msg":"trace[865585508] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:824; }","duration":"221.126883ms","start":"2026-09-29T03:40:38.200835Z","end":"2026-09-29T03:40:38.421962Z","steps":["trace[865585508] 'read index received' (duration: 140.193903ms)","trace[865585508] 'applied index is now lower than readState.Index' (duration: 80.931519ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:40:38.422054Z","caller":"traceutil/trace.go:171","msg":"trace[1010795651] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"276.600095ms","start":"2026-09-29T03:40:38.145412Z","end":"2026-09-29T03:40:38.422012Z","steps":["trace[1010795651] 'process raft request' (duration: 275.721324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:40:38.422682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.798638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-29T03:40:38.422768Z","caller":"traceutil/trace.go:171","msg":"trace[454082910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"221.959332ms","start":"2026-09-29T03:40:38.200792Z","end":"2026-09-29T03:40:38.422751Z","steps":["trace[454082910] 'agreement among raft nodes before linearized reading' (duration: 221.353938ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:32.804096Z","caller":"traceutil/trace.go:171","msg":"trace[1576115470] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1516; }","duration":"113.298049ms","start":"2026-09-29T03:41:32.690763Z","end":"2026-09-29T03:41:32.804061Z","steps":["trace[1576115470] 'read index received' (duration: 87.79318ms)","trace[1576115470] 'applied index is now lower than readState.Index' (duration: 25.503599ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:41:32.804119Z","caller":"traceutil/trace.go:171","msg":"trace[1777968242] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"123.228846ms","start":"2026-09-29T03:41:32.680865Z","end":"2026-09-29T03:41:32.804094Z","steps":["trace[1777968242] 'process raft request' (duration: 97.86891ms)","trace[1777968242] 'compare' (duration: 25.189132ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:41:32.804267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.496764ms","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-29T03:41:32.8043Z","caller":"traceutil/trace.go:171","msg":"trace[2120908967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1471; }","duration":"113.566205ms","start":"2026-09-29T03:41:32.690726Z","end":"2026-09-29T03:41:32.804292Z","steps":["trace[2120908967] 'agreement among raft nodes before linearized reading' (duration: 113.445472ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:33.001814Z","caller":"traceutil/trace.go:171","msg":"trace[1086372318] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"101.154568ms","start":"2026-09-29T03:41:32.900599Z","end":"2026-09-29T03:41:33.001753Z","steps":["trace[1086372318] 'process raft request' (duration: 100.80963ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:41:58.024244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.521276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-09-29T03:41:58.024371Z","caller":"traceutil/trace.go:171","msg":"trace[2006180537] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1888; }","duration":"262.714931ms","start":"2026-09-29T03:41:57.76163Z","end":"2026-09-29T03:41:58.024345Z","steps":["trace[2006180537] 'range keys from in-memory index tree' (duration: 262.289111ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:58.038204Z","caller":"traceutil/trace.go:171","msg":"trace[759025316] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"171.167532ms","start":"2026-09-29T03:41:57.867008Z","end":"2026-09-29T03:41:58.038176Z","steps":["trace[759025316] 'process raft request' (duration: 170.998758ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:58.04774Z","caller":"traceutil/trace.go:171","msg":"trace[482995853] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1961; }","duration":"104.825007ms","start":"2026-09-29T03:41:57.9429Z","end":"2026-09-29T03:41:58.047725Z","steps":["trace[482995853] 'read index received' (duration: 95.070673ms)","trace[482995853] 'applied index is now lower than readState.Index' (duration: 9.752844ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:41:58.047854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.942149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:41:58.047832Z","caller":"traceutil/trace.go:171","msg":"trace[1317006767] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"141.582324ms","start":"2026-09-29T03:41:57.906214Z","end":"2026-09-29T03:41:58.047796Z","steps":["trace[1317006767] 'process raft request' (duration: 141.428361ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:58.04789Z","caller":"traceutil/trace.go:171","msg":"trace[1500650029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1890; }","duration":"105.032631ms","start":"2026-09-29T03:41:57.942847Z","end":"2026-09-29T03:41:58.04788Z","steps":["trace[1500650029] 'agreement among raft nodes before linearized reading' (duration: 104.955669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:41:58.093175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.504847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-29T03:41:58.093282Z","caller":"traceutil/trace.go:171","msg":"trace[1701823047] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1891; }","duration":"101.66195ms","start":"2026-09-29T03:41:57.991593Z","end":"2026-09-29T03:41:58.093255Z","steps":["trace[1701823047] 'agreement among raft nodes before linearized reading' (duration: 101.486116ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:58.356635Z","caller":"traceutil/trace.go:171","msg":"trace[1775594180] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"256.084532ms","start":"2026-09-29T03:41:58.100513Z","end":"2026-09-29T03:41:58.356597Z","steps":["trace[1775594180] 'process raft request' (duration: 255.855627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:41:58.357079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.515215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-29T03:41:58.356709Z","caller":"traceutil/trace.go:171","msg":"trace[390017407] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1965; }","duration":"185.148037ms","start":"2026-09-29T03:41:58.171539Z","end":"2026-09-29T03:41:58.356687Z","steps":["trace[390017407] 'read index received' (duration: 158.302305ms)","trace[390017407] 'applied index is now lower than readState.Index' (duration: 26.843571ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:41:58.356704Z","caller":"traceutil/trace.go:171","msg":"trace[1990332510] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"256.270997ms","start":"2026-09-29T03:41:58.100335Z","end":"2026-09-29T03:41:58.356606Z","steps":["trace[1990332510] 'process raft request' (duration: 229.501957ms)","trace[1990332510] 'compare' (duration: 26.37138ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:41:58.357131Z","caller":"traceutil/trace.go:171","msg":"trace[487771265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1896; }","duration":"185.588797ms","start":"2026-09-29T03:41:58.171531Z","end":"2026-09-29T03:41:58.35712Z","steps":["trace[487771265] 'agreement among raft nodes before linearized reading' (duration: 185.216538ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:58.356673Z","caller":"traceutil/trace.go:171","msg":"trace[1925894746] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"256.061041ms","start":"2026-09-29T03:41:58.100572Z","end":"2026-09-29T03:41:58.356633Z","steps":["trace[1925894746] 'process raft request' (duration: 255.893297ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:41:58.356833Z","caller":"traceutil/trace.go:171","msg":"trace[157705885] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"250.188211ms","start":"2026-09-29T03:41:58.106624Z","end":"2026-09-29T03:41:58.356812Z","steps":["trace[157705885] 'process raft request' (duration: 249.899324ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:42:52.96522Z","caller":"traceutil/trace.go:171","msg":"trace[963410953] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"260.088557ms","start":"2026-09-29T03:42:52.705096Z","end":"2026-09-29T03:42:52.965185Z","steps":["trace[963410953] 'process raft request' (duration: 259.941804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:42:53.344785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.301793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:42:53.34504Z","caller":"traceutil/trace.go:171","msg":"trace[538092147] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"353.897234ms","start":"2026-09-29T03:42:52.991097Z","end":"2026-09-29T03:42:53.344994Z","steps":["trace[538092147] 'process raft request' (duration: 135.764572ms)","trace[538092147] 'compare' (duration: 217.108888ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:42:53.345162Z","caller":"traceutil/trace.go:171","msg":"trace[2131508252] linearizableReadLoop","detail":"{readStateIndex:2328; appliedIndex:2327; }","duration":"249.801138ms","start":"2026-09-29T03:42:53.095304Z","end":"2026-09-29T03:42:53.345105Z","steps":["trace[2131508252] 'read index received' (duration: 31.548712ms)","trace[2131508252] 'applied index is now lower than readState.Index' (duration: 218.242826ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:42:53.345214Z","caller":"traceutil/trace.go:171","msg":"trace[1290627445] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"223.434271ms","start":"2026-09-29T03:42:53.121749Z","end":"2026-09-29T03:42:53.345184Z","steps":["trace[1290627445] 'process raft request' (duration: 223.257477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:42:53.345279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:42:52.991053Z","time spent":"354.093879ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T03:42:53.345485Z","caller":"traceutil/trace.go:171","msg":"trace[1503261283] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"112.480969ms","start":"2026-09-29T03:42:53.232987Z","end":"2026-09-29T03:42:53.345468Z","steps":["trace[1503261283] 'process raft request' (duration: 112.140231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:42:53.345622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.28617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-29T03:42:53.34572Z","caller":"traceutil/trace.go:171","msg":"trace[1796026111] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2245; }","duration":"250.446784ms","start":"2026-09-29T03:42:53.095248Z","end":"2026-09-29T03:42:53.345694Z","steps":["trace[1796026111] 'agreement among raft nodes before linearized reading' (duration: 249.995983ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:48:06.712479Z","caller":"traceutil/trace.go:171","msg":"trace[1046494244] linearizableReadLoop","detail":"{readStateIndex:4521; appliedIndex:4520; }","duration":"100.276186ms","start":"2026-09-29T03:48:06.612173Z","end":"2026-09-29T03:48:06.712449Z","steps":["trace[1046494244] 'read index received' (duration: 23.827624ms)","trace[1046494244] 'applied index is now lower than readState.Index' (duration: 76.446822ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:48:06.712558Z","caller":"traceutil/trace.go:171","msg":"trace[1579575295] transaction","detail":"{read_only:false; response_revision:4354; number_of_response:1; }","duration":"101.921255ms","start":"2026-09-29T03:48:06.610587Z","end":"2026-09-29T03:48:06.712508Z","steps":["trace[1579575295] 'process raft request' (duration: 83.700746ms)","trace[1579575295] 'compare' (duration: 17.746688ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:48:06.712683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.482651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/scrapeconfigs.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:48:06.712767Z","caller":"traceutil/trace.go:171","msg":"trace[650524000] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/scrapeconfigs.monitoring.coreos.com; range_end:; response_count:0; response_revision:4354; }","duration":"100.617054ms","start":"2026-09-29T03:48:06.612132Z","end":"2026-09-29T03:48:06.71275Z","steps":["trace[650524000] 'agreement among raft nodes before linearized reading' (duration: 100.4655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:48:59.75431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.157176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-29T03:48:59.754345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.916354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-29T03:48:59.754501Z","caller":"traceutil/trace.go:171","msg":"trace[1988359827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5187; }","duration":"159.17302ms","start":"2026-09-29T03:48:59.595298Z","end":"2026-09-29T03:48:59.754471Z","steps":["trace[1988359827] 'range keys from in-memory index tree' (duration: 158.552045ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:48:59.754416Z","caller":"traceutil/trace.go:171","msg":"trace[2119448555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5187; }","duration":"110.32913ms","start":"2026-09-29T03:48:59.644064Z","end":"2026-09-29T03:48:59.754394Z","steps":["trace[2119448555] 'range keys from in-memory index tree' (duration: 109.878339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:48:59.759041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.601673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210296"} {"level":"info","ts":"2026-09-29T03:48:59.75918Z","caller":"traceutil/trace.go:171","msg":"trace[85162246] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5187; }","duration":"109.768397ms","start":"2026-09-29T03:48:59.649382Z","end":"2026-09-29T03:48:59.759151Z","steps":["trace[85162246] 'range keys from in-memory index tree' (duration: 104.481231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:49:02.633307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.091961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-29T03:49:02.633397Z","caller":"traceutil/trace.go:171","msg":"trace[617459893] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5310; }","duration":"170.248245ms","start":"2026-09-29T03:49:02.463131Z","end":"2026-09-29T03:49:02.633379Z","steps":["trace[617459893] 'count revisions from in-memory index tree' (duration: 170.003269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:49:02.633438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.892194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e3fa613f-41cb-4df7-a471-d2958874a18e\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-09-29T03:49:02.633536Z","caller":"traceutil/trace.go:171","msg":"trace[1781550153] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e3fa613f-41cb-4df7-a471-d2958874a18e; range_end:; response_count:1; response_revision:5310; }","duration":"398.056508ms","start":"2026-09-29T03:49:02.235456Z","end":"2026-09-29T03:49:02.633513Z","steps":["trace[1781550153] 'range keys from in-memory index tree' (duration: 397.72207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:49:02.633592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T03:49:02.235428Z","time spent":"398.154751ms","remote":"127.0.0.1:38198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3744,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e3fa613f-41cb-4df7-a471-d2958874a18e\" "} {"level":"info","ts":"2026-09-29T03:49:17.082279Z","caller":"traceutil/trace.go:171","msg":"trace[670661375] transaction","detail":"{read_only:false; response_revision:5571; number_of_response:1; }","duration":"192.699462ms","start":"2026-09-29T03:49:16.889557Z","end":"2026-09-29T03:49:17.082257Z","steps":["trace[670661375] 'process raft request' (duration: 192.555369ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:49:17.082357Z","caller":"traceutil/trace.go:171","msg":"trace[497837854] linearizableReadLoop","detail":"{readStateIndex:5779; appliedIndex:5779; }","duration":"140.422581ms","start":"2026-09-29T03:49:16.941905Z","end":"2026-09-29T03:49:17.082328Z","steps":["trace[497837854] 'read index received' (duration: 140.411161ms)","trace[497837854] 'applied index is now lower than readState.Index' (duration: 9.6µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:49:17.082656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.733729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:49:17.082742Z","caller":"traceutil/trace.go:171","msg":"trace[2123505338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5571; }","duration":"140.836611ms","start":"2026-09-29T03:49:16.941863Z","end":"2026-09-29T03:49:17.0827Z","steps":["trace[2123505338] 'agreement among raft nodes before linearized reading' (duration: 140.546124ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:49:28.682133Z","caller":"traceutil/trace.go:171","msg":"trace[855275065] transaction","detail":"{read_only:false; response_revision:5811; number_of_response:1; }","duration":"143.622988ms","start":"2026-09-29T03:49:28.538488Z","end":"2026-09-29T03:49:28.682111Z","steps":["trace[855275065] 'process raft request' (duration: 143.463164ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:49:28.699901Z","caller":"traceutil/trace.go:171","msg":"trace[342220330] transaction","detail":"{read_only:false; response_revision:5812; number_of_response:1; }","duration":"158.898733ms","start":"2026-09-29T03:49:28.54098Z","end":"2026-09-29T03:49:28.699879Z","steps":["trace[342220330] 'process raft request' (duration: 158.74246ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:49:28.700003Z","caller":"traceutil/trace.go:171","msg":"trace[1183326845] linearizableReadLoop","detail":"{readStateIndex:6025; appliedIndex:6023; }","duration":"155.669756ms","start":"2026-09-29T03:49:28.544317Z","end":"2026-09-29T03:49:28.699987Z","steps":["trace[1183326845] 'read index received' (duration: 137.616774ms)","trace[1183326845] 'applied index is now lower than readState.Index' (duration: 18.051642ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:49:28.700043Z","caller":"traceutil/trace.go:171","msg":"trace[977961429] transaction","detail":"{read_only:false; response_revision:5813; number_of_response:1; }","duration":"119.709586ms","start":"2026-09-29T03:49:28.580327Z","end":"2026-09-29T03:49:28.700036Z","steps":["trace[977961429] 'process raft request' (duration: 119.517762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:49:28.700163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.838631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-29T03:49:28.700196Z","caller":"traceutil/trace.go:171","msg":"trace[1602023449] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5813; }","duration":"155.909222ms","start":"2026-09-29T03:49:28.544275Z","end":"2026-09-29T03:49:28.700184Z","steps":["trace[1602023449] 'agreement among raft nodes before linearized reading' (duration: 155.777129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:49:28.956611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.781779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T03:49:28.956706Z","caller":"traceutil/trace.go:171","msg":"trace[937141438] transaction","detail":"{read_only:false; response_revision:5814; number_of_response:1; }","duration":"239.694028ms","start":"2026-09-29T03:49:28.716996Z","end":"2026-09-29T03:49:28.95669Z","steps":["trace[937141438] 'process raft request' (duration: 111.763495ms)","trace[937141438] 'compare' (duration: 127.602425ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:49:29.152929Z","caller":"traceutil/trace.go:171","msg":"trace[1432227382] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"190.785386ms","start":"2026-09-29T03:49:28.962123Z","end":"2026-09-29T03:49:29.152908Z","steps":["trace[1432227382] 'process raft request' (duration: 125.665738ms)","trace[1432227382] 'compare' (duration: 64.867642ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:49:29.1532Z","caller":"traceutil/trace.go:171","msg":"trace[2073588615] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"191.025012ms","start":"2026-09-29T03:49:28.962166Z","end":"2026-09-29T03:49:29.153191Z","steps":["trace[2073588615] 'process raft request' (duration: 190.699874ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:49:29.153204Z","caller":"traceutil/trace.go:171","msg":"trace[432610179] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"190.803087ms","start":"2026-09-29T03:49:28.962378Z","end":"2026-09-29T03:49:29.153181Z","steps":["trace[432610179] 'process raft request' (duration: 190.704085ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:49:29.174944Z","caller":"traceutil/trace.go:171","msg":"trace[551714750] transaction","detail":"{read_only:false; response_revision:5820; number_of_response:1; }","duration":"203.624794ms","start":"2026-09-29T03:49:28.971287Z","end":"2026-09-29T03:49:29.174912Z","steps":["trace[551714750] 'process raft request' (duration: 203.390739ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:49:29.175009Z","caller":"traceutil/trace.go:171","msg":"trace[171253110] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"205.452097ms","start":"2026-09-29T03:49:28.969513Z","end":"2026-09-29T03:49:29.174965Z","steps":["trace[171253110] 'process raft request' (duration: 205.002387ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:49:35.584596Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2996} {"level":"info","ts":"2026-09-29T03:49:35.657118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2996,"took":"70.656881ms","hash":442477159,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":32481280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T03:49:35.657195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":442477159,"revision":2996,"compact-revision":-1} {"level":"info","ts":"2026-09-29T03:54:35.593858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5893} {"level":"info","ts":"2026-09-29T03:54:35.688725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5893,"took":"92.22299ms","hash":2095587632,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":26550272,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-29T03:54:35.688841Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2095587632,"revision":5893,"compact-revision":2996} {"level":"info","ts":"2026-09-29T03:55:49.839732Z","caller":"traceutil/trace.go:171","msg":"trace[1772361514] linearizableReadLoop","detail":"{readStateIndex:8976; appliedIndex:8975; }","duration":"164.007683ms","start":"2026-09-29T03:55:49.675695Z","end":"2026-09-29T03:55:49.839703Z","steps":["trace[1772361514] 'read index received' (duration: 163.978522ms)","trace[1772361514] 'applied index is now lower than readState.Index' (duration: 28.161µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:55:49.840084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.368852ms","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-29T03:55:49.840181Z","caller":"traceutil/trace.go:171","msg":"trace[155588744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8669; }","duration":"164.508835ms","start":"2026-09-29T03:55:49.675651Z","end":"2026-09-29T03:55:49.84016Z","steps":["trace[155588744] 'agreement among raft nodes before linearized reading' (duration: 164.225868ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:55:49.840282Z","caller":"traceutil/trace.go:171","msg":"trace[989517950] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"293.456613ms","start":"2026-09-29T03:55:49.546755Z","end":"2026-09-29T03:55:49.840212Z","steps":["trace[989517950] 'process raft request' (duration: 292.805488ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:55:49.845308Z","caller":"traceutil/trace.go:171","msg":"trace[970594818] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"127.034143ms","start":"2026-09-29T03:55:49.718246Z","end":"2026-09-29T03:55:49.84528Z","steps":["trace[970594818] 'process raft request' (duration: 126.693165ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:55:59.949829Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002349.wal"} {"level":"info","ts":"2026-09-29T03:56:02.280867Z","caller":"traceutil/trace.go:171","msg":"trace[2064197292] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"197.994772ms","start":"2026-09-29T03:56:02.082807Z","end":"2026-09-29T03:56:02.280802Z","steps":["trace[2064197292] 'process raft request' (duration: 197.794467ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:02.519374Z","caller":"traceutil/trace.go:171","msg":"trace[1134686020] linearizableReadLoop","detail":"{readStateIndex:9046; appliedIndex:9045; }","duration":"182.129355ms","start":"2026-09-29T03:56:02.337211Z","end":"2026-09-29T03:56:02.519341Z","steps":["trace[1134686020] 'read index received' (duration: 120.388875ms)","trace[1134686020] 'applied index is now lower than readState.Index' (duration: 61.7393ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:56:02.519501Z","caller":"traceutil/trace.go:171","msg":"trace[1961520346] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"233.991528ms","start":"2026-09-29T03:56:02.285454Z","end":"2026-09-29T03:56:02.519445Z","steps":["trace[1961520346] 'process raft request' (duration: 172.128516ms)","trace[1961520346] 'compare' (duration: 61.631697ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:56:02.519663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.429482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-29T03:56:02.519701Z","caller":"traceutil/trace.go:171","msg":"trace[1055092880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8737; }","duration":"182.529734ms","start":"2026-09-29T03:56:02.33716Z","end":"2026-09-29T03:56:02.51969Z","steps":["trace[1055092880] 'agreement among raft nodes before linearized reading' (duration: 182.34307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:56:02.519989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.638836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-29T03:56:02.520088Z","caller":"traceutil/trace.go:171","msg":"trace[681131288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8737; }","duration":"182.813891ms","start":"2026-09-29T03:56:02.337252Z","end":"2026-09-29T03:56:02.520066Z","steps":["trace[681131288] 'agreement among raft nodes before linearized reading' (duration: 182.479703ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:02.702702Z","caller":"traceutil/trace.go:171","msg":"trace[890649232] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"178.119429ms","start":"2026-09-29T03:56:02.524536Z","end":"2026-09-29T03:56:02.702656Z","steps":["trace[890649232] 'process raft request' (duration: 139.671144ms)","trace[890649232] 'compare' (duration: 38.22351ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T03:56:33.142533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.150708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-29T03:56:33.142637Z","caller":"traceutil/trace.go:171","msg":"trace[1610477836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8954; }","duration":"274.316302ms","start":"2026-09-29T03:56:32.868301Z","end":"2026-09-29T03:56:33.142617Z","steps":["trace[1610477836] 'range keys from in-memory index tree' (duration: 273.976344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:56:33.142602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.436069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rjs97\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-29T03:56:33.142806Z","caller":"traceutil/trace.go:171","msg":"trace[983911289] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rjs97; range_end:; response_count:1; response_revision:8954; }","duration":"218.693365ms","start":"2026-09-29T03:56:32.924091Z","end":"2026-09-29T03:56:33.142784Z","steps":["trace[983911289] 'range keys from in-memory index tree' (duration: 218.259415ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:33.355406Z","caller":"traceutil/trace.go:171","msg":"trace[2042646956] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"218.995353ms","start":"2026-09-29T03:56:33.136375Z","end":"2026-09-29T03:56:33.35537Z","steps":["trace[2042646956] 'process raft request' (duration: 218.780408ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:33.355626Z","caller":"traceutil/trace.go:171","msg":"trace[1301984052] transaction","detail":"{read_only:false; response_revision:8957; number_of_response:1; }","duration":"213.542063ms","start":"2026-09-29T03:56:33.142032Z","end":"2026-09-29T03:56:33.355574Z","steps":["trace[1301984052] 'process raft request' (duration: 213.299757ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:33.356111Z","caller":"traceutil/trace.go:171","msg":"trace[1803170784] linearizableReadLoop","detail":"{readStateIndex:9276; appliedIndex:9273; }","duration":"208.643726ms","start":"2026-09-29T03:56:33.147444Z","end":"2026-09-29T03:56:33.356087Z","steps":["trace[1803170784] 'read index received' (duration: 207.747674ms)","trace[1803170784] 'applied index is now lower than readState.Index' (duration: 895.122µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:56:33.356363Z","caller":"traceutil/trace.go:171","msg":"trace[855433010] transaction","detail":"{read_only:false; response_revision:8958; number_of_response:1; }","duration":"209.139767ms","start":"2026-09-29T03:56:33.147185Z","end":"2026-09-29T03:56:33.356324Z","steps":["trace[855433010] 'process raft request' (duration: 208.793919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:56:33.356609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.142868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:227530"} {"level":"info","ts":"2026-09-29T03:56:33.356656Z","caller":"traceutil/trace.go:171","msg":"trace[1075469175] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8958; }","duration":"209.226ms","start":"2026-09-29T03:56:33.147418Z","end":"2026-09-29T03:56:33.356644Z","steps":["trace[1075469175] 'agreement among raft nodes before linearized reading' (duration: 208.745529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:56:35.567162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.099257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T03:56:35.567241Z","caller":"traceutil/trace.go:171","msg":"trace[1233760961] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:8967; }","duration":"118.21927ms","start":"2026-09-29T03:56:35.449007Z","end":"2026-09-29T03:56:35.567226Z","steps":["trace[1233760961] 'count revisions from in-memory index tree' (duration: 117.991814ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:43.409948Z","caller":"traceutil/trace.go:171","msg":"trace[192528978] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"209.704451ms","start":"2026-09-29T03:56:43.200213Z","end":"2026-09-29T03:56:43.409918Z","steps":["trace[192528978] 'process raft request' (duration: 209.528527ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:43.530864Z","caller":"traceutil/trace.go:171","msg":"trace[769564093] transaction","detail":"{read_only:false; response_revision:9008; number_of_response:1; }","duration":"125.154355ms","start":"2026-09-29T03:56:43.40566Z","end":"2026-09-29T03:56:43.530815Z","steps":["trace[769564093] 'process raft request' (duration: 111.91925ms)","trace[769564093] 'compare' (duration: 12.841966ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:56:43.53133Z","caller":"traceutil/trace.go:171","msg":"trace[1679673980] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"105.284651ms","start":"2026-09-29T03:56:43.425985Z","end":"2026-09-29T03:56:43.53127Z","steps":["trace[1679673980] 'process raft request' (duration: 104.557524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T03:56:43.531476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.082297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-29T03:56:43.531576Z","caller":"traceutil/trace.go:171","msg":"trace[1824827679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9009; }","duration":"100.233951ms","start":"2026-09-29T03:56:43.431318Z","end":"2026-09-29T03:56:43.531552Z","steps":["trace[1824827679] 'agreement among raft nodes before linearized reading' (duration: 99.972454ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:56:47.286973Z","caller":"traceutil/trace.go:171","msg":"trace[1601207838] transaction","detail":"{read_only:false; response_revision:9038; number_of_response:1; }","duration":"134.465577ms","start":"2026-09-29T03:56:47.152473Z","end":"2026-09-29T03:56:47.286939Z","steps":["trace[1601207838] 'process raft request' (duration: 69.916138ms)","trace[1601207838] 'compare' (duration: 64.238332ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T03:56:47.28727Z","caller":"traceutil/trace.go:171","msg":"trace[1855962908] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9039; }","duration":"133.175836ms","start":"2026-09-29T03:56:47.154027Z","end":"2026-09-29T03:56:47.287203Z","steps":["trace[1855962908] 'process raft request' (duration: 132.819878ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T03:57:47.092255Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-29T03:57:47.106285Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-29T03:57:47.106447Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-29T03:59:35.60141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8047} {"level":"info","ts":"2026-09-29T03:59:35.674356Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8047,"took":"70.219374ms","hash":2000038648,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":28368896,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-29T03:59:35.674447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2000038648,"revision":8047,"compact-revision":5893} {"level":"info","ts":"2026-09-29T04:04:02.1663Z","caller":"traceutil/trace.go:171","msg":"trace[1471621616] transaction","detail":"{read_only:false; response_revision:12357; number_of_response:1; }","duration":"157.092558ms","start":"2026-09-29T04:04:02.009173Z","end":"2026-09-29T04:04:02.166266Z","steps":["trace[1471621616] 'process raft request' (duration: 156.822532ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:04:03.188835Z","caller":"traceutil/trace.go:171","msg":"trace[1534811025] linearizableReadLoop","detail":"{readStateIndex:12790; appliedIndex:12789; }","duration":"158.893482ms","start":"2026-09-29T04:04:03.029917Z","end":"2026-09-29T04:04:03.188811Z","steps":["trace[1534811025] 'read index received' (duration: 158.756258ms)","trace[1534811025] 'applied index is now lower than readState.Index' (duration: 136.284µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:04:03.189038Z","caller":"traceutil/trace.go:171","msg":"trace[1085788200] transaction","detail":"{read_only:false; response_revision:12360; number_of_response:1; }","duration":"174.165028ms","start":"2026-09-29T04:04:03.014833Z","end":"2026-09-29T04:04:03.188998Z","steps":["trace[1085788200] 'process raft request' (duration: 173.83931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:04:03.189295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.944481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"warn","ts":"2026-09-29T04:04:03.189351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.00142ms","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-29T04:04:03.189407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.497547ms","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-29T04:04:03.189452Z","caller":"traceutil/trace.go:171","msg":"trace[237904821] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12360; }","duration":"159.593129ms","start":"2026-09-29T04:04:03.029847Z","end":"2026-09-29T04:04:03.18944Z","steps":["trace[237904821] 'agreement among raft nodes before linearized reading' (duration: 159.528647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:04:03.189485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.097787ms","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-29T04:04:03.18953Z","caller":"traceutil/trace.go:171","msg":"trace[545720788] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12360; }","duration":"159.178289ms","start":"2026-09-29T04:04:03.030342Z","end":"2026-09-29T04:04:03.18952Z","steps":["trace[545720788] 'agreement among raft nodes before linearized reading' (duration: 159.097787ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:04:03.18935Z","caller":"traceutil/trace.go:171","msg":"trace[2033000333] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12360; }","duration":"156.028923ms","start":"2026-09-29T04:04:03.033306Z","end":"2026-09-29T04:04:03.189335Z","steps":["trace[2033000333] 'agreement among raft nodes before linearized reading' (duration: 155.874569ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:04:03.189405Z","caller":"traceutil/trace.go:171","msg":"trace[299786677] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12360; }","duration":"158.066642ms","start":"2026-09-29T04:04:03.031317Z","end":"2026-09-29T04:04:03.189383Z","steps":["trace[299786677] 'agreement among raft nodes before linearized reading' (duration: 158.008661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:04:03.189205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.890811ms","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-29T04:04:03.18983Z","caller":"traceutil/trace.go:171","msg":"trace[1044265913] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12360; }","duration":"159.549168ms","start":"2026-09-29T04:04:03.03026Z","end":"2026-09-29T04:04:03.18981Z","steps":["trace[1044265913] 'agreement among raft nodes before linearized reading' (duration: 158.883282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:04:03.190292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.566348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-29T04:04:03.191112Z","caller":"traceutil/trace.go:171","msg":"trace[555180369] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12360; }","duration":"160.430379ms","start":"2026-09-29T04:04:03.030655Z","end":"2026-09-29T04:04:03.191085Z","steps":["trace[555180369] 'agreement among raft nodes before linearized reading' (duration: 158.897362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:04:03.191811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.343633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rjs97\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-29T04:04:03.191876Z","caller":"traceutil/trace.go:171","msg":"trace[1159928865] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rjs97; range_end:; response_count:1; response_revision:12360; }","duration":"104.434006ms","start":"2026-09-29T04:04:03.087422Z","end":"2026-09-29T04:04:03.191856Z","steps":["trace[1159928865] 'agreement among raft nodes before linearized reading' (duration: 104.268052ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:04:35.60884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10416} {"level":"info","ts":"2026-09-29T04:04:35.704829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10416,"took":"92.54606ms","hash":1236655050,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":26132480,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-29T04:04:35.704909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1236655050,"revision":10416,"compact-revision":8047} {"level":"info","ts":"2026-09-29T04:09:35.617966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12580} {"level":"info","ts":"2026-09-29T04:09:35.702806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12580,"took":"81.968157ms","hash":3339646385,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":27627520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-29T04:09:35.702891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3339646385,"revision":12580,"compact-revision":10416} {"level":"info","ts":"2026-09-29T04:10:03.329661Z","caller":"traceutil/trace.go:171","msg":"trace[489063438] transaction","detail":"{read_only:false; response_revision:14834; number_of_response:1; }","duration":"155.143034ms","start":"2026-09-29T04:10:03.174489Z","end":"2026-09-29T04:10:03.329633Z","steps":["trace[489063438] 'process raft request' (duration: 154.97067ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:10:32.517491Z","caller":"traceutil/trace.go:171","msg":"trace[2062141246] transaction","detail":"{read_only:false; response_revision:14988; number_of_response:1; }","duration":"180.83697ms","start":"2026-09-29T04:10:32.336624Z","end":"2026-09-29T04:10:32.517461Z","steps":["trace[2062141246] 'process raft request' (duration: 180.257036ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:10:49.447052Z","caller":"traceutil/trace.go:171","msg":"trace[434750166] linearizableReadLoop","detail":"{readStateIndex:15618; appliedIndex:15617; }","duration":"160.849081ms","start":"2026-09-29T04:10:49.286171Z","end":"2026-09-29T04:10:49.44702Z","steps":["trace[434750166] 'read index received' (duration: 3.367551ms)","trace[434750166] 'applied index is now lower than readState.Index' (duration: 157.48002ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:10:49.447143Z","caller":"traceutil/trace.go:171","msg":"trace[700957713] transaction","detail":"{read_only:false; response_revision:15090; number_of_response:1; }","duration":"173.260298ms","start":"2026-09-29T04:10:49.273832Z","end":"2026-09-29T04:10:49.447092Z","steps":["trace[700957713] 'process raft request' (duration: 151.191409ms)","trace[700957713] 'compare' (duration: 21.628279ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:10:49.447373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.165308ms","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-29T04:10:49.447497Z","caller":"traceutil/trace.go:171","msg":"trace[1723885558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15090; }","duration":"161.443744ms","start":"2026-09-29T04:10:49.286024Z","end":"2026-09-29T04:10:49.447467Z","steps":["trace[1723885558] 'agreement among raft nodes before linearized reading' (duration: 161.119987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:10:50.251169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.013449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T04:10:50.251348Z","caller":"traceutil/trace.go:171","msg":"trace[1058293839] linearizableReadLoop","detail":"{readStateIndex:15622; appliedIndex:15621; }","duration":"384.407596ms","start":"2026-09-29T04:10:49.866909Z","end":"2026-09-29T04:10:50.251316Z","steps":["trace[1058293839] 'read index received' (duration: 102.032979ms)","trace[1058293839] 'applied index is now lower than readState.Index' (duration: 282.372957ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:10:50.251393Z","caller":"traceutil/trace.go:171","msg":"trace[1032409091] transaction","detail":"{read_only:false; response_revision:15094; number_of_response:1; }","duration":"403.69253ms","start":"2026-09-29T04:10:49.847666Z","end":"2026-09-29T04:10:50.251359Z","steps":["trace[1032409091] 'process raft request' (duration: 121.360303ms)","trace[1032409091] 'compare' (duration: 281.823714ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:10:50.251593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.684503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-29T04:10:50.252493Z","caller":"traceutil/trace.go:171","msg":"trace[2048293873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15094; }","duration":"385.600965ms","start":"2026-09-29T04:10:49.86684Z","end":"2026-09-29T04:10:50.25244Z","steps":["trace[2048293873] 'agreement among raft nodes before linearized reading' (duration: 384.601941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:10:50.251575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.541056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-29T04:10:50.252556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:10:49.866806Z","time spent":"385.730639ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-29T04:10:50.252593Z","caller":"traceutil/trace.go:171","msg":"trace[996068664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15094; }","duration":"309.590501ms","start":"2026-09-29T04:10:49.942961Z","end":"2026-09-29T04:10:50.252551Z","steps":["trace[996068664] 'agreement among raft nodes before linearized reading' (duration: 308.515295ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:10:50.251669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.994286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-29T04:10:50.252687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:10:49.942939Z","time spent":"309.728544ms","remote":"127.0.0.1:38000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-29T04:10:50.252737Z","caller":"traceutil/trace.go:171","msg":"trace[1282936471] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15094; }","duration":"250.066032ms","start":"2026-09-29T04:10:50.002607Z","end":"2026-09-29T04:10:50.252673Z","steps":["trace[1282936471] 'agreement among raft nodes before linearized reading' (duration: 248.888034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:10:50.25281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:10:49.847635Z","time spent":"404.328105ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:11:01.038183Z","caller":"traceutil/trace.go:171","msg":"trace[2059212925] linearizableReadLoop","detail":"{readStateIndex:15800; appliedIndex:15799; }","duration":"121.090006ms","start":"2026-09-29T04:11:00.916947Z","end":"2026-09-29T04:11:01.038037Z","steps":["trace[2059212925] 'read index received' (duration: 120.925212ms)","trace[2059212925] 'applied index is now lower than readState.Index' (duration: 163.424µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:11:01.038368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.408553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/data-ovn-ovsdb-sb-0.18d9af4a0d8d6a8d\" ","response":"range_response_count:1 size:956"} {"level":"info","ts":"2026-09-29T04:11:01.038442Z","caller":"traceutil/trace.go:171","msg":"trace[436401715] range","detail":"{range_begin:/registry/events/openstack/data-ovn-ovsdb-sb-0.18d9af4a0d8d6a8d; range_end:; response_count:1; response_revision:15269; }","duration":"122.555062ms","start":"2026-09-29T04:11:00.915873Z","end":"2026-09-29T04:11:01.038428Z","steps":["trace[436401715] 'agreement among raft nodes before linearized reading' (duration: 122.321876ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:11:01.038742Z","caller":"traceutil/trace.go:171","msg":"trace[1109248844] transaction","detail":"{read_only:false; response_revision:15269; number_of_response:1; }","duration":"124.711614ms","start":"2026-09-29T04:11:00.913429Z","end":"2026-09-29T04:11:01.03814Z","steps":["trace[1109248844] 'process raft request' (duration: 124.429427ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:11:01.233938Z","caller":"traceutil/trace.go:171","msg":"trace[1717660688] linearizableReadLoop","detail":"{readStateIndex:15801; appliedIndex:15800; }","duration":"195.628896ms","start":"2026-09-29T04:11:01.038274Z","end":"2026-09-29T04:11:01.233903Z","steps":["trace[1717660688] 'read index received' (duration: 114.817666ms)","trace[1717660688] 'applied index is now lower than readState.Index' (duration: 80.808059ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:11:01.23395Z","caller":"traceutil/trace.go:171","msg":"trace[436579508] transaction","detail":"{read_only:false; response_revision:15271; number_of_response:1; }","duration":"192.084861ms","start":"2026-09-29T04:11:01.041833Z","end":"2026-09-29T04:11:01.233917Z","steps":["trace[436579508] 'process raft request' (duration: 191.990969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:11:01.234194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.22675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-29T04:11:01.234246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.96422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T04:11:01.234291Z","caller":"traceutil/trace.go:171","msg":"trace[1876024304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15271; }","duration":"291.385643ms","start":"2026-09-29T04:11:00.942884Z","end":"2026-09-29T04:11:01.23427Z","steps":["trace[1876024304] 'agreement among raft nodes before linearized reading' (duration: 291.167448ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:11:01.234338Z","caller":"traceutil/trace.go:171","msg":"trace[1656020484] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:15271; }","duration":"180.108183ms","start":"2026-09-29T04:11:01.05421Z","end":"2026-09-29T04:11:01.234318Z","steps":["trace[1656020484] 'agreement among raft nodes before linearized reading' (duration: 179.95821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:11:01.234581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.57841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rjs97\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-29T04:11:01.234588Z","caller":"traceutil/trace.go:171","msg":"trace[177518765] transaction","detail":"{read_only:false; response_revision:15270; number_of_response:1; }","duration":"205.170095ms","start":"2026-09-29T04:11:01.028696Z","end":"2026-09-29T04:11:01.233866Z","steps":["trace[177518765] 'process raft request' (duration: 124.270523ms)","trace[177518765] 'compare' (duration: 80.669976ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:11:01.234653Z","caller":"traceutil/trace.go:171","msg":"trace[955859011] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rjs97; range_end:; response_count:1; response_revision:15271; }","duration":"154.664232ms","start":"2026-09-29T04:11:01.079972Z","end":"2026-09-29T04:11:01.234636Z","steps":["trace[955859011] 'agreement among raft nodes before linearized reading' (duration: 154.450137ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:12:43.048775Z","caller":"traceutil/trace.go:171","msg":"trace[123941077] transaction","detail":"{read_only:false; response_revision:16365; number_of_response:1; }","duration":"118.32416ms","start":"2026-09-29T04:12:42.930381Z","end":"2026-09-29T04:12:43.048705Z","steps":["trace[123941077] 'process raft request' (duration: 118.111765ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:12:48.388924Z","caller":"traceutil/trace.go:171","msg":"trace[524796122] linearizableReadLoop","detail":"{readStateIndex:16958; appliedIndex:16957; }","duration":"105.531233ms","start":"2026-09-29T04:12:48.283352Z","end":"2026-09-29T04:12:48.388884Z","steps":["trace[524796122] 'read index received' (duration: 97.967641ms)","trace[524796122] 'applied index is now lower than readState.Index' (duration: 7.562192ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:12:48.38889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.017355ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-29T04:12:48.388982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.910103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:12:48.389044Z","caller":"traceutil/trace.go:171","msg":"trace[1291445297] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:16394; }","duration":"131.200909ms","start":"2026-09-29T04:12:48.257821Z","end":"2026-09-29T04:12:48.389021Z","steps":["trace[1291445297] 'range keys from in-memory index tree' (duration: 130.976793ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:12:48.389054Z","caller":"traceutil/trace.go:171","msg":"trace[1790705317] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:16394; }","duration":"129.033466ms","start":"2026-09-29T04:12:48.260002Z","end":"2026-09-29T04:12:48.389035Z","steps":["trace[1790705317] 'count revisions from in-memory index tree' (duration: 128.75471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:12:48.389157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.783808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-09-29T04:12:48.389246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.061735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-29T04:12:48.389253Z","caller":"traceutil/trace.go:171","msg":"trace[530083726] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16395; }","duration":"105.908891ms","start":"2026-09-29T04:12:48.283324Z","end":"2026-09-29T04:12:48.389233Z","steps":["trace[530083726] 'agreement among raft nodes before linearized reading' (duration: 105.661795ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:12:48.389288Z","caller":"traceutil/trace.go:171","msg":"trace[853444661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16395; }","duration":"101.123877ms","start":"2026-09-29T04:12:48.288152Z","end":"2026-09-29T04:12:48.389276Z","steps":["trace[853444661] 'agreement among raft nodes before linearized reading' (duration: 100.919462ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:12:48.389585Z","caller":"traceutil/trace.go:171","msg":"trace[1164007439] transaction","detail":"{read_only:false; response_revision:16395; number_of_response:1; }","duration":"128.897013ms","start":"2026-09-29T04:12:48.260676Z","end":"2026-09-29T04:12:48.389573Z","steps":["trace[1164007439] 'process raft request' (duration: 120.780378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:12:48.390373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.672532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:771204"} {"level":"info","ts":"2026-09-29T04:12:48.390561Z","caller":"traceutil/trace.go:171","msg":"trace[291179756] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:16394; }","duration":"149.886047ms","start":"2026-09-29T04:12:48.240645Z","end":"2026-09-29T04:12:48.390531Z","steps":["trace[291179756] 'range keys from in-memory index tree' (duration: 147.89301ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:12:49.232912Z","caller":"traceutil/trace.go:171","msg":"trace[340077508] linearizableReadLoop","detail":"{readStateIndex:16960; appliedIndex:16959; }","duration":"110.504282ms","start":"2026-09-29T04:12:49.122385Z","end":"2026-09-29T04:12:49.232889Z","steps":["trace[340077508] 'read index received' (duration: 110.294707ms)","trace[340077508] 'applied index is now lower than readState.Index' (duration: 208.815µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:12:49.233227Z","caller":"traceutil/trace.go:171","msg":"trace[295628549] transaction","detail":"{read_only:false; response_revision:16397; number_of_response:1; }","duration":"154.561399ms","start":"2026-09-29T04:12:49.078654Z","end":"2026-09-29T04:12:49.233215Z","steps":["trace[295628549] 'process raft request' (duration: 154.101208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:12:49.2334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.987944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:12:49.23349Z","caller":"traceutil/trace.go:171","msg":"trace[1881120640] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:16397; }","duration":"111.134977ms","start":"2026-09-29T04:12:49.122337Z","end":"2026-09-29T04:12:49.233472Z","steps":["trace[1881120640] 'agreement among raft nodes before linearized reading' (duration: 110.911332ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:14:35.624413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14616} {"level":"info","ts":"2026-09-29T04:14:35.710496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14616,"took":"82.609962ms","hash":525356893,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":31809536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T04:14:35.710584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":525356893,"revision":14616,"compact-revision":12580} {"level":"info","ts":"2026-09-29T04:18:28.033222Z","caller":"traceutil/trace.go:171","msg":"trace[1327459530] transaction","detail":"{read_only:false; response_revision:18896; number_of_response:1; }","duration":"106.308214ms","start":"2026-09-29T04:18:27.926873Z","end":"2026-09-29T04:18:28.033181Z","steps":["trace[1327459530] 'process raft request' (duration: 106.004787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:18:28.265974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.935154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-29T04:18:28.26639Z","caller":"traceutil/trace.go:171","msg":"trace[451752523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18896; }","duration":"155.411034ms","start":"2026-09-29T04:18:28.110955Z","end":"2026-09-29T04:18:28.266366Z","steps":["trace[451752523] 'range keys from in-memory index tree' (duration: 154.751739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:18:28.266029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.312488ms","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-29T04:18:28.266685Z","caller":"traceutil/trace.go:171","msg":"trace[64529833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18896; }","duration":"119.971093ms","start":"2026-09-29T04:18:28.146667Z","end":"2026-09-29T04:18:28.266638Z","steps":["trace[64529833] 'range keys from in-memory index tree' (duration: 119.122622ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:18:28.505663Z","caller":"traceutil/trace.go:171","msg":"trace[1910282912] transaction","detail":"{read_only:false; response_revision:18897; number_of_response:1; }","duration":"233.989653ms","start":"2026-09-29T04:18:28.271644Z","end":"2026-09-29T04:18:28.505634Z","steps":["trace[1910282912] 'process raft request' (duration: 233.8457ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:18:28.564716Z","caller":"traceutil/trace.go:171","msg":"trace[1139593517] linearizableReadLoop","detail":"{readStateIndex:19546; appliedIndex:19544; }","duration":"286.123056ms","start":"2026-09-29T04:18:28.278569Z","end":"2026-09-29T04:18:28.564692Z","steps":["trace[1139593517] 'read index received' (duration: 226.914523ms)","trace[1139593517] 'applied index is now lower than readState.Index' (duration: 59.207363ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:18:28.564983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.402513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lvwj9\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-09-29T04:18:28.565065Z","caller":"traceutil/trace.go:171","msg":"trace[527552148] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lvwj9; range_end:; response_count:1; response_revision:18898; }","duration":"286.521545ms","start":"2026-09-29T04:18:28.278531Z","end":"2026-09-29T04:18:28.565053Z","steps":["trace[527552148] 'agreement among raft nodes before linearized reading' (duration: 286.3047ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:18:28.564977Z","caller":"traceutil/trace.go:171","msg":"trace[179052145] transaction","detail":"{read_only:false; response_revision:18898; number_of_response:1; }","duration":"287.605742ms","start":"2026-09-29T04:18:28.277337Z","end":"2026-09-29T04:18:28.564943Z","steps":["trace[179052145] 'process raft request' (duration: 287.219442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:18:28.836094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.267001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:941306"} {"level":"info","ts":"2026-09-29T04:18:28.836193Z","caller":"traceutil/trace.go:171","msg":"trace[1891169681] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:18898; }","duration":"264.383424ms","start":"2026-09-29T04:18:28.571791Z","end":"2026-09-29T04:18:28.836175Z","steps":["trace[1891169681] 'range keys from in-memory index tree' (duration: 263.102643ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:19:12.594774Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-29T04:19:12.601611Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-29T04:19:12.601772Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-29T04:19:35.631847Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17075} {"level":"info","ts":"2026-09-29T04:19:35.741194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17075,"took":"100.72592ms","hash":726807830,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":34144256,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-29T04:19:35.741278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":726807830,"revision":17075,"compact-revision":14616} {"level":"info","ts":"2026-09-29T04:24:35.639775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19682} {"level":"info","ts":"2026-09-29T04:24:35.76063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19682,"took":"117.136955ms","hash":605812809,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":30126080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-29T04:24:35.760709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":605812809,"revision":19682,"compact-revision":17075} {"level":"info","ts":"2026-09-29T04:25:03.625345Z","caller":"traceutil/trace.go:171","msg":"trace[1534188241] transaction","detail":"{read_only:false; response_revision:21867; number_of_response:1; }","duration":"120.218196ms","start":"2026-09-29T04:25:03.505072Z","end":"2026-09-29T04:25:03.62529Z","steps":["trace[1534188241] 'process raft request' (duration: 119.771316ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:25:04.770585Z","caller":"traceutil/trace.go:171","msg":"trace[451472609] linearizableReadLoop","detail":"{readStateIndex:22617; appliedIndex:22616; }","duration":"318.384963ms","start":"2026-09-29T04:25:04.452162Z","end":"2026-09-29T04:25:04.770547Z","steps":["trace[451472609] 'read index received' (duration: 318.174278ms)","trace[451472609] 'applied index is now lower than readState.Index' (duration: 209.085µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:25:04.770991Z","caller":"traceutil/trace.go:171","msg":"trace[1828585858] transaction","detail":"{read_only:false; response_revision:21869; number_of_response:1; }","duration":"364.2755ms","start":"2026-09-29T04:25:04.406701Z","end":"2026-09-29T04:25:04.770976Z","steps":["trace[1828585858] 'process raft request' (duration: 363.639066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:25:04.771161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:25:04.406676Z","time spent":"364.356303ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:25:04.771471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.303913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:25:04.771514Z","caller":"traceutil/trace.go:171","msg":"trace[473176197] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:21869; }","duration":"319.401135ms","start":"2026-09-29T04:25:04.452102Z","end":"2026-09-29T04:25:04.771503Z","steps":["trace[473176197] 'agreement among raft nodes before linearized reading' (duration: 319.299573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:25:04.771553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:25:04.45207Z","time spent":"319.474367ms","remote":"127.0.0.1:38516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-09-29T04:25:05.000432Z","caller":"traceutil/trace.go:171","msg":"trace[1397617267] transaction","detail":"{read_only:false; response_revision:21870; number_of_response:1; }","duration":"422.657744ms","start":"2026-09-29T04:25:04.577749Z","end":"2026-09-29T04:25:05.000406Z","steps":["trace[1397617267] 'process raft request' (duration: 414.911537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:25:05.000583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:25:04.57772Z","time spent":"422.781317ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:25:05.226902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.030756ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-29T04:25:05.227349Z","caller":"traceutil/trace.go:171","msg":"trace[1097856116] transaction","detail":"{read_only:false; response_revision:21871; number_of_response:1; }","duration":"470.333933ms","start":"2026-09-29T04:25:04.756976Z","end":"2026-09-29T04:25:05.22731Z","steps":["trace[1097856116] 'process raft request' (duration: 470.051207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:25:05.227535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:25:04.756963Z","time spent":"470.479187ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:25:05.288357Z","caller":"traceutil/trace.go:171","msg":"trace[1713213437] linearizableReadLoop","detail":"{readStateIndex:22621; appliedIndex:22617; }","duration":"517.649524ms","start":"2026-09-29T04:25:04.770678Z","end":"2026-09-29T04:25:05.288328Z","steps":["trace[1713213437] 'read index received' (duration: 221.906949ms)","trace[1713213437] 'applied index is now lower than readState.Index' (duration: 295.741475ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:25:05.288397Z","caller":"traceutil/trace.go:171","msg":"trace[935011752] transaction","detail":"{read_only:false; response_revision:21872; number_of_response:1; }","duration":"529.928465ms","start":"2026-09-29T04:25:04.758434Z","end":"2026-09-29T04:25:05.288362Z","steps":["trace[935011752] 'process raft request' (duration: 468.85802ms)","trace[935011752] 'compare' (duration: 60.916421ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:25:05.288551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:25:04.758409Z","time spent":"530.038198ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:25:05.288578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.538538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-29T04:25:05.288612Z","caller":"traceutil/trace.go:171","msg":"trace[1167986300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21872; }","duration":"558.63391ms","start":"2026-09-29T04:25:04.729967Z","end":"2026-09-29T04:25:05.2886Z","steps":["trace[1167986300] 'agreement among raft nodes before linearized reading' (duration: 558.506257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:25:05.288647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:25:04.729937Z","time spent":"558.702872ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-29T04:25:05.288734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.317888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:25:05.288826Z","caller":"traceutil/trace.go:171","msg":"trace[1557296438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21872; }","duration":"345.462711ms","start":"2026-09-29T04:25:04.943341Z","end":"2026-09-29T04:25:05.288804Z","steps":["trace[1557296438] 'agreement among raft nodes before linearized reading' (duration: 345.321088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:25:05.288869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:25:04.943311Z","time spent":"345.550073ms","remote":"127.0.0.1:38000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-29T04:25:05.288804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.25611ms","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-29T04:25:05.289032Z","caller":"traceutil/trace.go:171","msg":"trace[1521091564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21872; }","duration":"540.520047ms","start":"2026-09-29T04:25:04.748492Z","end":"2026-09-29T04:25:05.289012Z","steps":["trace[1521091564] 'agreement among raft nodes before linearized reading' (duration: 539.889032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:25:05.289278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:25:04.74845Z","time spent":"540.812903ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-29T04:25:05.462019Z","caller":"traceutil/trace.go:171","msg":"trace[1628020687] transaction","detail":"{read_only:false; response_revision:21875; number_of_response:1; }","duration":"167.122138ms","start":"2026-09-29T04:25:05.294876Z","end":"2026-09-29T04:25:05.461998Z","steps":["trace[1628020687] 'process raft request' (duration: 167.031656ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:25:05.462128Z","caller":"traceutil/trace.go:171","msg":"trace[1011035897] transaction","detail":"{read_only:false; response_revision:21874; number_of_response:1; }","duration":"167.24171ms","start":"2026-09-29T04:25:05.294756Z","end":"2026-09-29T04:25:05.461997Z","steps":["trace[1011035897] 'process raft request' (duration: 167.048385ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:25:05.462389Z","caller":"traceutil/trace.go:171","msg":"trace[319681129] transaction","detail":"{read_only:false; response_revision:21876; number_of_response:1; }","duration":"167.179729ms","start":"2026-09-29T04:25:05.295173Z","end":"2026-09-29T04:25:05.462352Z","steps":["trace[319681129] 'process raft request' (duration: 166.78467ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:25:05.46249Z","caller":"traceutil/trace.go:171","msg":"trace[319006320] linearizableReadLoop","detail":"{readStateIndex:22625; appliedIndex:22622; }","duration":"154.221072ms","start":"2026-09-29T04:25:05.308066Z","end":"2026-09-29T04:25:05.462287Z","steps":["trace[319006320] 'read index received' (duration: 72.66002ms)","trace[319006320] 'applied index is now lower than readState.Index' (duration: 81.558712ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:25:05.462601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.53896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:25:05.462669Z","caller":"traceutil/trace.go:171","msg":"trace[1080443581] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:21876; }","duration":"154.646913ms","start":"2026-09-29T04:25:05.308005Z","end":"2026-09-29T04:25:05.462652Z","steps":["trace[1080443581] 'agreement among raft nodes before linearized reading' (duration: 154.52083ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:29:35.262563Z","caller":"traceutil/trace.go:171","msg":"trace[1953466089] transaction","detail":"{read_only:false; response_revision:24515; number_of_response:1; }","duration":"113.550384ms","start":"2026-09-29T04:29:35.148973Z","end":"2026-09-29T04:29:35.262523Z","steps":["trace[1953466089] 'process raft request' (duration: 113.294428ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:29:35.411313Z","caller":"traceutil/trace.go:171","msg":"trace[154752776] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"258.660349ms","start":"2026-09-29T04:29:35.152609Z","end":"2026-09-29T04:29:35.41127Z","steps":["trace[154752776] 'process raft request' (duration: 246.276238ms)","trace[154752776] 'compare' (duration: 12.089124ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:29:35.646854Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21710} {"level":"info","ts":"2026-09-29T04:29:35.755914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21710,"took":"105.863065ms","hash":416394065,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":41799680,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-29T04:29:35.755989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":416394065,"revision":21710,"compact-revision":19682} {"level":"warn","ts":"2026-09-29T04:31:20.294828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.87945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-09-29T04:31:20.294919Z","caller":"traceutil/trace.go:171","msg":"trace[1594955245] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25593; }","duration":"102.023724ms","start":"2026-09-29T04:31:20.192875Z","end":"2026-09-29T04:31:20.294899Z","steps":["trace[1594955245] 'range keys from in-memory index tree' (duration: 101.715346ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:31:24.39392Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006775.wal"} {"level":"info","ts":"2026-09-29T04:33:31.527106Z","caller":"traceutil/trace.go:171","msg":"trace[1116633904] transaction","detail":"{read_only:false; response_revision:26816; number_of_response:1; }","duration":"111.344672ms","start":"2026-09-29T04:33:31.415738Z","end":"2026-09-29T04:33:31.527083Z","steps":["trace[1116633904] 'process raft request' (duration: 111.152717ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:31.527444Z","caller":"traceutil/trace.go:171","msg":"trace[1253882247] transaction","detail":"{read_only:false; response_revision:26817; number_of_response:1; }","duration":"105.951514ms","start":"2026-09-29T04:33:31.421461Z","end":"2026-09-29T04:33:31.527413Z","steps":["trace[1253882247] 'process raft request' (duration: 105.573835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:31.929782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.900103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:33:31.93006Z","caller":"traceutil/trace.go:171","msg":"trace[849280430] transaction","detail":"{read_only:false; response_revision:26822; number_of_response:1; }","duration":"166.088041ms","start":"2026-09-29T04:33:31.763955Z","end":"2026-09-29T04:33:31.930043Z","steps":["trace[849280430] 'process raft request' (duration: 166.022739ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:31.930476Z","caller":"traceutil/trace.go:171","msg":"trace[1249497127] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"372.690442ms","start":"2026-09-29T04:33:31.557768Z","end":"2026-09-29T04:33:31.930458Z","steps":["trace[1249497127] 'process raft request' (duration: 137.00921ms)","trace[1249497127] 'compare' (duration: 234.206697ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:33:31.930567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:33:31.557749Z","time spent":"372.772234ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:33:31.93072Z","caller":"traceutil/trace.go:171","msg":"trace[1642076602] transaction","detail":"{read_only:false; response_revision:26820; number_of_response:1; }","duration":"341.053742ms","start":"2026-09-29T04:33:31.589654Z","end":"2026-09-29T04:33:31.930707Z","steps":["trace[1642076602] 'process raft request' (duration: 340.226392ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:31.930779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:33:31.58963Z","time spent":"341.119103ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:33:31.930929Z","caller":"traceutil/trace.go:171","msg":"trace[1376618215] transaction","detail":"{read_only:false; response_revision:26821; number_of_response:1; }","duration":"293.352709ms","start":"2026-09-29T04:33:31.637561Z","end":"2026-09-29T04:33:31.930914Z","steps":["trace[1376618215] 'process raft request' (duration: 292.378756ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:32.503479Z","caller":"traceutil/trace.go:171","msg":"trace[1904261781] linearizableReadLoop","detail":"{readStateIndex:27713; appliedIndex:27712; }","duration":"154.729341ms","start":"2026-09-29T04:33:32.348717Z","end":"2026-09-29T04:33:32.503447Z","steps":["trace[1904261781] 'read index received' (duration: 154.522996ms)","trace[1904261781] 'applied index is now lower than readState.Index' (duration: 205.285µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:32.503611Z","caller":"traceutil/trace.go:171","msg":"trace[1012411595] transaction","detail":"{read_only:false; response_revision:26823; number_of_response:1; }","duration":"218.29075ms","start":"2026-09-29T04:33:32.28531Z","end":"2026-09-29T04:33:32.503601Z","steps":["trace[1012411595] 'process raft request' (duration: 217.942991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:32.503908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.179481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-29T04:33:32.503939Z","caller":"traceutil/trace.go:171","msg":"trace[1621159396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26823; }","duration":"155.275574ms","start":"2026-09-29T04:33:32.348655Z","end":"2026-09-29T04:33:32.503931Z","steps":["trace[1621159396] 'agreement among raft nodes before linearized reading' (duration: 155.102679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:32.50416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.265589ms","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-29T04:33:32.50421Z","caller":"traceutil/trace.go:171","msg":"trace[685302315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26823; }","duration":"143.334481ms","start":"2026-09-29T04:33:32.360863Z","end":"2026-09-29T04:33:32.504198Z","steps":["trace[685302315] 'agreement among raft nodes before linearized reading' (duration: 143.250379ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:32.686449Z","caller":"traceutil/trace.go:171","msg":"trace[456624120] transaction","detail":"{read_only:false; response_revision:26825; number_of_response:1; }","duration":"177.00276ms","start":"2026-09-29T04:33:32.509403Z","end":"2026-09-29T04:33:32.686406Z","steps":["trace[456624120] 'process raft request' (duration: 161.063572ms)","trace[456624120] 'compare' (duration: 15.172239ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:32.844566Z","caller":"traceutil/trace.go:171","msg":"trace[369256421] transaction","detail":"{read_only:false; response_revision:26828; number_of_response:1; }","duration":"153.102432ms","start":"2026-09-29T04:33:32.691406Z","end":"2026-09-29T04:33:32.844508Z","steps":["trace[369256421] 'process raft request' (duration: 135.248559ms)","trace[369256421] 'compare' (duration: 17.635948ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:33:33.083649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.913814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:33:33.084146Z","caller":"traceutil/trace.go:171","msg":"trace[940186305] transaction","detail":"{read_only:false; response_revision:26829; number_of_response:1; }","duration":"234.780651ms","start":"2026-09-29T04:33:32.849344Z","end":"2026-09-29T04:33:33.084125Z","steps":["trace[940186305] 'process raft request' (duration: 125.315443ms)","trace[940186305] 'compare' (duration: 108.595727ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:33.084389Z","caller":"traceutil/trace.go:171","msg":"trace[1070464554] linearizableReadLoop","detail":"{readStateIndex:27719; appliedIndex:27718; }","duration":"139.952761ms","start":"2026-09-29T04:33:32.944424Z","end":"2026-09-29T04:33:33.084376Z","steps":["trace[1070464554] 'read index received' (duration: 30.152985ms)","trace[1070464554] 'applied index is now lower than readState.Index' (duration: 109.798456ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:33:33.084453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.027942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:33.084478Z","caller":"traceutil/trace.go:171","msg":"trace[541033038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26830; }","duration":"140.107695ms","start":"2026-09-29T04:33:32.944361Z","end":"2026-09-29T04:33:33.084469Z","steps":["trace[541033038] 'agreement among raft nodes before linearized reading' (duration: 140.059704ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:33.397024Z","caller":"traceutil/trace.go:171","msg":"trace[1613381723] transaction","detail":"{read_only:false; response_revision:26831; number_of_response:1; }","duration":"147.351286ms","start":"2026-09-29T04:33:33.249623Z","end":"2026-09-29T04:33:33.396975Z","steps":["trace[1613381723] 'process raft request' (duration: 147.180202ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:33.829058Z","caller":"traceutil/trace.go:171","msg":"trace[949259239] linearizableReadLoop","detail":"{readStateIndex:27725; appliedIndex:27723; }","duration":"223.81255ms","start":"2026-09-29T04:33:33.605225Z","end":"2026-09-29T04:33:33.829038Z","steps":["trace[949259239] 'read index received' (duration: 223.584065ms)","trace[949259239] 'applied index is now lower than readState.Index' (duration: 227.855µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:33.829299Z","caller":"traceutil/trace.go:171","msg":"trace[1525995458] transaction","detail":"{read_only:false; response_revision:26834; number_of_response:1; }","duration":"293.691058ms","start":"2026-09-29T04:33:33.535599Z","end":"2026-09-29T04:33:33.82929Z","steps":["trace[1525995458] 'process raft request' (duration: 293.272608ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:33.829403Z","caller":"traceutil/trace.go:171","msg":"trace[377338407] transaction","detail":"{read_only:false; response_revision:26835; number_of_response:1; }","duration":"293.724659ms","start":"2026-09-29T04:33:33.535673Z","end":"2026-09-29T04:33:33.829398Z","steps":["trace[377338407] 'process raft request' (duration: 293.33993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:33.8295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.275581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:33.829523Z","caller":"traceutil/trace.go:171","msg":"trace[876228410] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26835; }","duration":"224.348843ms","start":"2026-09-29T04:33:33.605168Z","end":"2026-09-29T04:33:33.829517Z","steps":["trace[876228410] 'agreement among raft nodes before linearized reading' (duration: 224.280511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:33.829609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.578747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:33.82963Z","caller":"traceutil/trace.go:171","msg":"trace[1323469018] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26835; }","duration":"178.661539ms","start":"2026-09-29T04:33:33.650957Z","end":"2026-09-29T04:33:33.829619Z","steps":["trace[1323469018] 'agreement among raft nodes before linearized reading' (duration: 178.631898ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:34.052114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.287244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-09-29T04:33:34.052145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.219942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-29T04:33:34.05221Z","caller":"traceutil/trace.go:171","msg":"trace[1051144065] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26835; }","duration":"187.427767ms","start":"2026-09-29T04:33:33.864757Z","end":"2026-09-29T04:33:34.052184Z","steps":["trace[1051144065] 'range keys from in-memory index tree' (duration: 187.023507ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:34.052238Z","caller":"traceutil/trace.go:171","msg":"trace[2071516695] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26835; }","duration":"155.328925ms","start":"2026-09-29T04:33:33.896881Z","end":"2026-09-29T04:33:34.052209Z","steps":["trace[2071516695] 'count revisions from in-memory index tree' (duration: 155.149031ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:34.055758Z","caller":"traceutil/trace.go:171","msg":"trace[697074498] transaction","detail":"{read_only:false; response_revision:26836; number_of_response:1; }","duration":"117.988069ms","start":"2026-09-29T04:33:33.937748Z","end":"2026-09-29T04:33:34.055737Z","steps":["trace[697074498] 'process raft request' (duration: 117.834226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:37.26783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.188319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:33:37.26793Z","caller":"traceutil/trace.go:171","msg":"trace[977276528] linearizableReadLoop","detail":"{readStateIndex:27752; appliedIndex:27751; }","duration":"164.374429ms","start":"2026-09-29T04:33:37.103538Z","end":"2026-09-29T04:33:37.267912Z","steps":["trace[977276528] 'read index received' (duration: 15.078388ms)","trace[977276528] 'applied index is now lower than readState.Index' (duration: 149.294541ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:33:37.268116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.574814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-29T04:33:37.268152Z","caller":"traceutil/trace.go:171","msg":"trace[2146229937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26861; }","duration":"164.641666ms","start":"2026-09-29T04:33:37.103501Z","end":"2026-09-29T04:33:37.268143Z","steps":["trace[2146229937] 'agreement among raft nodes before linearized reading' (duration: 164.454812ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:37.268438Z","caller":"traceutil/trace.go:171","msg":"trace[1642041728] transaction","detail":"{read_only:false; response_revision:26861; number_of_response:1; }","duration":"172.075463ms","start":"2026-09-29T04:33:37.096345Z","end":"2026-09-29T04:33:37.268421Z","steps":["trace[1642041728] 'process raft request' (duration: 22.207797ms)","trace[1642041728] 'compare' (duration: 148.720678ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:37.388843Z","caller":"traceutil/trace.go:171","msg":"trace[1094766717] transaction","detail":"{read_only:false; response_revision:26862; number_of_response:1; }","duration":"115.763547ms","start":"2026-09-29T04:33:37.273053Z","end":"2026-09-29T04:33:37.388816Z","steps":["trace[1094766717] 'process raft request' (duration: 102.39769ms)","trace[1094766717] 'compare' (duration: 12.821144ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:33:37.733236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.218551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:33:37.733377Z","caller":"traceutil/trace.go:171","msg":"trace[262450298] linearizableReadLoop","detail":"{readStateIndex:27755; appliedIndex:27754; }","duration":"177.45916ms","start":"2026-09-29T04:33:37.555897Z","end":"2026-09-29T04:33:37.733356Z","steps":["trace[262450298] 'read index received' (duration: 24.957072ms)","trace[262450298] 'applied index is now lower than readState.Index' (duration: 152.499938ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:37.733489Z","caller":"traceutil/trace.go:171","msg":"trace[1682252188] transaction","detail":"{read_only:false; response_revision:26864; number_of_response:1; }","duration":"200.373944ms","start":"2026-09-29T04:33:37.533066Z","end":"2026-09-29T04:33:37.73344Z","steps":["trace[1682252188] 'process raft request' (duration: 47.832235ms)","trace[1682252188] 'compare' (duration: 151.931405ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:33:37.733682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.783687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-29T04:33:37.733722Z","caller":"traceutil/trace.go:171","msg":"trace[907040402] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26864; }","duration":"177.86386ms","start":"2026-09-29T04:33:37.555846Z","end":"2026-09-29T04:33:37.73371Z","steps":["trace[907040402] 'agreement among raft nodes before linearized reading' (duration: 177.567603ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:37.753925Z","caller":"traceutil/trace.go:171","msg":"trace[1400201639] transaction","detail":"{read_only:false; response_revision:26865; number_of_response:1; }","duration":"144.615941ms","start":"2026-09-29T04:33:37.609279Z","end":"2026-09-29T04:33:37.753895Z","steps":["trace[1400201639] 'process raft request' (duration: 144.475548ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:49.684124Z","caller":"traceutil/trace.go:171","msg":"trace[1026452802] transaction","detail":"{read_only:false; response_revision:26952; number_of_response:1; }","duration":"146.441484ms","start":"2026-09-29T04:33:49.53765Z","end":"2026-09-29T04:33:49.684092Z","steps":["trace[1026452802] 'process raft request' (duration: 146.24825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:55.320715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.201749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:55.320828Z","caller":"traceutil/trace.go:171","msg":"trace[184479223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26990; }","duration":"377.374573ms","start":"2026-09-29T04:33:54.943433Z","end":"2026-09-29T04:33:55.320807Z","steps":["trace[184479223] 'range keys from in-memory index tree' (duration: 377.114517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:55.320876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:33:54.943412Z","time spent":"377.453375ms","remote":"127.0.0.1:38000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-29T04:33:55.320856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.196076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:55.321004Z","caller":"traceutil/trace.go:171","msg":"trace[2116282399] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:26990; }","duration":"258.37634ms","start":"2026-09-29T04:33:55.062574Z","end":"2026-09-29T04:33:55.32095Z","steps":["trace[2116282399] 'count revisions from in-memory index tree' (duration: 258.128924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:55.32103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.301498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:55.321132Z","caller":"traceutil/trace.go:171","msg":"trace[1257626488] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26990; }","duration":"360.477832ms","start":"2026-09-29T04:33:54.960627Z","end":"2026-09-29T04:33:55.321104Z","steps":["trace[1257626488] 'range keys from in-memory index tree' (duration: 360.239877ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:55.321203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:33:54.960609Z","time spent":"360.578395ms","remote":"127.0.0.1:50176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-09-29T04:33:55.321446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.902151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-29T04:33:55.32153Z","caller":"traceutil/trace.go:171","msg":"trace[1871144671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26990; }","duration":"342.021515ms","start":"2026-09-29T04:33:54.979488Z","end":"2026-09-29T04:33:55.32151Z","steps":["trace[1871144671] 'range keys from in-memory index tree' (duration: 341.716437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:55.321568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:33:54.979477Z","time spent":"342.081836ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-29T04:33:55.322676Z","caller":"traceutil/trace.go:171","msg":"trace[1746493142] transaction","detail":"{read_only:false; response_revision:26991; number_of_response:1; }","duration":"205.652569ms","start":"2026-09-29T04:33:55.117004Z","end":"2026-09-29T04:33:55.322656Z","steps":["trace[1746493142] 'process raft request' (duration: 205.532476ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:55.620781Z","caller":"traceutil/trace.go:171","msg":"trace[1146463265] linearizableReadLoop","detail":"{readStateIndex:27888; appliedIndex:27887; }","duration":"131.901609ms","start":"2026-09-29T04:33:55.488854Z","end":"2026-09-29T04:33:55.620756Z","steps":["trace[1146463265] 'read index received' (duration: 131.625302ms)","trace[1146463265] 'applied index is now lower than readState.Index' (duration: 274.977µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:55.620885Z","caller":"traceutil/trace.go:171","msg":"trace[1188025208] transaction","detail":"{read_only:false; response_revision:26993; number_of_response:1; }","duration":"270.18779ms","start":"2026-09-29T04:33:55.350656Z","end":"2026-09-29T04:33:55.620844Z","steps":["trace[1188025208] 'process raft request' (duration: 269.897453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:55.621024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.154444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-29T04:33:55.621079Z","caller":"traceutil/trace.go:171","msg":"trace[1513697985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26993; }","duration":"132.265377ms","start":"2026-09-29T04:33:55.488799Z","end":"2026-09-29T04:33:55.621065Z","steps":["trace[1513697985] 'agreement among raft nodes before linearized reading' (duration: 132.053322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.02068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.455173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:33:56.020991Z","caller":"traceutil/trace.go:171","msg":"trace[2140767497] transaction","detail":"{read_only:false; response_revision:26997; number_of_response:1; }","duration":"174.726786ms","start":"2026-09-29T04:33:55.846223Z","end":"2026-09-29T04:33:56.02095Z","steps":["trace[2140767497] 'process raft request' (duration: 174.686245ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:56.021118Z","caller":"traceutil/trace.go:171","msg":"trace[1234080369] transaction","detail":"{read_only:false; response_revision:26995; number_of_response:1; }","duration":"314.075551ms","start":"2026-09-29T04:33:55.707034Z","end":"2026-09-29T04:33:56.021109Z","steps":["trace[1234080369] 'process raft request' (duration: 313.747334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.021177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:33:55.707005Z","time spent":"314.132453ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:33:56.021183Z","caller":"traceutil/trace.go:171","msg":"trace[312877628] linearizableReadLoop","detail":"{readStateIndex:27889; appliedIndex:27888; }","duration":"342.417204ms","start":"2026-09-29T04:33:55.678736Z","end":"2026-09-29T04:33:56.021153Z","steps":["trace[312877628] 'read index received' (duration: 21.313185ms)","trace[312877628] 'applied index is now lower than readState.Index' (duration: 321.101468ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:56.021194Z","caller":"traceutil/trace.go:171","msg":"trace[875712242] transaction","detail":"{read_only:false; response_revision:26996; number_of_response:1; }","duration":"207.897853ms","start":"2026-09-29T04:33:55.813261Z","end":"2026-09-29T04:33:56.021159Z","steps":["trace[875712242] 'process raft request' (duration: 207.608476ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:56.021225Z","caller":"traceutil/trace.go:171","msg":"trace[1027737281] transaction","detail":"{read_only:false; response_revision:26994; number_of_response:1; }","duration":"391.97937ms","start":"2026-09-29T04:33:55.629211Z","end":"2026-09-29T04:33:56.02119Z","steps":["trace[1027737281] 'process raft request' (duration: 70.918213ms)","trace[1027737281] 'compare' (duration: 320.209017ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:33:56.021324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:33:55.629189Z","time spent":"392.083892ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:33:56.021355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.600788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:33:56.021467Z","caller":"traceutil/trace.go:171","msg":"trace[286520754] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:26997; }","duration":"342.763252ms","start":"2026-09-29T04:33:55.678681Z","end":"2026-09-29T04:33:56.021445Z","steps":["trace[286520754] 'agreement among raft nodes before linearized reading' (duration: 342.573428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.021574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:33:55.678651Z","time spent":"342.907605ms","remote":"127.0.0.1:51954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":32,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"info","ts":"2026-09-29T04:33:56.044448Z","caller":"traceutil/trace.go:171","msg":"trace[928719763] transaction","detail":"{read_only:false; response_revision:26998; number_of_response:1; }","duration":"100.284519ms","start":"2026-09-29T04:33:55.94414Z","end":"2026-09-29T04:33:56.044424Z","steps":["trace[928719763] 'process raft request' (duration: 100.105334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.044686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.321853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-29T04:33:56.044703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.020535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:56.044726Z","caller":"traceutil/trace.go:171","msg":"trace[1225313505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26999; }","duration":"101.409085ms","start":"2026-09-29T04:33:55.943306Z","end":"2026-09-29T04:33:56.044715Z","steps":["trace[1225313505] 'agreement among raft nodes before linearized reading' (duration: 101.295383ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:56.044772Z","caller":"traceutil/trace.go:171","msg":"trace[426410343] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26999; }","duration":"259.142978ms","start":"2026-09-29T04:33:55.785616Z","end":"2026-09-29T04:33:56.044759Z","steps":["trace[426410343] 'agreement among raft nodes before linearized reading' (duration: 258.987744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.044817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.660525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:56.044886Z","caller":"traceutil/trace.go:171","msg":"trace[1743338946] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:26999; }","duration":"102.774627ms","start":"2026-09-29T04:33:55.942095Z","end":"2026-09-29T04:33:56.04487Z","steps":["trace[1743338946] 'agreement among raft nodes before linearized reading' (duration: 102.656574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.238437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.990852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-29T04:33:56.238589Z","caller":"traceutil/trace.go:171","msg":"trace[1332782906] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:26999; }","duration":"116.193827ms","start":"2026-09-29T04:33:56.122374Z","end":"2026-09-29T04:33:56.238568Z","steps":["trace[1332782906] 'count revisions from in-memory index tree' (duration: 115.840418ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:56.83113Z","caller":"traceutil/trace.go:171","msg":"trace[559265553] linearizableReadLoop","detail":"{readStateIndex:27896; appliedIndex:27895; }","duration":"145.357548ms","start":"2026-09-29T04:33:56.68575Z","end":"2026-09-29T04:33:56.831108Z","steps":["trace[559265553] 'read index received' (duration: 145.142643ms)","trace[559265553] 'applied index is now lower than readState.Index' (duration: 213.185µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:56.831429Z","caller":"traceutil/trace.go:171","msg":"trace[1323641561] transaction","detail":"{read_only:false; response_revision:27001; number_of_response:1; }","duration":"192.137279ms","start":"2026-09-29T04:33:56.639277Z","end":"2026-09-29T04:33:56.831414Z","steps":["trace[1323641561] 'process raft request' (duration: 191.593636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.831677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.913932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-29T04:33:56.831714Z","caller":"traceutil/trace.go:171","msg":"trace[694371256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27001; }","duration":"146.003864ms","start":"2026-09-29T04:33:56.6857Z","end":"2026-09-29T04:33:56.831704Z","steps":["trace[694371256] 'agreement among raft nodes before linearized reading' (duration: 145.879161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.835223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.043149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:56.835319Z","caller":"traceutil/trace.go:171","msg":"trace[899623079] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:27002; }","duration":"123.220773ms","start":"2026-09-29T04:33:56.712075Z","end":"2026-09-29T04:33:56.835296Z","steps":["trace[899623079] 'agreement among raft nodes before linearized reading' (duration: 123.007678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.835329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.302804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:56.835391Z","caller":"traceutil/trace.go:171","msg":"trace[414994901] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:27002; }","duration":"144.391025ms","start":"2026-09-29T04:33:56.690983Z","end":"2026-09-29T04:33:56.835374Z","steps":["trace[414994901] 'agreement among raft nodes before linearized reading' (duration: 143.887504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:56.835499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.154741ms","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-29T04:33:56.835896Z","caller":"traceutil/trace.go:171","msg":"trace[198044232] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:27002; }","duration":"144.655092ms","start":"2026-09-29T04:33:56.691215Z","end":"2026-09-29T04:33:56.83587Z","steps":["trace[198044232] 'agreement among raft nodes before linearized reading' (duration: 143.957846ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:57.058617Z","caller":"traceutil/trace.go:171","msg":"trace[116337448] transaction","detail":"{read_only:false; response_revision:27003; number_of_response:1; }","duration":"221.863543ms","start":"2026-09-29T04:33:56.836727Z","end":"2026-09-29T04:33:57.05859Z","steps":["trace[116337448] 'process raft request' (duration: 221.705509ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:57.090543Z","caller":"traceutil/trace.go:171","msg":"trace[1429477339] transaction","detail":"{read_only:false; response_revision:27004; number_of_response:1; }","duration":"250.504123ms","start":"2026-09-29T04:33:56.840013Z","end":"2026-09-29T04:33:57.090517Z","steps":["trace[1429477339] 'process raft request' (duration: 250.314378ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:57.090841Z","caller":"traceutil/trace.go:171","msg":"trace[496124933] linearizableReadLoop","detail":"{readStateIndex:27899; appliedIndex:27898; }","duration":"223.314599ms","start":"2026-09-29T04:33:56.867495Z","end":"2026-09-29T04:33:57.09081Z","steps":["trace[496124933] 'read index received' (duration: 191.393851ms)","trace[496124933] 'applied index is now lower than readState.Index' (duration: 31.918098ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:57.090934Z","caller":"traceutil/trace.go:171","msg":"trace[627071920] transaction","detail":"{read_only:false; response_revision:27005; number_of_response:1; }","duration":"110.204974ms","start":"2026-09-29T04:33:56.980715Z","end":"2026-09-29T04:33:57.09092Z","steps":["trace[627071920] 'process raft request' (duration: 109.750834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:57.091122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.621586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-856b67b997-wgpct.18d9ae733f76f81d\" ","response":"range_response_count:1 size:1138"} {"level":"warn","ts":"2026-09-29T04:33:57.091138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.086993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:57.091163Z","caller":"traceutil/trace.go:171","msg":"trace[1376185547] range","detail":"{range_begin:/registry/events/openstack/barbican-api-856b67b997-wgpct.18d9ae733f76f81d; range_end:; response_count:1; response_revision:27005; }","duration":"223.726528ms","start":"2026-09-29T04:33:56.867425Z","end":"2026-09-29T04:33:57.091151Z","steps":["trace[1376185547] 'agreement among raft nodes before linearized reading' (duration: 223.526883ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:57.091174Z","caller":"traceutil/trace.go:171","msg":"trace[1329352576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27005; }","duration":"148.174715ms","start":"2026-09-29T04:33:56.942989Z","end":"2026-09-29T04:33:57.091164Z","steps":["trace[1329352576] 'agreement among raft nodes before linearized reading' (duration: 148.104424ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:57.799464Z","caller":"traceutil/trace.go:171","msg":"trace[1212708424] transaction","detail":"{read_only:false; response_revision:27008; number_of_response:1; }","duration":"173.293801ms","start":"2026-09-29T04:33:57.626145Z","end":"2026-09-29T04:33:57.799439Z","steps":["trace[1212708424] 'process raft request' (duration: 173.121077ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:58.134057Z","caller":"traceutil/trace.go:171","msg":"trace[742054358] transaction","detail":"{read_only:false; response_revision:27009; number_of_response:1; }","duration":"108.264499ms","start":"2026-09-29T04:33:58.025747Z","end":"2026-09-29T04:33:58.134011Z","steps":["trace[742054358] 'process raft request' (duration: 108.094795ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:58.238546Z","caller":"traceutil/trace.go:171","msg":"trace[1796631981] linearizableReadLoop","detail":"{readStateIndex:27907; appliedIndex:27903; }","duration":"206.980671ms","start":"2026-09-29T04:33:58.031543Z","end":"2026-09-29T04:33:58.238524Z","steps":["trace[1796631981] 'read index received' (duration: 102.3878ms)","trace[1796631981] 'applied index is now lower than readState.Index' (duration: 104.591771ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:58.238553Z","caller":"traceutil/trace.go:171","msg":"trace[1397011289] transaction","detail":"{read_only:false; response_revision:27010; number_of_response:1; }","duration":"211.657912ms","start":"2026-09-29T04:33:58.026853Z","end":"2026-09-29T04:33:58.238511Z","steps":["trace[1397011289] 'process raft request' (duration: 211.385255ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:58.238568Z","caller":"traceutil/trace.go:171","msg":"trace[793884731] transaction","detail":"{read_only:false; response_revision:27011; number_of_response:1; }","duration":"211.053887ms","start":"2026-09-29T04:33:58.027481Z","end":"2026-09-29T04:33:58.238535Z","steps":["trace[793884731] 'process raft request' (duration: 210.897963ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:58.238643Z","caller":"traceutil/trace.go:171","msg":"trace[111858260] transaction","detail":"{read_only:false; response_revision:27012; number_of_response:1; }","duration":"210.145175ms","start":"2026-09-29T04:33:58.028487Z","end":"2026-09-29T04:33:58.238633Z","steps":["trace[111858260] 'process raft request' (duration: 209.936271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:58.238685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.127484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:33:58.238591Z","caller":"traceutil/trace.go:171","msg":"trace[1389079415] transaction","detail":"{read_only:false; response_revision:27013; number_of_response:1; }","duration":"188.854981ms","start":"2026-09-29T04:33:58.049714Z","end":"2026-09-29T04:33:58.238569Z","steps":["trace[1389079415] 'process raft request' (duration: 188.740278ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:58.238726Z","caller":"traceutil/trace.go:171","msg":"trace[818917031] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:27014; }","duration":"207.214986ms","start":"2026-09-29T04:33:58.031499Z","end":"2026-09-29T04:33:58.238714Z","steps":["trace[818917031] 'agreement among raft nodes before linearized reading' (duration: 207.114574ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:58.238851Z","caller":"traceutil/trace.go:171","msg":"trace[1724981120] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"188.535313ms","start":"2026-09-29T04:33:58.050114Z","end":"2026-09-29T04:33:58.238649Z","steps":["trace[1724981120] 'process raft request' (duration: 188.376149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:33:58.44092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.302743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:33:58.441104Z","caller":"traceutil/trace.go:171","msg":"trace[1144672098] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"196.986563ms","start":"2026-09-29T04:33:58.244087Z","end":"2026-09-29T04:33:58.441074Z","steps":["trace[1144672098] 'process raft request' (duration: 95.415644ms)","trace[1144672098] 'compare' (duration: 101.104538ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:33:58.441107Z","caller":"traceutil/trace.go:171","msg":"trace[1827431512] linearizableReadLoop","detail":"{readStateIndex:27911; appliedIndex:27910; }","duration":"166.285615ms","start":"2026-09-29T04:33:58.274786Z","end":"2026-09-29T04:33:58.441072Z","steps":["trace[1827431512] 'read index received' (duration: 64.660674ms)","trace[1827431512] 'applied index is now lower than readState.Index' (duration: 101.622671ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:33:58.441383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.573592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"warn","ts":"2026-09-29T04:33:58.441447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.033062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-09-29T04:33:58.441487Z","caller":"traceutil/trace.go:171","msg":"trace[1947746705] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27016; }","duration":"164.114484ms","start":"2026-09-29T04:33:58.27736Z","end":"2026-09-29T04:33:58.441475Z","steps":["trace[1947746705] 'agreement among raft nodes before linearized reading' (duration: 163.9528ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:58.441493Z","caller":"traceutil/trace.go:171","msg":"trace[624975442] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27016; }","duration":"166.723516ms","start":"2026-09-29T04:33:58.274745Z","end":"2026-09-29T04:33:58.441469Z","steps":["trace[624975442] 'agreement among raft nodes before linearized reading' (duration: 166.407938ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:33:58.681589Z","caller":"traceutil/trace.go:171","msg":"trace[896704117] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"231.072352ms","start":"2026-09-29T04:33:58.450486Z","end":"2026-09-29T04:33:58.681558Z","steps":["trace[896704117] 'process raft request' (duration: 230.874007ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:00.309163Z","caller":"traceutil/trace.go:171","msg":"trace[515222878] linearizableReadLoop","detail":"{readStateIndex:27979; appliedIndex:27978; }","duration":"160.774525ms","start":"2026-09-29T04:34:00.148358Z","end":"2026-09-29T04:34:00.309133Z","steps":["trace[515222878] 'read index received' (duration: 72.125881ms)","trace[515222878] 'applied index is now lower than readState.Index' (duration: 88.647544ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:00.309292Z","caller":"traceutil/trace.go:171","msg":"trace[230663801] transaction","detail":"{read_only:false; response_revision:27083; number_of_response:1; }","duration":"170.441843ms","start":"2026-09-29T04:34:00.138838Z","end":"2026-09-29T04:34:00.30928Z","steps":["trace[230663801] 'process raft request' (duration: 81.664197ms)","trace[230663801] 'compare' (duration: 88.50531ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:00.309576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.107112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-29T04:34:00.309842Z","caller":"traceutil/trace.go:171","msg":"trace[1220859745] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:27083; }","duration":"161.514662ms","start":"2026-09-29T04:34:00.148306Z","end":"2026-09-29T04:34:00.309821Z","steps":["trace[1220859745] 'agreement among raft nodes before linearized reading' (duration: 161.031491ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:00.559528Z","caller":"traceutil/trace.go:171","msg":"trace[1203644382] linearizableReadLoop","detail":"{readStateIndex:27983; appliedIndex:27979; }","duration":"250.248567ms","start":"2026-09-29T04:34:00.309246Z","end":"2026-09-29T04:34:00.559495Z","steps":["trace[1203644382] 'read index received' (duration: 7.97474ms)","trace[1203644382] 'applied index is now lower than readState.Index' (duration: 242.272677ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:00.559684Z","caller":"traceutil/trace.go:171","msg":"trace[898662122] transaction","detail":"{read_only:false; response_revision:27087; number_of_response:1; }","duration":"312.010303ms","start":"2026-09-29T04:34:00.24766Z","end":"2026-09-29T04:34:00.559671Z","steps":["trace[898662122] 'process raft request' (duration: 311.798478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:00.559798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:00.247646Z","time spent":"312.062273ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:34:00.559636Z","caller":"traceutil/trace.go:171","msg":"trace[445999872] transaction","detail":"{read_only:false; response_revision:27086; number_of_response:1; }","duration":"312.129306ms","start":"2026-09-29T04:34:00.247459Z","end":"2026-09-29T04:34:00.559589Z","steps":["trace[445999872] 'process raft request' (duration: 311.817148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:00.560185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:00.247442Z","time spent":"312.647267ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:34:00.560263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.558591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-29T04:34:00.560401Z","caller":"traceutil/trace.go:171","msg":"trace[242628374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27087; }","duration":"316.713294ms","start":"2026-09-29T04:34:00.243667Z","end":"2026-09-29T04:34:00.56038Z","steps":["trace[242628374] 'agreement among raft nodes before linearized reading' (duration: 316.345836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:00.560451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:00.243601Z","time spent":"316.838947ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-29T04:34:00.6619Z","caller":"traceutil/trace.go:171","msg":"trace[1375905057] transaction","detail":"{read_only:false; response_revision:27088; number_of_response:1; }","duration":"213.526156ms","start":"2026-09-29T04:34:00.448337Z","end":"2026-09-29T04:34:00.661863Z","steps":["trace[1375905057] 'process raft request' (duration: 150.757837ms)","trace[1375905057] 'compare' (duration: 62.571645ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:00.662302Z","caller":"traceutil/trace.go:171","msg":"trace[1999936041] linearizableReadLoop","detail":"{readStateIndex:27984; appliedIndex:27983; }","duration":"102.668066ms","start":"2026-09-29T04:34:00.559594Z","end":"2026-09-29T04:34:00.662262Z","steps":["trace[1999936041] 'read index received' (duration: 39.517707ms)","trace[1999936041] 'applied index is now lower than readState.Index' (duration: 63.143919ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:00.662402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.714524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-29T04:34:00.662403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.306636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:34:00.662481Z","caller":"traceutil/trace.go:171","msg":"trace[768991270] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:27089; }","duration":"171.846167ms","start":"2026-09-29T04:34:00.490617Z","end":"2026-09-29T04:34:00.662464Z","steps":["trace[768991270] 'agreement among raft nodes before linearized reading' (duration: 171.710374ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:00.662526Z","caller":"traceutil/trace.go:171","msg":"trace[1802842906] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:27089; }","duration":"236.47559ms","start":"2026-09-29T04:34:00.426027Z","end":"2026-09-29T04:34:00.662503Z","steps":["trace[1802842906] 'agreement among raft nodes before linearized reading' (duration: 236.306526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:00.663668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.420474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b166f09a-3db4-48ab-9dc7-38bfb820a87a\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-09-29T04:34:00.663733Z","caller":"traceutil/trace.go:171","msg":"trace[1073849650] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b166f09a-3db4-48ab-9dc7-38bfb820a87a; range_end:; response_count:1; response_revision:27089; }","duration":"350.546726ms","start":"2026-09-29T04:34:00.313168Z","end":"2026-09-29T04:34:00.663715Z","steps":["trace[1073849650] 'agreement among raft nodes before linearized reading' (duration: 349.208964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:00.663774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:00.313145Z","time spent":"350.620148ms","remote":"127.0.0.1:38160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1342,"request content":"key:\"/registry/persistentvolumes/pvc-b166f09a-3db4-48ab-9dc7-38bfb820a87a\" "} {"level":"warn","ts":"2026-09-29T04:34:02.176305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.320448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:34:02.176514Z","caller":"traceutil/trace.go:171","msg":"trace[232601041] linearizableReadLoop","detail":"{readStateIndex:27996; appliedIndex:27995; }","duration":"175.212897ms","start":"2026-09-29T04:34:02.001285Z","end":"2026-09-29T04:34:02.176498Z","steps":["trace[232601041] 'read index received' (duration: 29.621473ms)","trace[232601041] 'applied index is now lower than readState.Index' (duration: 145.590234ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:02.176613Z","caller":"traceutil/trace.go:171","msg":"trace[78742543] transaction","detail":"{read_only:false; response_revision:27100; number_of_response:1; }","duration":"214.56878ms","start":"2026-09-29T04:34:01.962014Z","end":"2026-09-29T04:34:02.176583Z","steps":["trace[78742543] 'process raft request' (duration: 68.891274ms)","trace[78742543] 'compare' (duration: 145.021811ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:02.176766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.450903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:02.176867Z","caller":"traceutil/trace.go:171","msg":"trace[2075479548] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:27101; }","duration":"175.602056ms","start":"2026-09-29T04:34:02.001238Z","end":"2026-09-29T04:34:02.17684Z","steps":["trace[2075479548] 'agreement among raft nodes before linearized reading' (duration: 175.34536ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:06.817165Z","caller":"traceutil/trace.go:171","msg":"trace[1670233202] linearizableReadLoop","detail":"{readStateIndex:28054; appliedIndex:28053; }","duration":"130.2721ms","start":"2026-09-29T04:34:06.686868Z","end":"2026-09-29T04:34:06.81714Z","steps":["trace[1670233202] 'read index received' (duration: 130.130216ms)","trace[1670233202] 'applied index is now lower than readState.Index' (duration: 140.844µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:06.817372Z","caller":"traceutil/trace.go:171","msg":"trace[662775134] transaction","detail":"{read_only:false; response_revision:27157; number_of_response:1; }","duration":"134.013368ms","start":"2026-09-29T04:34:06.683332Z","end":"2026-09-29T04:34:06.817345Z","steps":["trace[662775134] 'process raft request' (duration: 133.658699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:06.817397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.509775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-29T04:34:06.817445Z","caller":"traceutil/trace.go:171","msg":"trace[1620279589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27157; }","duration":"130.610408ms","start":"2026-09-29T04:34:06.686823Z","end":"2026-09-29T04:34:06.817433Z","steps":["trace[1620279589] 'agreement among raft nodes before linearized reading' (duration: 130.423583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:07.094783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.173356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:34:07.094915Z","caller":"traceutil/trace.go:171","msg":"trace[2085372349] linearizableReadLoop","detail":"{readStateIndex:28056; appliedIndex:28054; }","duration":"218.014582ms","start":"2026-09-29T04:34:06.876888Z","end":"2026-09-29T04:34:07.094902Z","steps":["trace[2085372349] 'read index received' (duration: 66.571569ms)","trace[2085372349] 'applied index is now lower than readState.Index' (duration: 151.442303ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:07.095027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.142136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-29T04:34:07.095045Z","caller":"traceutil/trace.go:171","msg":"trace[1868941946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27159; }","duration":"218.189346ms","start":"2026-09-29T04:34:06.876849Z","end":"2026-09-29T04:34:07.095039Z","steps":["trace[1868941946] 'agreement among raft nodes before linearized reading' (duration: 218.086504ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:07.095183Z","caller":"traceutil/trace.go:171","msg":"trace[899486592] transaction","detail":"{read_only:false; response_revision:27159; number_of_response:1; }","duration":"220.639764ms","start":"2026-09-29T04:34:06.874535Z","end":"2026-09-29T04:34:07.095175Z","steps":["trace[899486592] 'process raft request' (duration: 220.327107ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:07.09549Z","caller":"traceutil/trace.go:171","msg":"trace[1318906886] transaction","detail":"{read_only:false; response_revision:27158; number_of_response:1; }","duration":"274.530803ms","start":"2026-09-29T04:34:06.82093Z","end":"2026-09-29T04:34:07.095411Z","steps":["trace[1318906886] 'process raft request' (duration: 122.617609ms)","trace[1318906886] 'compare' (duration: 150.89334ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:07.494891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.563125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:34:07.495145Z","caller":"traceutil/trace.go:171","msg":"trace[400786209] linearizableReadLoop","detail":"{readStateIndex:28058; appliedIndex:28056; }","duration":"364.473047ms","start":"2026-09-29T04:34:07.130656Z","end":"2026-09-29T04:34:07.495129Z","steps":["trace[400786209] 'read index received' (duration: 180.526473ms)","trace[400786209] 'applied index is now lower than readState.Index' (duration: 183.945084ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:07.495235Z","caller":"traceutil/trace.go:171","msg":"trace[990479266] transaction","detail":"{read_only:false; response_revision:27161; number_of_response:1; }","duration":"393.168767ms","start":"2026-09-29T04:34:07.102057Z","end":"2026-09-29T04:34:07.495226Z","steps":["trace[990479266] 'process raft request' (duration: 392.953412ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:07.495261Z","caller":"traceutil/trace.go:171","msg":"trace[264028444] transaction","detail":"{read_only:false; response_revision:27160; number_of_response:1; }","duration":"395.245257ms","start":"2026-09-29T04:34:07.099991Z","end":"2026-09-29T04:34:07.495236Z","steps":["trace[264028444] 'process raft request' (duration: 211.251971ms)","trace[264028444] 'compare' (duration: 183.408492ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:07.495294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:07.102032Z","time spent":"393.231459ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:34:07.495358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:07.099968Z","time spent":"395.339929ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:34:07.495496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.857517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-29T04:34:07.495517Z","caller":"traceutil/trace.go:171","msg":"trace[1439713992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27162; }","duration":"364.921768ms","start":"2026-09-29T04:34:07.130589Z","end":"2026-09-29T04:34:07.495511Z","steps":["trace[1439713992] 'agreement among raft nodes before linearized reading' (duration: 364.845126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:07.495531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:07.13057Z","time spent":"364.957308ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-29T04:34:07.495613Z","caller":"traceutil/trace.go:171","msg":"trace[1103989569] transaction","detail":"{read_only:false; response_revision:27162; number_of_response:1; }","duration":"312.559366ms","start":"2026-09-29T04:34:07.183018Z","end":"2026-09-29T04:34:07.495577Z","steps":["trace[1103989569] 'process raft request' (duration: 312.048173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:07.495804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:07.183001Z","time spent":"312.723099ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:34:07.63214Z","caller":"traceutil/trace.go:171","msg":"trace[1725455164] linearizableReadLoop","detail":"{readStateIndex:28061; appliedIndex:28059; }","duration":"112.693624ms","start":"2026-09-29T04:34:07.519411Z","end":"2026-09-29T04:34:07.632104Z","steps":["trace[1725455164] 'read index received' (duration: 10.738955ms)","trace[1725455164] 'applied index is now lower than readState.Index' (duration: 101.952829ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:07.632426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.981651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:07.632517Z","caller":"traceutil/trace.go:171","msg":"trace[774182307] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:27164; }","duration":"113.118444ms","start":"2026-09-29T04:34:07.519383Z","end":"2026-09-29T04:34:07.632502Z","steps":["trace[774182307] 'agreement among raft nodes before linearized reading' (duration: 112.92163ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:07.632899Z","caller":"traceutil/trace.go:171","msg":"trace[2095633732] transaction","detail":"{read_only:false; response_revision:27164; number_of_response:1; }","duration":"133.474496ms","start":"2026-09-29T04:34:07.49941Z","end":"2026-09-29T04:34:07.632885Z","steps":["trace[2095633732] 'process raft request' (duration: 121.614055ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:11.278438Z","caller":"traceutil/trace.go:171","msg":"trace[1629252890] linearizableReadLoop","detail":"{readStateIndex:28090; appliedIndex:28089; }","duration":"166.453369ms","start":"2026-09-29T04:34:11.11196Z","end":"2026-09-29T04:34:11.278413Z","steps":["trace[1629252890] 'read index received' (duration: 166.258295ms)","trace[1629252890] 'applied index is now lower than readState.Index' (duration: 194.194µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:11.278661Z","caller":"traceutil/trace.go:171","msg":"trace[1777974829] transaction","detail":"{read_only:false; response_revision:27192; number_of_response:1; }","duration":"168.963848ms","start":"2026-09-29T04:34:11.109658Z","end":"2026-09-29T04:34:11.278622Z","steps":["trace[1777974829] 'process raft request' (duration: 168.61176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:11.279015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.965501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-29T04:34:11.279224Z","caller":"traceutil/trace.go:171","msg":"trace[1043861114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27192; }","duration":"167.253588ms","start":"2026-09-29T04:34:11.111898Z","end":"2026-09-29T04:34:11.279152Z","steps":["trace[1043861114] 'agreement among raft nodes before linearized reading' (duration: 166.696995ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:11.595292Z","caller":"traceutil/trace.go:171","msg":"trace[1335705400] transaction","detail":"{read_only:false; response_revision:27193; number_of_response:1; }","duration":"311.278645ms","start":"2026-09-29T04:34:11.283962Z","end":"2026-09-29T04:34:11.595241Z","steps":["trace[1335705400] 'process raft request' (duration: 267.109057ms)","trace[1335705400] 'compare' (duration: 43.853571ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:11.595456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:11.283918Z","time spent":"311.452799ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:34:11.718138Z","caller":"traceutil/trace.go:171","msg":"trace[1229607124] transaction","detail":"{read_only:false; response_revision:27196; number_of_response:1; }","duration":"118.128693ms","start":"2026-09-29T04:34:11.599978Z","end":"2026-09-29T04:34:11.718106Z","steps":["trace[1229607124] 'process raft request' (duration: 117.125379ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:35.654475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24517} {"level":"info","ts":"2026-09-29T04:34:35.802179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24517,"took":"143.435733ms","hash":4082608337,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":37613568,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-09-29T04:34:35.802303Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4082608337,"revision":24517,"compact-revision":21710} {"level":"info","ts":"2026-09-29T04:34:37.557162Z","caller":"traceutil/trace.go:171","msg":"trace[1990276122] linearizableReadLoop","detail":"{readStateIndex:28304; appliedIndex:28303; }","duration":"135.570556ms","start":"2026-09-29T04:34:37.421562Z","end":"2026-09-29T04:34:37.557132Z","steps":["trace[1990276122] 'read index received' (duration: 135.391912ms)","trace[1990276122] 'applied index is now lower than readState.Index' (duration: 177.454µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:37.557238Z","caller":"traceutil/trace.go:171","msg":"trace[1372218344] transaction","detail":"{read_only:false; response_revision:27399; number_of_response:1; }","duration":"176.934138ms","start":"2026-09-29T04:34:37.38027Z","end":"2026-09-29T04:34:37.557204Z","steps":["trace[1372218344] 'process raft request' (duration: 176.675902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:37.557332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.73134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:37.557374Z","caller":"traceutil/trace.go:171","msg":"trace[556286304] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:27399; }","duration":"135.839813ms","start":"2026-09-29T04:34:37.421524Z","end":"2026-09-29T04:34:37.557363Z","steps":["trace[556286304] 'agreement among raft nodes before linearized reading' (duration: 135.7372ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:37.728006Z","caller":"traceutil/trace.go:171","msg":"trace[1893440436] linearizableReadLoop","detail":"{readStateIndex:28305; appliedIndex:28304; }","duration":"170.72606ms","start":"2026-09-29T04:34:37.557231Z","end":"2026-09-29T04:34:37.727957Z","steps":["trace[1893440436] 'read index received' (duration: 80.168351ms)","trace[1893440436] 'applied index is now lower than readState.Index' (duration: 90.555549ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:37.728234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.548362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:37.728272Z","caller":"traceutil/trace.go:171","msg":"trace[1325811516] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:27400; }","duration":"201.690605ms","start":"2026-09-29T04:34:37.526569Z","end":"2026-09-29T04:34:37.72826Z","steps":["trace[1325811516] 'agreement among raft nodes before linearized reading' (duration: 201.543712ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:37.728756Z","caller":"traceutil/trace.go:171","msg":"trace[1102976499] transaction","detail":"{read_only:false; response_revision:27400; number_of_response:1; }","duration":"190.992272ms","start":"2026-09-29T04:34:37.537747Z","end":"2026-09-29T04:34:37.728739Z","steps":["trace[1102976499] 'process raft request' (duration: 99.726686ms)","trace[1102976499] 'compare' (duration: 89.935474ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:38.106707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.297664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:34:38.106794Z","caller":"traceutil/trace.go:171","msg":"trace[757331349] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:27401; }","duration":"120.394106ms","start":"2026-09-29T04:34:37.986377Z","end":"2026-09-29T04:34:38.106771Z","steps":["trace[757331349] 'count revisions from in-memory index tree' (duration: 120.233163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.106948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.68166ms","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":"warn","ts":"2026-09-29T04:34:38.10698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.394326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-29T04:34:38.107032Z","caller":"traceutil/trace.go:171","msg":"trace[773785104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27401; }","duration":"240.821493ms","start":"2026-09-29T04:34:37.866188Z","end":"2026-09-29T04:34:38.10701Z","steps":["trace[773785104] 'range keys from in-memory index tree' (duration: 240.414874ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:38.107045Z","caller":"traceutil/trace.go:171","msg":"trace[414846432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27401; }","duration":"254.491047ms","start":"2026-09-29T04:34:37.852539Z","end":"2026-09-29T04:34:38.10703Z","steps":["trace[414846432] 'range keys from in-memory index tree' (duration: 254.15112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.107126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.594313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-29T04:34:38.107158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.572535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-29T04:34:38.107203Z","caller":"traceutil/trace.go:171","msg":"trace[1398518092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27401; }","duration":"242.642116ms","start":"2026-09-29T04:34:37.864552Z","end":"2026-09-29T04:34:38.107194Z","steps":["trace[1398518092] 'range keys from in-memory index tree' (duration: 242.462263ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:38.107217Z","caller":"traceutil/trace.go:171","msg":"trace[991852684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27401; }","duration":"268.725976ms","start":"2026-09-29T04:34:37.83846Z","end":"2026-09-29T04:34:38.107186Z","steps":["trace[991852684] 'range keys from in-memory index tree' (duration: 268.404018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.107624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.238573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:38.107736Z","caller":"traceutil/trace.go:171","msg":"trace[1148668830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27401; }","duration":"163.436008ms","start":"2026-09-29T04:34:37.944274Z","end":"2026-09-29T04:34:38.10771Z","steps":["trace[1148668830] 'range keys from in-memory index tree' (duration: 162.478285ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:38.218905Z","caller":"traceutil/trace.go:171","msg":"trace[1313378041] linearizableReadLoop","detail":"{readStateIndex:28307; appliedIndex:28306; }","duration":"109.532909ms","start":"2026-09-29T04:34:38.109347Z","end":"2026-09-29T04:34:38.21888Z","steps":["trace[1313378041] 'read index received' (duration: 109.429757ms)","trace[1313378041] 'applied index is now lower than readState.Index' (duration: 102.072µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:38.219071Z","caller":"traceutil/trace.go:171","msg":"trace[1303027159] transaction","detail":"{read_only:false; response_revision:27402; number_of_response:1; }","duration":"232.62665ms","start":"2026-09-29T04:34:37.986403Z","end":"2026-09-29T04:34:38.21903Z","steps":["trace[1303027159] 'process raft request' (duration: 232.315812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.219222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.851707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:38.21931Z","caller":"traceutil/trace.go:171","msg":"trace[995652792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27402; }","duration":"109.99729ms","start":"2026-09-29T04:34:38.109291Z","end":"2026-09-29T04:34:38.219288Z","steps":["trace[995652792] 'agreement among raft nodes before linearized reading' (duration: 109.770004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.650701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.053606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:34:38.651018Z","caller":"traceutil/trace.go:171","msg":"trace[34822141] transaction","detail":"{read_only:false; response_revision:27407; number_of_response:1; }","duration":"179.272943ms","start":"2026-09-29T04:34:38.471726Z","end":"2026-09-29T04:34:38.650999Z","steps":["trace[34822141] 'process raft request' (duration: 179.233852ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:38.651353Z","caller":"traceutil/trace.go:171","msg":"trace[1418057120] transaction","detail":"{read_only:false; response_revision:27403; number_of_response:1; }","duration":"539.481959ms","start":"2026-09-29T04:34:38.111859Z","end":"2026-09-29T04:34:38.651341Z","steps":["trace[1418057120] 'process raft request' (duration: 303.688335ms)","trace[1418057120] 'compare' (duration: 234.233927ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:38.651424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:38.111836Z","time spent":"539.551821ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:34:38.651553Z","caller":"traceutil/trace.go:171","msg":"trace[1629839177] transaction","detail":"{read_only:false; response_revision:27404; number_of_response:1; }","duration":"539.324956ms","start":"2026-09-29T04:34:38.112218Z","end":"2026-09-29T04:34:38.651543Z","steps":["trace[1629839177] 'process raft request' (duration: 538.587398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.651607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:38.112213Z","time spent":"539.367517ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:34:38.651726Z","caller":"traceutil/trace.go:171","msg":"trace[400404431] transaction","detail":"{read_only:false; response_revision:27405; number_of_response:1; }","duration":"539.204543ms","start":"2026-09-29T04:34:38.112512Z","end":"2026-09-29T04:34:38.651717Z","steps":["trace[400404431] 'process raft request' (duration: 538.342622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.651778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:38.11249Z","time spent":"539.262864ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:34:38.65188Z","caller":"traceutil/trace.go:171","msg":"trace[1718220712] transaction","detail":"{read_only:false; response_revision:27406; number_of_response:1; }","duration":"537.81702ms","start":"2026-09-29T04:34:38.114055Z","end":"2026-09-29T04:34:38.651872Z","steps":["trace[1718220712] 'process raft request' (duration: 536.833306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.651955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:38.114038Z","time spent":"537.8689ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:34:38.652074Z","caller":"traceutil/trace.go:171","msg":"trace[1547198692] linearizableReadLoop","detail":"{readStateIndex:28311; appliedIndex:28307; }","duration":"280.963146ms","start":"2026-09-29T04:34:38.371102Z","end":"2026-09-29T04:34:38.652065Z","steps":["trace[1547198692] 'read index received' (duration: 44.410414ms)","trace[1547198692] 'applied index is now lower than readState.Index' (duration: 236.551752ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:38.652393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.291854ms","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:9"} {"level":"info","ts":"2026-09-29T04:34:38.652439Z","caller":"traceutil/trace.go:171","msg":"trace[1625614435] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:27407; }","duration":"281.386656ms","start":"2026-09-29T04:34:38.371041Z","end":"2026-09-29T04:34:38.652428Z","steps":["trace[1625614435] 'agreement among raft nodes before linearized reading' (duration: 281.200671ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:38.741463Z","caller":"traceutil/trace.go:171","msg":"trace[119831878] transaction","detail":"{read_only:false; response_revision:27408; number_of_response:1; }","duration":"255.785409ms","start":"2026-09-29T04:34:38.485651Z","end":"2026-09-29T04:34:38.741436Z","steps":["trace[119831878] 'process raft request' (duration: 255.617435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:38.741678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.969183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:38.741726Z","caller":"traceutil/trace.go:171","msg":"trace[1031150033] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:27409; }","duration":"256.081855ms","start":"2026-09-29T04:34:38.48563Z","end":"2026-09-29T04:34:38.741712Z","steps":["trace[1031150033] 'agreement among raft nodes before linearized reading' (duration: 255.952182ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:38.741692Z","caller":"traceutil/trace.go:171","msg":"trace[148625733] transaction","detail":"{read_only:false; response_revision:27409; number_of_response:1; }","duration":"235.133528ms","start":"2026-09-29T04:34:38.506499Z","end":"2026-09-29T04:34:38.741632Z","steps":["trace[148625733] 'process raft request' (duration: 234.891033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:39.808001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.168811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:34:39.808535Z","caller":"traceutil/trace.go:171","msg":"trace[1552901714] linearizableReadLoop","detail":"{readStateIndex:28320; appliedIndex:28318; }","duration":"229.746441ms","start":"2026-09-29T04:34:39.578759Z","end":"2026-09-29T04:34:39.808505Z","steps":["trace[1552901714] 'read index received' (duration: 154.966806ms)","trace[1552901714] 'applied index is now lower than readState.Index' (duration: 74.778045ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:39.808663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.899815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:34:39.808654Z","caller":"traceutil/trace.go:171","msg":"trace[641008188] transaction","detail":"{read_only:false; response_revision:27414; number_of_response:1; }","duration":"245.899924ms","start":"2026-09-29T04:34:39.562709Z","end":"2026-09-29T04:34:39.808609Z","steps":["trace[641008188] 'process raft request' (duration: 245.471864ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:39.808714Z","caller":"traceutil/trace.go:171","msg":"trace[1179401784] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27414; }","duration":"230.000127ms","start":"2026-09-29T04:34:39.578696Z","end":"2026-09-29T04:34:39.808696Z","steps":["trace[1179401784] 'agreement among raft nodes before linearized reading' (duration: 229.902765ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:39.808726Z","caller":"traceutil/trace.go:171","msg":"trace[984433111] transaction","detail":"{read_only:false; response_revision:27413; number_of_response:1; }","duration":"433.453564ms","start":"2026-09-29T04:34:39.375197Z","end":"2026-09-29T04:34:39.80865Z","steps":["trace[984433111] 'process raft request' (duration: 124.446523ms)","trace[984433111] 'compare' (duration: 307.566317ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:39.809006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:34:39.375173Z","time spent":"433.640648ms","remote":"127.0.0.1:38020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T04:34:39.881251Z","caller":"traceutil/trace.go:171","msg":"trace[2043679468] transaction","detail":"{read_only:false; response_revision:27415; number_of_response:1; }","duration":"164.132484ms","start":"2026-09-29T04:34:39.717089Z","end":"2026-09-29T04:34:39.881221Z","steps":["trace[2043679468] 'process raft request' (duration: 163.99019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:40.139059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.536444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:34:40.139176Z","caller":"traceutil/trace.go:171","msg":"trace[1271160330] linearizableReadLoop","detail":"{readStateIndex:28322; appliedIndex:28321; }","duration":"194.553066ms","start":"2026-09-29T04:34:39.944596Z","end":"2026-09-29T04:34:40.139149Z","steps":["trace[1271160330] 'read index received' (duration: 61.92701ms)","trace[1271160330] 'applied index is now lower than readState.Index' (duration: 132.624376ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:40.139249Z","caller":"traceutil/trace.go:171","msg":"trace[1938116672] transaction","detail":"{read_only:false; response_revision:27416; number_of_response:1; }","duration":"252.168143ms","start":"2026-09-29T04:34:39.887071Z","end":"2026-09-29T04:34:40.139239Z","steps":["trace[1938116672] 'process raft request' (duration: 119.386003ms)","trace[1938116672] 'compare' (duration: 132.332229ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:40.139389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.803461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:40.13941Z","caller":"traceutil/trace.go:171","msg":"trace[30342060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27416; }","duration":"194.855333ms","start":"2026-09-29T04:34:39.94455Z","end":"2026-09-29T04:34:40.139405Z","steps":["trace[30342060] 'agreement among raft nodes before linearized reading' (duration: 194.804812ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:40.394329Z","caller":"traceutil/trace.go:171","msg":"trace[1164832809] transaction","detail":"{read_only:false; response_revision:27417; number_of_response:1; }","duration":"168.261622ms","start":"2026-09-29T04:34:40.226041Z","end":"2026-09-29T04:34:40.394302Z","steps":["trace[1164832809] 'process raft request' (duration: 167.857333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:40.651963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.385355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:40.652064Z","caller":"traceutil/trace.go:171","msg":"trace[1831261553] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27417; }","duration":"149.574969ms","start":"2026-09-29T04:34:40.502465Z","end":"2026-09-29T04:34:40.65204Z","steps":["trace[1831261553] 'range keys from in-memory index tree' (duration: 149.291552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:40.962327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.820726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:34:40.962451Z","caller":"traceutil/trace.go:171","msg":"trace[1778891440] transaction","detail":"{read_only:false; response_revision:27420; number_of_response:1; }","duration":"213.087895ms","start":"2026-09-29T04:34:40.749347Z","end":"2026-09-29T04:34:40.962435Z","steps":["trace[1778891440] 'process raft request' (duration: 92.064664ms)","trace[1778891440] 'compare' (duration: 120.372196ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:41.042405Z","caller":"traceutil/trace.go:171","msg":"trace[35071717] transaction","detail":"{read_only:false; response_revision:27422; number_of_response:1; }","duration":"289.680693ms","start":"2026-09-29T04:34:40.752699Z","end":"2026-09-29T04:34:41.04238Z","steps":["trace[35071717] 'process raft request' (duration: 289.622591ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:41.04249Z","caller":"traceutil/trace.go:171","msg":"trace[2117488722] transaction","detail":"{read_only:false; response_revision:27421; number_of_response:1; }","duration":"292.988451ms","start":"2026-09-29T04:34:40.749431Z","end":"2026-09-29T04:34:41.04242Z","steps":["trace[2117488722] 'process raft request' (duration: 292.764385ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:41.042726Z","caller":"traceutil/trace.go:171","msg":"trace[614627395] transaction","detail":"{read_only:false; response_revision:27424; number_of_response:1; }","duration":"288.399072ms","start":"2026-09-29T04:34:40.754309Z","end":"2026-09-29T04:34:41.042708Z","steps":["trace[614627395] 'process raft request' (duration: 288.254719ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:41.042731Z","caller":"traceutil/trace.go:171","msg":"trace[1056381006] transaction","detail":"{read_only:false; response_revision:27423; number_of_response:1; }","duration":"288.862273ms","start":"2026-09-29T04:34:40.75383Z","end":"2026-09-29T04:34:41.042692Z","steps":["trace[1056381006] 'process raft request' (duration: 288.521435ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:41.043241Z","caller":"traceutil/trace.go:171","msg":"trace[1339320267] transaction","detail":"{read_only:false; response_revision:27425; number_of_response:1; }","duration":"252.371687ms","start":"2026-09-29T04:34:40.79086Z","end":"2026-09-29T04:34:41.043231Z","steps":["trace[1339320267] 'process raft request' (duration: 251.798263ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:41.043475Z","caller":"traceutil/trace.go:171","msg":"trace[1708052716] linearizableReadLoop","detail":"{readStateIndex:28330; appliedIndex:28325; }","duration":"279.517881ms","start":"2026-09-29T04:34:40.763942Z","end":"2026-09-29T04:34:41.04346Z","steps":["trace[1708052716] 'read index received' (duration: 77.56427ms)","trace[1708052716] 'applied index is now lower than readState.Index' (duration: 201.952061ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:41.04355Z","caller":"traceutil/trace.go:171","msg":"trace[385370994] transaction","detail":"{read_only:false; response_revision:27426; number_of_response:1; }","duration":"246.938078ms","start":"2026-09-29T04:34:40.796595Z","end":"2026-09-29T04:34:41.043533Z","steps":["trace[385370994] 'process raft request' (duration: 246.60013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:34:41.043641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.683615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-29T04:34:41.043681Z","caller":"traceutil/trace.go:171","msg":"trace[1554348031] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:27426; }","duration":"279.814648ms","start":"2026-09-29T04:34:40.763852Z","end":"2026-09-29T04:34:41.043667Z","steps":["trace[1554348031] 'agreement among raft nodes before linearized reading' (duration: 279.660575ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:41.176452Z","caller":"traceutil/trace.go:171","msg":"trace[154479165] transaction","detail":"{read_only:false; response_revision:27427; number_of_response:1; }","duration":"121.099783ms","start":"2026-09-29T04:34:41.055318Z","end":"2026-09-29T04:34:41.176418Z","steps":["trace[154479165] 'process raft request' (duration: 51.676926ms)","trace[154479165] 'compare' (duration: 69.277553ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:34:41.939942Z","caller":"traceutil/trace.go:171","msg":"trace[869907834] linearizableReadLoop","detail":"{readStateIndex:28334; appliedIndex:28333; }","duration":"120.954649ms","start":"2026-09-29T04:34:41.818941Z","end":"2026-09-29T04:34:41.939895Z","steps":["trace[869907834] 'read index received' (duration: 120.723284ms)","trace[869907834] 'applied index is now lower than readState.Index' (duration: 230.185µs)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:34:41.94011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.181305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:34:41.940155Z","caller":"traceutil/trace.go:171","msg":"trace[497514416] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:27428; }","duration":"121.244096ms","start":"2026-09-29T04:34:41.818898Z","end":"2026-09-29T04:34:41.940142Z","steps":["trace[497514416] 'agreement among raft nodes before linearized reading' (duration: 121.119263ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:34:41.940469Z","caller":"traceutil/trace.go:171","msg":"trace[1915618361] transaction","detail":"{read_only:false; response_revision:27428; number_of_response:1; }","duration":"125.16769ms","start":"2026-09-29T04:34:41.815288Z","end":"2026-09-29T04:34:41.940456Z","steps":["trace[1915618361] 'process raft request' (duration: 124.463923ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:35:04.134318Z","caller":"traceutil/trace.go:171","msg":"trace[980993673] transaction","detail":"{read_only:false; response_revision:27606; number_of_response:1; }","duration":"103.063584ms","start":"2026-09-29T04:35:04.031228Z","end":"2026-09-29T04:35:04.134292Z","steps":["trace[980993673] 'process raft request' (duration: 102.871619ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:35:04.940692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.653961ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-29T04:35:04.941788Z","caller":"traceutil/trace.go:171","msg":"trace[1925878483] linearizableReadLoop","detail":"{readStateIndex:28519; appliedIndex:28517; }","duration":"337.393344ms","start":"2026-09-29T04:35:04.60437Z","end":"2026-09-29T04:35:04.941763Z","steps":["trace[1925878483] 'read index received' (duration: 112.883807ms)","trace[1925878483] 'applied index is now lower than readState.Index' (duration: 224.507837ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:35:04.941987Z","caller":"traceutil/trace.go:171","msg":"trace[1648919838] transaction","detail":"{read_only:false; response_revision:27608; number_of_response:1; }","duration":"394.827157ms","start":"2026-09-29T04:35:04.546989Z","end":"2026-09-29T04:35:04.941816Z","steps":["trace[1648919838] 'process raft request' (duration: 393.830914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:35:04.942248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:35:04.546965Z","time spent":"395.149756ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:35:05.005666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.26161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-29T04:35:05.005776Z","caller":"traceutil/trace.go:171","msg":"trace[1110264072] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:27608; }","duration":"401.442014ms","start":"2026-09-29T04:35:04.604304Z","end":"2026-09-29T04:35:05.005746Z","steps":["trace[1110264072] 'agreement among raft nodes before linearized reading' (duration: 337.550048ms)","trace[1110264072] 'count revisions from in-memory index tree' (duration: 63.733072ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:35:05.005837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:35:04.604279Z","time spent":"401.542057ms","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-09-29T04:35:05.005994Z","caller":"traceutil/trace.go:171","msg":"trace[103794252] transaction","detail":"{read_only:false; response_revision:27609; number_of_response:1; }","duration":"143.981446ms","start":"2026-09-29T04:35:04.861981Z","end":"2026-09-29T04:35:05.005963Z","steps":["trace[103794252] 'process raft request' (duration: 92.413113ms)","trace[103794252] 'compare' (duration: 51.058691ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T04:35:05.00629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.644238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-29T04:35:05.006299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.341706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-29T04:35:05.00633Z","caller":"traceutil/trace.go:171","msg":"trace[2080281112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27609; }","duration":"127.739841ms","start":"2026-09-29T04:35:04.878581Z","end":"2026-09-29T04:35:05.00632Z","steps":["trace[2080281112] 'agreement among raft nodes before linearized reading' (duration: 127.596897ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:35:05.006344Z","caller":"traceutil/trace.go:171","msg":"trace[1686861065] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:27609; }","duration":"327.48536ms","start":"2026-09-29T04:35:04.678846Z","end":"2026-09-29T04:35:05.006331Z","steps":["trace[1686861065] 'agreement among raft nodes before linearized reading' (duration: 327.401988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:35:05.006389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:35:04.67881Z","time spent":"327.562122ms","remote":"127.0.0.1:42024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"info","ts":"2026-09-29T04:35:05.281301Z","caller":"traceutil/trace.go:171","msg":"trace[1859963437] transaction","detail":"{read_only:false; response_revision:27612; number_of_response:1; }","duration":"115.997702ms","start":"2026-09-29T04:35:05.165278Z","end":"2026-09-29T04:35:05.281276Z","steps":["trace[1859963437] 'process raft request' (duration: 115.816078ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:35:05.282429Z","caller":"traceutil/trace.go:171","msg":"trace[1991872415] transaction","detail":"{read_only:false; response_revision:27613; number_of_response:1; }","duration":"107.926721ms","start":"2026-09-29T04:35:05.174474Z","end":"2026-09-29T04:35:05.282401Z","steps":["trace[1991872415] 'process raft request' (duration: 107.542622ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:35:10.307778Z","caller":"traceutil/trace.go:171","msg":"trace[326833094] transaction","detail":"{read_only:false; response_revision:27655; number_of_response:1; }","duration":"126.486671ms","start":"2026-09-29T04:35:10.181258Z","end":"2026-09-29T04:35:10.307745Z","steps":["trace[326833094] 'process raft request' (duration: 126.278246ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:35:10.735107Z","caller":"traceutil/trace.go:171","msg":"trace[1023678501] transaction","detail":"{read_only:false; response_revision:27656; number_of_response:1; }","duration":"208.468436ms","start":"2026-09-29T04:35:10.5266Z","end":"2026-09-29T04:35:10.735069Z","steps":["trace[1023678501] 'process raft request' (duration: 208.24323ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:35:21.724273Z","caller":"traceutil/trace.go:171","msg":"trace[568272978] transaction","detail":"{read_only:false; response_revision:27902; number_of_response:1; }","duration":"100.416642ms","start":"2026-09-29T04:35:21.623825Z","end":"2026-09-29T04:35:21.724242Z","steps":["trace[568272978] 'process raft request' (duration: 100.101265ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:35:21.961076Z","caller":"traceutil/trace.go:171","msg":"trace[477888574] transaction","detail":"{read_only:false; response_revision:27903; number_of_response:1; }","duration":"229.3173ms","start":"2026-09-29T04:35:21.731704Z","end":"2026-09-29T04:35:21.961022Z","steps":["trace[477888574] 'process raft request' (duration: 176.082628ms)","trace[477888574] 'compare' (duration: 53.014507ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:35:22.111011Z","caller":"traceutil/trace.go:171","msg":"trace[549657166] transaction","detail":"{read_only:false; response_revision:27905; number_of_response:1; }","duration":"143.775431ms","start":"2026-09-29T04:35:21.967208Z","end":"2026-09-29T04:35:22.110984Z","steps":["trace[549657166] 'process raft request' (duration: 143.141046ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T04:35:22.111227Z","caller":"traceutil/trace.go:171","msg":"trace[885793860] transaction","detail":"{read_only:false; response_revision:27906; number_of_response:1; }","duration":"141.980228ms","start":"2026-09-29T04:35:21.969235Z","end":"2026-09-29T04:35:22.111215Z","steps":["trace[885793860] 'process raft request' (duration: 141.678601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:35:22.512452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.815399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-29T04:35:22.512857Z","caller":"traceutil/trace.go:171","msg":"trace[507390399] transaction","detail":"{read_only:false; response_revision:27907; number_of_response:1; }","duration":"394.150231ms","start":"2026-09-29T04:35:22.118481Z","end":"2026-09-29T04:35:22.512632Z","steps":["trace[507390399] 'process raft request' (duration: 272.070085ms)","trace[507390399] 'compare' (duration: 121.682986ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:35:22.512949Z","caller":"traceutil/trace.go:171","msg":"trace[9174226] linearizableReadLoop","detail":"{readStateIndex:28822; appliedIndex:28821; }","duration":"393.934936ms","start":"2026-09-29T04:35:22.118976Z","end":"2026-09-29T04:35:22.512911Z","steps":["trace[9174226] 'read index received' (duration: 271.589834ms)","trace[9174226] 'applied index is now lower than readState.Index' (duration: 122.341312ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T04:35:22.512992Z","caller":"traceutil/trace.go:171","msg":"trace[1181444524] transaction","detail":"{read_only:false; response_revision:27908; number_of_response:1; }","duration":"146.245399ms","start":"2026-09-29T04:35:22.366706Z","end":"2026-09-29T04:35:22.512951Z","steps":["trace[1181444524] 'process raft request' (duration: 145.8551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:35:22.512983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:35:22.118463Z","time spent":"394.471769ms","remote":"127.0.0.1:38502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3020,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T04:35:22.513246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.242203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-29T04:35:22.513353Z","caller":"traceutil/trace.go:171","msg":"trace[1145531526] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27908; }","duration":"394.374726ms","start":"2026-09-29T04:35:22.118952Z","end":"2026-09-29T04:35:22.513327Z","steps":["trace[1145531526] 'agreement among raft nodes before linearized reading' (duration: 394.09833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T04:35:22.513416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T04:35:22.118941Z","time spent":"394.461339ms","remote":"127.0.0.1:38198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-29T04:37:38.122312Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-29T04:37:38.12882Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-29T04:37:38.128974Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-29T04:39:35.661998Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27385} {"level":"info","ts":"2026-09-29T04:39:35.802889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27385,"took":"135.991506ms","hash":242478681,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":38322176,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-09-29T04:39:35.802969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":242478681,"revision":27385,"compact-revision":24517} {"level":"info","ts":"2026-09-29T04:44:35.669733Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30257} {"level":"info","ts":"2026-09-29T04:44:35.823682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30257,"took":"148.985245ms","hash":1112647016,"current-db-size-bytes":57720832,"current-db-size":"58 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T04:44:35.823754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1112647016,"revision":30257,"compact-revision":27385} {"level":"info","ts":"2026-09-29T04:49:35.677395Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32516} {"level":"info","ts":"2026-09-29T04:49:35.847061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32516,"took":"165.337828ms","hash":769758748,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":30285824,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-29T04:49:35.847132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":769758748,"revision":32516,"compact-revision":30257} {"level":"info","ts":"2026-09-29T04:54:35.685798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34739} {"level":"info","ts":"2026-09-29T04:54:35.816685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34739,"took":"126.096632ms","hash":984318847,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":30302208,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-29T04:54:35.816778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":984318847,"revision":34739,"compact-revision":32516} {"level":"info","ts":"2026-09-29T04:58:24.831099Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"299bf3744060cbd","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-29T04:58:24.836995Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-29T04:58:24.837086Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-29T04:59:35.694429Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36967} {"level":"info","ts":"2026-09-29T04:59:35.84589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36967,"took":"144.923727ms","hash":1888824586,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":32079872,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T04:59:35.84687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1888824586,"revision":36967,"compact-revision":34739} {"level":"info","ts":"2026-09-29T05:04:35.704237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39308} {"level":"info","ts":"2026-09-29T05:04:35.849859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39308,"took":"140.478048ms","hash":3456516318,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":31920128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T05:04:35.849939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3456516318,"revision":39308,"compact-revision":36967} {"level":"info","ts":"2026-09-29T05:09:35.711354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41602} {"level":"info","ts":"2026-09-29T05:09:35.839258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41602,"took":"123.733248ms","hash":3879168146,"current-db-size-bytes":58765312,"current-db-size":"59 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-29T05:09:35.839304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3879168146,"revision":41602,"compact-revision":39308}