{"level":"warn","ts":"2026-10-15T06:35:33.012731Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T06:35:33.01285Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.146:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.146:2380","--initial-cluster=instance=https://199.19.213.146:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.146:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.146: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-10-15T06:35:33.012928Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T06:35:33.013075Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-10-15T06:35:33.013111Z","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-10-15T06:35:33.013862Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"]} {"level":"info","ts":"2026-10-15T06:35:33.014115Z","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://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.146: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-10-15T06:35:33.017369Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.000995ms"} {"level":"info","ts":"2026-10-15T06:35:33.023812Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c8e9c1aa9bb537b7","cluster-id":"933c0a8774274c15"} {"level":"info","ts":"2026-10-15T06:35:33.023982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T06:35:33.024009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 0"} {"level":"info","ts":"2026-10-15T06:35:33.024049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c8e9c1aa9bb537b7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T06:35:33.024058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 1"} {"level":"info","ts":"2026-10-15T06:35:33.024104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"warn","ts":"2026-10-15T06:35:33.029714Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T06:35:33.032585Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T06:35:33.037161Z","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-10-15T06:35:33.040726Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c8e9c1aa9bb537b7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T06:35:33.040982Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c8e9c1aa9bb537b7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T06:35:33.041168Z","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-10-15T06:35:33.04134Z","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-10-15T06:35:33.04138Z","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-10-15T06:35:33.042645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"info","ts":"2026-10-15T06:35:33.042935Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","added-peer-id":"c8e9c1aa9bb537b7","added-peer-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-10-15T06:35:33.0446Z","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-10-15T06:35:33.044647Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-10-15T06:35:33.044681Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-10-15T06:35:33.044853Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c8e9c1aa9bb537b7","initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T06:35:33.044906Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T06:35:33.224853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T06:35:33.224913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T06:35:33.224956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgPreVoteResp from c8e9c1aa9bb537b7 at term 1"} {"level":"info","ts":"2026-10-15T06:35:33.224975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became candidate at term 2"} {"level":"info","ts":"2026-10-15T06:35:33.224986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgVoteResp from c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-10-15T06:35:33.225001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became leader at term 2"} {"level":"info","ts":"2026-10-15T06:35:33.225041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c8e9c1aa9bb537b7 elected leader c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-10-15T06:35:33.230577Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:35:33.232799Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c8e9c1aa9bb537b7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.146:2379]}","request-path":"/0/members/c8e9c1aa9bb537b7/attributes","cluster-id":"933c0a8774274c15","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T06:35:33.232944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T06:35:33.232976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T06:35:33.23469Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.146:2379"} {"level":"info","ts":"2026-10-15T06:35:33.235293Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:35:33.235452Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T06:35:33.235465Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:35:33.235478Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T06:35:33.2355Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T06:35:33.235512Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-10-15T06:35:49.529062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.710917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-15T06:35:49.529376Z","caller":"traceutil/trace.go:171","msg":"trace[1725675557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"258.144446ms","start":"2026-10-15T06:35:49.271204Z","end":"2026-10-15T06:35:49.529348Z","steps":["trace[1725675557] 'range keys from in-memory index tree' (duration: 257.570913ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:35:55.041476Z","caller":"traceutil/trace.go:171","msg":"trace[2093499713] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"156.534849ms","start":"2026-10-15T06:35:54.884917Z","end":"2026-10-15T06:35:55.041452Z","steps":["trace[2093499713] 'process raft request' (duration: 156.350965ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:01.264814Z","caller":"traceutil/trace.go:171","msg":"trace[2091092139] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"116.106028ms","start":"2026-10-15T06:36:01.148688Z","end":"2026-10-15T06:36:01.264794Z","steps":["trace[2091092139] 'process raft request' (duration: 116.068537ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:01.265103Z","caller":"traceutil/trace.go:171","msg":"trace[1092368867] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"117.345206ms","start":"2026-10-15T06:36:01.147748Z","end":"2026-10-15T06:36:01.265093Z","steps":["trace[1092368867] 'process raft request' (duration: 71.527599ms)","trace[1092368867] 'compare' (duration: 45.365847ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:36:06.977402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.65525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:36:06.97752Z","caller":"traceutil/trace.go:171","msg":"trace[940325782] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"221.175247ms","start":"2026-10-15T06:36:06.756326Z","end":"2026-10-15T06:36:06.977501Z","steps":["trace[940325782] 'process raft request' (duration: 31.71931ms)","trace[940325782] 'compare' (duration: 188.455186ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:19.939782Z","caller":"traceutil/trace.go:171","msg":"trace[959639070] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"119.978419ms","start":"2026-10-15T06:36:19.819778Z","end":"2026-10-15T06:36:19.939756Z","steps":["trace[959639070] 'read index received' (duration: 72.137185ms)","trace[959639070] 'applied index is now lower than readState.Index' (duration: 47.840394ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:36:19.939959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.150443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-10-15T06:36:19.940009Z","caller":"traceutil/trace.go:171","msg":"trace[1316370849] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:602; }","duration":"120.246235ms","start":"2026-10-15T06:36:19.819749Z","end":"2026-10-15T06:36:19.939995Z","steps":["trace[1316370849] 'agreement among raft nodes before linearized reading' (duration: 120.113663ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:19.940162Z","caller":"traceutil/trace.go:171","msg":"trace[1551964220] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"135.736116ms","start":"2026-10-15T06:36:19.804394Z","end":"2026-10-15T06:36:19.94013Z","steps":["trace[1551964220] 'process raft request' (duration: 87.570454ms)","trace[1551964220] 'compare' (duration: 47.479158ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:19.940203Z","caller":"traceutil/trace.go:171","msg":"trace[1803329933] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"120.866628ms","start":"2026-10-15T06:36:19.819301Z","end":"2026-10-15T06:36:19.940167Z","steps":["trace[1803329933] 'process raft request' (duration: 120.391138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:20.432987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.62087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:36:20.43321Z","caller":"traceutil/trace.go:171","msg":"trace[1113903463] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"259.389116ms","start":"2026-10-15T06:36:20.173784Z","end":"2026-10-15T06:36:20.433173Z","steps":["trace[1113903463] 'process raft request' (duration: 133.456159ms)","trace[1113903463] 'compare' (duration: 125.342713ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:20.43581Z","caller":"traceutil/trace.go:171","msg":"trace[1913442980] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"249.010583ms","start":"2026-10-15T06:36:20.186765Z","end":"2026-10-15T06:36:20.435776Z","steps":["trace[1913442980] 'process raft request' (duration: 248.627475ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:20.436715Z","caller":"traceutil/trace.go:171","msg":"trace[2066478332] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"246.191703ms","start":"2026-10-15T06:36:20.190455Z","end":"2026-10-15T06:36:20.436647Z","steps":["trace[2066478332] 'process raft request' (duration: 245.194241ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:20.436777Z","caller":"traceutil/trace.go:171","msg":"trace[1408153545] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"244.534687ms","start":"2026-10-15T06:36:20.192213Z","end":"2026-10-15T06:36:20.436748Z","steps":["trace[1408153545] 'process raft request' (duration: 244.299772ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:20.974288Z","caller":"traceutil/trace.go:171","msg":"trace[1447103884] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"156.195385ms","start":"2026-10-15T06:36:20.818057Z","end":"2026-10-15T06:36:20.974252Z","steps":["trace[1447103884] 'process raft request' (duration: 119.907679ms)","trace[1447103884] 'compare' (duration: 36.160464ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:21.043966Z","caller":"traceutil/trace.go:171","msg":"trace[1879066022] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"177.795988ms","start":"2026-10-15T06:36:20.866132Z","end":"2026-10-15T06:36:21.043928Z","steps":["trace[1879066022] 'process raft request' (duration: 177.306739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.472266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.526612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T06:36:21.472333Z","caller":"traceutil/trace.go:171","msg":"trace[1971837888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"113.634315ms","start":"2026-10-15T06:36:21.358684Z","end":"2026-10-15T06:36:21.472318Z","steps":["trace[1971837888] 'range keys from in-memory index tree' (duration: 113.360938ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:21.513088Z","caller":"traceutil/trace.go:171","msg":"trace[994787693] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"134.335847ms","start":"2026-10-15T06:36:21.378726Z","end":"2026-10-15T06:36:21.513062Z","steps":["trace[994787693] 'process raft request' (duration: 134.128102ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:21.513309Z","caller":"traceutil/trace.go:171","msg":"trace[781590156] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"121.84536ms","start":"2026-10-15T06:36:21.391443Z","end":"2026-10-15T06:36:21.513288Z","steps":["trace[781590156] 'process raft request' (duration: 121.564613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.945374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.312582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:36:21.945607Z","caller":"traceutil/trace.go:171","msg":"trace[1680358221] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"427.011096ms","start":"2026-10-15T06:36:21.518558Z","end":"2026-10-15T06:36:21.94557Z","steps":["trace[1680358221] 'process raft request' (duration: 148.402919ms)","trace[1680358221] 'compare' (duration: 278.142197ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:21.945702Z","caller":"traceutil/trace.go:171","msg":"trace[622996689] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"419.444495ms","start":"2026-10-15T06:36:21.526234Z","end":"2026-10-15T06:36:21.945679Z","steps":["trace[622996689] 'process raft request' (duration: 419.273451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.945792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:21.518536Z","time spent":"427.18245ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:36:21.945801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:21.526215Z","time spent":"419.535076ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:36:21.946071Z","caller":"traceutil/trace.go:171","msg":"trace[860041032] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"401.467149ms","start":"2026-10-15T06:36:21.544548Z","end":"2026-10-15T06:36:21.946015Z","steps":["trace[860041032] 'process raft request' (duration: 401.05911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.94614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:21.54453Z","time spent":"401.573891ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:36:21.946532Z","caller":"traceutil/trace.go:171","msg":"trace[1689827752] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"359.883908ms","start":"2026-10-15T06:36:21.586605Z","end":"2026-10-15T06:36:21.946489Z","steps":["trace[1689827752] 'process raft request' (duration: 359.486309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.946742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:21.586555Z","time spent":"360.076902ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:36:21.947074Z","caller":"traceutil/trace.go:171","msg":"trace[2101601710] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"356.70907ms","start":"2026-10-15T06:36:21.590343Z","end":"2026-10-15T06:36:21.947052Z","steps":["trace[2101601710] 'process raft request' (duration: 356.094627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.947155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:21.590318Z","time spent":"356.796242ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T06:36:21.947172Z","caller":"traceutil/trace.go:171","msg":"trace[795007048] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:666; }","duration":"393.713213ms","start":"2026-10-15T06:36:21.553426Z","end":"2026-10-15T06:36:21.947139Z","steps":["trace[795007048] 'read index received' (duration: 113.688255ms)","trace[795007048] 'applied index is now lower than readState.Index' (duration: 280.022318ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:36:21.947449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.012099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-10-15T06:36:21.947512Z","caller":"traceutil/trace.go:171","msg":"trace[975616152] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:647; }","duration":"394.118951ms","start":"2026-10-15T06:36:21.553374Z","end":"2026-10-15T06:36:21.947493Z","steps":["trace[975616152] 'agreement among raft nodes before linearized reading' (duration: 393.890697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.947555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:21.553353Z","time spent":"394.190244ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":10894,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-10-15T06:36:21.966228Z","caller":"traceutil/trace.go:171","msg":"trace[1356028760] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"171.234507ms","start":"2026-10-15T06:36:21.794959Z","end":"2026-10-15T06:36:21.966193Z","steps":["trace[1356028760] 'process raft request' (duration: 171.027153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.966286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.170713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:36:21.966383Z","caller":"traceutil/trace.go:171","msg":"trace[279170531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"217.375205ms","start":"2026-10-15T06:36:21.748989Z","end":"2026-10-15T06:36:21.966365Z","steps":["trace[279170531] 'agreement among raft nodes before linearized reading' (duration: 217.205782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:21.96643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.685378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-15T06:36:21.966542Z","caller":"traceutil/trace.go:171","msg":"trace[177377977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"221.880013ms","start":"2026-10-15T06:36:21.744637Z","end":"2026-10-15T06:36:21.966517Z","steps":["trace[177377977] 'agreement among raft nodes before linearized reading' (duration: 221.653369ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:28.919778Z","caller":"traceutil/trace.go:171","msg":"trace[57761885] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"145.925586ms","start":"2026-10-15T06:36:28.773834Z","end":"2026-10-15T06:36:28.91976Z","steps":["trace[57761885] 'process raft request' (duration: 145.807453ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:29.766141Z","caller":"traceutil/trace.go:171","msg":"trace[1678976833] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"188.171329ms","start":"2026-10-15T06:36:29.577945Z","end":"2026-10-15T06:36:29.766116Z","steps":["trace[1678976833] 'process raft request' (duration: 188.029667ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:29.995793Z","caller":"traceutil/trace.go:171","msg":"trace[642589306] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:742; }","duration":"250.485374ms","start":"2026-10-15T06:36:29.745281Z","end":"2026-10-15T06:36:29.995766Z","steps":["trace[642589306] 'read index received' (duration: 20.784465ms)","trace[642589306] 'applied index is now lower than readState.Index' (duration: 229.699629ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:29.995853Z","caller":"traceutil/trace.go:171","msg":"trace[1129224636] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"264.659679ms","start":"2026-10-15T06:36:29.73117Z","end":"2026-10-15T06:36:29.995829Z","steps":["trace[1129224636] 'process raft request' (duration: 195.033417ms)","trace[1129224636] 'compare' (duration: 69.421377ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:36:29.996004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.683529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:36:29.996098Z","caller":"traceutil/trace.go:171","msg":"trace[985863054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"250.847592ms","start":"2026-10-15T06:36:29.745232Z","end":"2026-10-15T06:36:29.996079Z","steps":["trace[985863054] 'agreement among raft nodes before linearized reading' (duration: 250.690369ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:49.705806Z","caller":"traceutil/trace.go:171","msg":"trace[1309580106] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"111.928158ms","start":"2026-10-15T06:36:49.59385Z","end":"2026-10-15T06:36:49.705778Z","steps":["trace[1309580106] 'process raft request' (duration: 111.781024ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:52.608813Z","caller":"traceutil/trace.go:171","msg":"trace[185815141] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"142.165415ms","start":"2026-10-15T06:36:52.466621Z","end":"2026-10-15T06:36:52.608787Z","steps":["trace[185815141] 'process raft request' (duration: 139.364225ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:37:22.326438Z","caller":"traceutil/trace.go:171","msg":"trace[1452228546] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"266.815367ms","start":"2026-10-15T06:37:22.059605Z","end":"2026-10-15T06:37:22.32642Z","steps":["trace[1452228546] 'process raft request' (duration: 266.602833ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:37:22.326401Z","caller":"traceutil/trace.go:171","msg":"trace[502389750] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"247.816841ms","start":"2026-10-15T06:37:22.078562Z","end":"2026-10-15T06:37:22.326379Z","steps":["trace[502389750] 'read index received' (duration: 247.707518ms)","trace[502389750] 'applied index is now lower than readState.Index' (duration: 108.413µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:37:22.326639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.026295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T06:37:22.326739Z","caller":"traceutil/trace.go:171","msg":"trace[1554631502] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1257; }","duration":"248.18895ms","start":"2026-10-15T06:37:22.078516Z","end":"2026-10-15T06:37:22.326705Z","steps":["trace[1554631502] 'agreement among raft nodes before linearized reading' (duration: 247.950024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:12.336296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.418646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:38:12.336479Z","caller":"traceutil/trace.go:171","msg":"trace[432157106] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2021; }","duration":"272.145848ms","start":"2026-10-15T06:38:12.064319Z","end":"2026-10-15T06:38:12.336465Z","steps":["trace[432157106] 'read index received' (duration: 59.513618ms)","trace[432157106] 'applied index is now lower than readState.Index' (duration: 212.63155ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:38:12.336531Z","caller":"traceutil/trace.go:171","msg":"trace[321403076] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"296.900679ms","start":"2026-10-15T06:38:12.039622Z","end":"2026-10-15T06:38:12.336523Z","steps":["trace[321403076] 'process raft request' (duration: 84.172197ms)","trace[321403076] 'compare' (duration: 212.202731ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:38:12.336601Z","caller":"traceutil/trace.go:171","msg":"trace[2078616648] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"286.339682ms","start":"2026-10-15T06:38:12.050199Z","end":"2026-10-15T06:38:12.336539Z","steps":["trace[2078616648] 'process raft request' (duration: 286.196469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:12.337083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.675289ms","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-10-15T06:38:12.337158Z","caller":"traceutil/trace.go:171","msg":"trace[2045687121] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1946; }","duration":"272.870774ms","start":"2026-10-15T06:38:12.064267Z","end":"2026-10-15T06:38:12.337138Z","steps":["trace[2045687121] 'agreement among raft nodes before linearized reading' (duration: 272.375954ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:12.478907Z","caller":"traceutil/trace.go:171","msg":"trace[1133218199] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"142.382198ms","start":"2026-10-15T06:38:12.336498Z","end":"2026-10-15T06:38:12.47888Z","steps":["trace[1133218199] 'read index received' (duration: 142.170164ms)","trace[1133218199] 'applied index is now lower than readState.Index' (duration: 210.804µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:38:12.478976Z","caller":"traceutil/trace.go:171","msg":"trace[501911747] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"166.437496ms","start":"2026-10-15T06:38:12.312499Z","end":"2026-10-15T06:38:12.478937Z","steps":["trace[501911747] 'process raft request' (duration: 166.239692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:12.479141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.525923ms","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-10-15T06:38:12.479225Z","caller":"traceutil/trace.go:171","msg":"trace[1629209795] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1947; }","duration":"207.643416ms","start":"2026-10-15T06:38:12.271561Z","end":"2026-10-15T06:38:12.479204Z","steps":["trace[1629209795] 'agreement among raft nodes before linearized reading' (duration: 207.415282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:12.479149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.961752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c133168-cf1a-4ca6-bca0-531897dd4eca\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-15T06:38:12.479364Z","caller":"traceutil/trace.go:171","msg":"trace[646882865] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c133168-cf1a-4ca6-bca0-531897dd4eca; range_end:; response_count:1; response_revision:1947; }","duration":"222.230587ms","start":"2026-10-15T06:38:12.257118Z","end":"2026-10-15T06:38:12.479348Z","steps":["trace[646882865] 'agreement among raft nodes before linearized reading' (duration: 221.86338ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:12.79992Z","caller":"traceutil/trace.go:171","msg":"trace[1274912504] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"151.298883ms","start":"2026-10-15T06:38:12.648598Z","end":"2026-10-15T06:38:12.799897Z","steps":["trace[1274912504] 'process raft request' (duration: 151.118029ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:29.009607Z","caller":"traceutil/trace.go:171","msg":"trace[435928536] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"134.685411ms","start":"2026-10-15T06:38:28.874902Z","end":"2026-10-15T06:38:29.009587Z","steps":["trace[435928536] 'process raft request' (duration: 134.555178ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:29.047768Z","caller":"traceutil/trace.go:171","msg":"trace[1815011537] linearizableReadLoop","detail":"{readStateIndex:2166; appliedIndex:2164; }","duration":"120.860815ms","start":"2026-10-15T06:38:28.926882Z","end":"2026-10-15T06:38:29.047743Z","steps":["trace[1815011537] 'read index received' (duration: 82.604735ms)","trace[1815011537] 'applied index is now lower than readState.Index' (duration: 38.25509ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:38:29.04791Z","caller":"traceutil/trace.go:171","msg":"trace[1419024644] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"140.458069ms","start":"2026-10-15T06:38:28.907425Z","end":"2026-10-15T06:38:29.047883Z","steps":["trace[1419024644] 'process raft request' (duration: 140.189044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.048106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.192742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T06:38:29.048164Z","caller":"traceutil/trace.go:171","msg":"trace[643782400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2085; }","duration":"121.318044ms","start":"2026-10-15T06:38:28.926833Z","end":"2026-10-15T06:38:29.048151Z","steps":["trace[643782400] 'agreement among raft nodes before linearized reading' (duration: 121.075329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.048291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.096009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T06:38:29.048383Z","caller":"traceutil/trace.go:171","msg":"trace[32219652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2085; }","duration":"104.246381ms","start":"2026-10-15T06:38:28.944114Z","end":"2026-10-15T06:38:29.04836Z","steps":["trace[32219652] 'agreement among raft nodes before linearized reading' (duration: 104.010647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.048481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.432979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-15T06:38:29.04853Z","caller":"traceutil/trace.go:171","msg":"trace[751698209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2085; }","duration":"110.49355ms","start":"2026-10-15T06:38:28.938024Z","end":"2026-10-15T06:38:29.048518Z","steps":["trace[751698209] 'agreement among raft nodes before linearized reading' (duration: 109.878988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:39:09.014861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.662808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:39:09.015142Z","caller":"traceutil/trace.go:171","msg":"trace[1976405597] linearizableReadLoop","detail":"{readStateIndex:2392; appliedIndex:2390; }","duration":"268.242388ms","start":"2026-10-15T06:39:08.746883Z","end":"2026-10-15T06:39:09.015126Z","steps":["trace[1976405597] 'read index received' (duration: 105.238123ms)","trace[1976405597] 'applied index is now lower than readState.Index' (duration: 163.003055ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:39:09.015292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.405992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:39:09.015346Z","caller":"traceutil/trace.go:171","msg":"trace[850944673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2302; }","duration":"268.503613ms","start":"2026-10-15T06:39:08.746828Z","end":"2026-10-15T06:39:09.015332Z","steps":["trace[850944673] 'agreement among raft nodes before linearized reading' (duration: 268.417082ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:09.015367Z","caller":"traceutil/trace.go:171","msg":"trace[224502879] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"430.762113ms","start":"2026-10-15T06:39:08.584555Z","end":"2026-10-15T06:39:09.015317Z","steps":["trace[224502879] 'process raft request' (duration: 267.505573ms)","trace[224502879] 'compare' (duration: 162.27532ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:39:09.015544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.96798ms","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-10-15T06:39:09.015681Z","caller":"traceutil/trace.go:171","msg":"trace[435410509] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2302; }","duration":"249.136673ms","start":"2026-10-15T06:39:08.766521Z","end":"2026-10-15T06:39:09.015657Z","steps":["trace[435410509] 'agreement among raft nodes before linearized reading' (duration: 248.764235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:39:09.015582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:39:08.58453Z","time spent":"430.940456ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:39:09.015393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.835732ms","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:5"} {"level":"info","ts":"2026-10-15T06:39:09.016325Z","caller":"traceutil/trace.go:171","msg":"trace[1670809044] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2302; }","duration":"197.758412ms","start":"2026-10-15T06:39:08.818489Z","end":"2026-10-15T06:39:09.016248Z","steps":["trace[1670809044] 'agreement among raft nodes before linearized reading' (duration: 196.817842ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:09.015624Z","caller":"traceutil/trace.go:171","msg":"trace[346779800] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"380.592137ms","start":"2026-10-15T06:39:08.63477Z","end":"2026-10-15T06:39:09.015362Z","steps":["trace[346779800] 'process raft request' (duration: 380.24974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:39:09.016683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:39:08.63475Z","time spent":"381.754921ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:39:43.507276Z","caller":"traceutil/trace.go:171","msg":"trace[1088643465] linearizableReadLoop","detail":"{readStateIndex:2770; appliedIndex:2769; }","duration":"100.147719ms","start":"2026-10-15T06:39:43.407103Z","end":"2026-10-15T06:39:43.507251Z","steps":["trace[1088643465] 'read index received' (duration: 100.029946ms)","trace[1088643465] 'applied index is now lower than readState.Index' (duration: 116.963µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:39:43.507469Z","caller":"traceutil/trace.go:171","msg":"trace[1190567677] transaction","detail":"{read_only:false; response_revision:2664; number_of_response:1; }","duration":"145.256003ms","start":"2026-10-15T06:39:43.362163Z","end":"2026-10-15T06:39:43.507419Z","steps":["trace[1190567677] 'process raft request' (duration: 144.955247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:39:43.507709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.598538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-15T06:39:43.507747Z","caller":"traceutil/trace.go:171","msg":"trace[1528834327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2664; }","duration":"100.68102ms","start":"2026-10-15T06:39:43.407054Z","end":"2026-10-15T06:39:43.507735Z","steps":["trace[1528834327] 'agreement among raft nodes before linearized reading' (duration: 100.333373ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:49.328175Z","caller":"traceutil/trace.go:171","msg":"trace[582190823] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"143.129869ms","start":"2026-10-15T06:39:49.185013Z","end":"2026-10-15T06:39:49.328143Z","steps":["trace[582190823] 'process raft request' (duration: 142.888614ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:17.639356Z","caller":"traceutil/trace.go:171","msg":"trace[1607655944] linearizableReadLoop","detail":"{readStateIndex:2982; appliedIndex:2981; }","duration":"191.200106ms","start":"2026-10-15T06:40:17.448132Z","end":"2026-10-15T06:40:17.639332Z","steps":["trace[1607655944] 'read index received' (duration: 190.963011ms)","trace[1607655944] 'applied index is now lower than readState.Index' (duration: 236.135µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:40:17.639438Z","caller":"traceutil/trace.go:171","msg":"trace[690351818] transaction","detail":"{read_only:false; response_revision:2869; number_of_response:1; }","duration":"198.519759ms","start":"2026-10-15T06:40:17.440886Z","end":"2026-10-15T06:40:17.639405Z","steps":["trace[690351818] 'process raft request' (duration: 198.302655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:40:17.639598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.441012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-15T06:40:17.639665Z","caller":"traceutil/trace.go:171","msg":"trace[407926222] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2869; }","duration":"191.550353ms","start":"2026-10-15T06:40:17.448102Z","end":"2026-10-15T06:40:17.639652Z","steps":["trace[407926222] 'agreement among raft nodes before linearized reading' (duration: 191.342319ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:17.777511Z","caller":"traceutil/trace.go:171","msg":"trace[32724260] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"105.625783ms","start":"2026-10-15T06:40:17.671266Z","end":"2026-10-15T06:40:17.776891Z","steps":["trace[32724260] 'process raft request' (duration: 41.122752ms)","trace[32724260] 'compare' (duration: 63.860668ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:08.656297Z","caller":"traceutil/trace.go:171","msg":"trace[700531687] linearizableReadLoop","detail":"{readStateIndex:4106; appliedIndex:4105; }","duration":"109.893636ms","start":"2026-10-15T06:43:08.546376Z","end":"2026-10-15T06:43:08.656269Z","steps":["trace[700531687] 'read index received' (duration: 109.712262ms)","trace[700531687] 'applied index is now lower than readState.Index' (duration: 177.734µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:08.656377Z","caller":"traceutil/trace.go:171","msg":"trace[1954578420] transaction","detail":"{read_only:false; response_revision:3952; number_of_response:1; }","duration":"115.025356ms","start":"2026-10-15T06:43:08.541326Z","end":"2026-10-15T06:43:08.656351Z","steps":["trace[1954578420] 'process raft request' (duration: 114.778152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:08.656772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.189972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-10-15T06:43:08.656848Z","caller":"traceutil/trace.go:171","msg":"trace[1664966577] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3952; }","duration":"110.513828ms","start":"2026-10-15T06:43:08.546315Z","end":"2026-10-15T06:43:08.656829Z","steps":["trace[1664966577] 'agreement among raft nodes before linearized reading' (duration: 110.11223ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:08.667125Z","caller":"traceutil/trace.go:171","msg":"trace[1833918237] transaction","detail":"{read_only:false; response_revision:3953; number_of_response:1; }","duration":"115.546177ms","start":"2026-10-15T06:43:08.55155Z","end":"2026-10-15T06:43:08.667096Z","steps":["trace[1833918237] 'process raft request' (duration: 115.242241ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:45:02.43679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.707973ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:45:02.436928Z","caller":"traceutil/trace.go:171","msg":"trace[933670317] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5290; }","duration":"132.908647ms","start":"2026-10-15T06:45:02.304Z","end":"2026-10-15T06:45:02.436909Z","steps":["trace[933670317] 'range keys from in-memory index tree' (duration: 132.687932ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:02.437207Z","caller":"traceutil/trace.go:171","msg":"trace[682143812] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"325.974318ms","start":"2026-10-15T06:45:02.111205Z","end":"2026-10-15T06:45:02.437179Z","steps":["trace[682143812] 'process raft request' (duration: 244.210457ms)","trace[682143812] 'compare' (duration: 81.201498ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:45:02.437308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:45:02.111182Z","time spent":"326.0843ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":879,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T06:45:02.494824Z","caller":"traceutil/trace.go:171","msg":"trace[1169829401] linearizableReadLoop","detail":"{readStateIndex:5493; appliedIndex:5491; }","duration":"244.207157ms","start":"2026-10-15T06:45:02.250577Z","end":"2026-10-15T06:45:02.494784Z","steps":["trace[1169829401] 'read index received' (duration: 104.941352ms)","trace[1169829401] 'applied index is now lower than readState.Index' (duration: 139.261335ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:45:02.495165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.493594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7448f922-5a97-49dd-8172-2db8edcab8bb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T06:45:02.495206Z","caller":"traceutil/trace.go:171","msg":"trace[48162220] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7448f922-5a97-49dd-8172-2db8edcab8bb; range_end:; response_count:1; response_revision:5292; }","duration":"171.589714ms","start":"2026-10-15T06:45:02.323604Z","end":"2026-10-15T06:45:02.495194Z","steps":["trace[48162220] 'agreement among raft nodes before linearized reading' (duration: 171.347239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:45:02.495179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.579595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7448f922-5a97-49dd-8172-2db8edcab8bb\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T06:45:02.495261Z","caller":"traceutil/trace.go:171","msg":"trace[795528887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7448f922-5a97-49dd-8172-2db8edcab8bb; range_end:; response_count:1; response_revision:5292; }","duration":"244.76496ms","start":"2026-10-15T06:45:02.250476Z","end":"2026-10-15T06:45:02.495241Z","steps":["trace[795528887] 'agreement among raft nodes before linearized reading' (duration: 244.452212ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:02.751549Z","caller":"traceutil/trace.go:171","msg":"trace[2136345437] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"127.155773ms","start":"2026-10-15T06:45:02.624314Z","end":"2026-10-15T06:45:02.75147Z","steps":["trace[2136345437] 'process raft request' (duration: 53.792975ms)","trace[2136345437] 'compare' (duration: 73.053691ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:45:02.908566Z","caller":"traceutil/trace.go:171","msg":"trace[1004651539] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"265.523819ms","start":"2026-10-15T06:45:02.643017Z","end":"2026-10-15T06:45:02.908541Z","steps":["trace[1004651539] 'process raft request' (duration: 264.098038ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:02.914268Z","caller":"traceutil/trace.go:171","msg":"trace[1387653942] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"157.400918ms","start":"2026-10-15T06:45:02.756836Z","end":"2026-10-15T06:45:02.914236Z","steps":["trace[1387653942] 'process raft request' (duration: 157.245775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:45:04.663241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.488642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:45:04.663321Z","caller":"traceutil/trace.go:171","msg":"trace[596502028] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"157.414878ms","start":"2026-10-15T06:45:04.505894Z","end":"2026-10-15T06:45:04.663309Z","steps":["trace[596502028] 'read index received' (duration: 16.754822ms)","trace[596502028] 'applied index is now lower than readState.Index' (duration: 140.658636ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:45:04.663369Z","caller":"traceutil/trace.go:171","msg":"trace[1875397879] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"158.194055ms","start":"2026-10-15T06:45:04.50514Z","end":"2026-10-15T06:45:04.663334Z","steps":["trace[1875397879] 'process raft request' (duration: 17.55683ms)","trace[1875397879] 'compare' (duration: 140.39434ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:45:04.663487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.602513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7448f922-5a97-49dd-8172-2db8edcab8bb\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-10-15T06:45:04.663511Z","caller":"traceutil/trace.go:171","msg":"trace[1611247304] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7448f922-5a97-49dd-8172-2db8edcab8bb; range_end:; response_count:1; response_revision:5332; }","duration":"157.665584ms","start":"2026-10-15T06:45:04.505838Z","end":"2026-10-15T06:45:04.663504Z","steps":["trace[1611247304] 'agreement among raft nodes before linearized reading' (duration: 157.532581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:45:04.664078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.34167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786932"} {"level":"info","ts":"2026-10-15T06:45:04.66413Z","caller":"traceutil/trace.go:171","msg":"trace[2072601347] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5332; }","duration":"141.439782ms","start":"2026-10-15T06:45:04.522678Z","end":"2026-10-15T06:45:04.664118Z","steps":["trace[2072601347] 'agreement among raft nodes before linearized reading' (duration: 140.86189ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:17.607826Z","caller":"traceutil/trace.go:171","msg":"trace[1319683402] linearizableReadLoop","detail":"{readStateIndex:5772; appliedIndex:5771; }","duration":"131.696051ms","start":"2026-10-15T06:45:17.476099Z","end":"2026-10-15T06:45:17.607795Z","steps":["trace[1319683402] 'read index received' (duration: 131.311363ms)","trace[1319683402] 'applied index is now lower than readState.Index' (duration: 383.418µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:45:17.607914Z","caller":"traceutil/trace.go:171","msg":"trace[1308687192] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"185.279202ms","start":"2026-10-15T06:45:17.422595Z","end":"2026-10-15T06:45:17.607874Z","steps":["trace[1308687192] 'process raft request' (duration: 184.844043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:45:17.608154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.031448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-hrs7l\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-10-15T06:45:17.608216Z","caller":"traceutil/trace.go:171","msg":"trace[661881404] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-hrs7l; range_end:; response_count:1; response_revision:5562; }","duration":"132.133941ms","start":"2026-10-15T06:45:17.476062Z","end":"2026-10-15T06:45:17.608196Z","steps":["trace[661881404] 'agreement among raft nodes before linearized reading' (duration: 131.883936ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:17.837988Z","caller":"traceutil/trace.go:171","msg":"trace[209679337] linearizableReadLoop","detail":"{readStateIndex:5773; appliedIndex:5772; }","duration":"160.078146ms","start":"2026-10-15T06:45:17.677863Z","end":"2026-10-15T06:45:17.837941Z","steps":["trace[209679337] 'read index received' (duration: 159.835831ms)","trace[209679337] 'applied index is now lower than readState.Index' (duration: 240.975µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:45:17.838111Z","caller":"traceutil/trace.go:171","msg":"trace[673394938] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"218.192074ms","start":"2026-10-15T06:45:17.619908Z","end":"2026-10-15T06:45:17.8381Z","steps":["trace[673394938] 'process raft request' (duration: 217.849387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:45:17.838338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.467324ms","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-10-15T06:45:17.838378Z","caller":"traceutil/trace.go:171","msg":"trace[10107375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5563; }","duration":"160.557626ms","start":"2026-10-15T06:45:17.677812Z","end":"2026-10-15T06:45:17.838369Z","steps":["trace[10107375] 'agreement among raft nodes before linearized reading' (duration: 160.421264ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:24.769571Z","caller":"traceutil/trace.go:171","msg":"trace[2018594587] linearizableReadLoop","detail":"{readStateIndex:5950; appliedIndex:5949; }","duration":"150.440186ms","start":"2026-10-15T06:45:24.619097Z","end":"2026-10-15T06:45:24.769537Z","steps":["trace[2018594587] 'read index received' (duration: 150.198181ms)","trace[2018594587] 'applied index is now lower than readState.Index' (duration: 240.895µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:45:24.769634Z","caller":"traceutil/trace.go:171","msg":"trace[1428864701] transaction","detail":"{read_only:false; response_revision:5737; number_of_response:1; }","duration":"197.850124ms","start":"2026-10-15T06:45:24.571741Z","end":"2026-10-15T06:45:24.769591Z","steps":["trace[1428864701] 'process raft request' (duration: 197.655589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:45:24.76985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.695453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-15T06:45:24.769926Z","caller":"traceutil/trace.go:171","msg":"trace[98584453] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5737; }","duration":"150.867126ms","start":"2026-10-15T06:45:24.619046Z","end":"2026-10-15T06:45:24.769913Z","steps":["trace[98584453] 'agreement among raft nodes before linearized reading' (duration: 150.640801ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:24.945854Z","caller":"traceutil/trace.go:171","msg":"trace[49324086] transaction","detail":"{read_only:false; response_revision:5738; number_of_response:1; }","duration":"171.548263ms","start":"2026-10-15T06:45:24.774269Z","end":"2026-10-15T06:45:24.945817Z","steps":["trace[49324086] 'process raft request' (duration: 160.669168ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:24.991063Z","caller":"traceutil/trace.go:171","msg":"trace[1102366131] transaction","detail":"{read_only:false; response_revision:5739; number_of_response:1; }","duration":"215.361333ms","start":"2026-10-15T06:45:24.775654Z","end":"2026-10-15T06:45:24.991015Z","steps":["trace[1102366131] 'process raft request' (duration: 215.136418ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:24.991571Z","caller":"traceutil/trace.go:171","msg":"trace[1540147352] transaction","detail":"{read_only:false; response_revision:5740; number_of_response:1; }","duration":"215.539756ms","start":"2026-10-15T06:45:24.776003Z","end":"2026-10-15T06:45:24.991543Z","steps":["trace[1540147352] 'process raft request' (duration: 214.929874ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:24.991639Z","caller":"traceutil/trace.go:171","msg":"trace[194235980] transaction","detail":"{read_only:false; response_revision:5741; number_of_response:1; }","duration":"214.830031ms","start":"2026-10-15T06:45:24.77679Z","end":"2026-10-15T06:45:24.99162Z","steps":["trace[194235980] 'process raft request' (duration: 214.471774ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:24.991684Z","caller":"traceutil/trace.go:171","msg":"trace[1662799974] transaction","detail":"{read_only:false; response_revision:5744; number_of_response:1; }","duration":"210.530369ms","start":"2026-10-15T06:45:24.78114Z","end":"2026-10-15T06:45:24.991671Z","steps":["trace[1662799974] 'process raft request' (duration: 210.364165ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:24.991566Z","caller":"traceutil/trace.go:171","msg":"trace[1599035109] transaction","detail":"{read_only:false; response_revision:5742; number_of_response:1; }","duration":"214.218218ms","start":"2026-10-15T06:45:24.777311Z","end":"2026-10-15T06:45:24.991529Z","steps":["trace[1599035109] 'process raft request' (duration: 214.067484ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:24.991592Z","caller":"traceutil/trace.go:171","msg":"trace[1496300200] transaction","detail":"{read_only:false; response_revision:5743; number_of_response:1; }","duration":"211.482548ms","start":"2026-10-15T06:45:24.780087Z","end":"2026-10-15T06:45:24.99157Z","steps":["trace[1496300200] 'process raft request' (duration: 211.357426ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:45:25.202085Z","caller":"traceutil/trace.go:171","msg":"trace[1910633171] transaction","detail":"{read_only:false; response_revision:5745; number_of_response:1; }","duration":"192.698912ms","start":"2026-10-15T06:45:25.00935Z","end":"2026-10-15T06:45:25.202049Z","steps":["trace[1910633171] 'process raft request' (duration: 159.388431ms)","trace[1910633171] 'compare' (duration: 32.428532ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:45:33.566402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2956} {"level":"info","ts":"2026-10-15T06:45:33.65211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2956,"took":"84.174873ms","hash":782534325,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":32284672,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T06:45:33.652162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":782534325,"revision":2956,"compact-revision":-1} {"level":"info","ts":"2026-10-15T06:50:33.580692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5853} {"level":"info","ts":"2026-10-15T06:50:33.715234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5853,"took":"131.633119ms","hash":72599098,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":26439680,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T06:50:33.715307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":72599098,"revision":5853,"compact-revision":2956} {"level":"info","ts":"2026-10-15T06:51:39.54724Z","caller":"traceutil/trace.go:171","msg":"trace[500257620] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"261.509171ms","start":"2026-10-15T06:51:39.285709Z","end":"2026-10-15T06:51:39.547219Z","steps":["trace[500257620] 'process raft request' (duration: 261.382988ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:39.754653Z","caller":"traceutil/trace.go:171","msg":"trace[1628951420] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8897; }","duration":"115.133504ms","start":"2026-10-15T06:51:39.639496Z","end":"2026-10-15T06:51:39.75463Z","steps":["trace[1628951420] 'read index received' (duration: 114.548101ms)","trace[1628951420] 'applied index is now lower than readState.Index' (duration: 584.163µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:51:39.755047Z","caller":"traceutil/trace.go:171","msg":"trace[1727021136] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"404.752158ms","start":"2026-10-15T06:51:39.350258Z","end":"2026-10-15T06:51:39.75501Z","steps":["trace[1727021136] 'process raft request' (duration: 403.806828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:39.755195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.648163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bx4nz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T06:51:39.755252Z","caller":"traceutil/trace.go:171","msg":"trace[654999692] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bx4nz; range_end:; response_count:1; response_revision:8595; }","duration":"115.760116ms","start":"2026-10-15T06:51:39.639478Z","end":"2026-10-15T06:51:39.755238Z","steps":["trace[654999692] 'agreement among raft nodes before linearized reading' (duration: 115.549472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:51:39.755271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:51:39.350232Z","time spent":"404.909321ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:51:53.223971Z","caller":"traceutil/trace.go:171","msg":"trace[276070063] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"117.497824ms","start":"2026-10-15T06:51:53.106444Z","end":"2026-10-15T06:51:53.223942Z","steps":["trace[276070063] 'process raft request' (duration: 117.358551ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:53.481039Z","caller":"traceutil/trace.go:171","msg":"trace[708113589] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"235.168282ms","start":"2026-10-15T06:51:53.24581Z","end":"2026-10-15T06:51:53.480978Z","steps":["trace[708113589] 'process raft request' (duration: 213.627586ms)","trace[708113589] 'compare' (duration: 21.181817ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:52:21.918443Z","caller":"traceutil/trace.go:171","msg":"trace[1728599140] linearizableReadLoop","detail":"{readStateIndex:9187; appliedIndex:9186; }","duration":"170.741041ms","start":"2026-10-15T06:52:21.747677Z","end":"2026-10-15T06:52:21.918418Z","steps":["trace[1728599140] 'read index received' (duration: 170.659589ms)","trace[1728599140] 'applied index is now lower than readState.Index' (duration: 80.272µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:52:21.918626Z","caller":"traceutil/trace.go:171","msg":"trace[1742181748] transaction","detail":"{read_only:false; response_revision:8874; number_of_response:1; }","duration":"342.361349ms","start":"2026-10-15T06:52:21.576254Z","end":"2026-10-15T06:52:21.918615Z","steps":["trace[1742181748] 'process raft request' (duration: 342.026581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:52:21.918754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:52:21.576231Z","time spent":"342.432301ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:52:21.918925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.314238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:52:21.919042Z","caller":"traceutil/trace.go:171","msg":"trace[870596106] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8874; }","duration":"160.476921ms","start":"2026-10-15T06:52:21.75854Z","end":"2026-10-15T06:52:21.919017Z","steps":["trace[870596106] 'agreement among raft nodes before linearized reading' (duration: 160.314758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:52:21.919212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.529927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:52:21.919261Z","caller":"traceutil/trace.go:171","msg":"trace[2031037713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8874; }","duration":"171.609979ms","start":"2026-10-15T06:52:21.747638Z","end":"2026-10-15T06:52:21.919248Z","steps":["trace[2031037713] 'agreement among raft nodes before linearized reading' (duration: 171.538807ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:22.082334Z","caller":"traceutil/trace.go:171","msg":"trace[237176865] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"113.339856ms","start":"2026-10-15T06:52:21.968966Z","end":"2026-10-15T06:52:22.082306Z","steps":["trace[237176865] 'process raft request' (duration: 105.634022ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:24.579158Z","caller":"traceutil/trace.go:171","msg":"trace[208036192] linearizableReadLoop","detail":"{readStateIndex:9202; appliedIndex:9201; }","duration":"148.940326ms","start":"2026-10-15T06:52:24.430193Z","end":"2026-10-15T06:52:24.579133Z","steps":["trace[208036192] 'read index received' (duration: 96.839295ms)","trace[208036192] 'applied index is now lower than readState.Index' (duration: 52.099791ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:52:24.579216Z","caller":"traceutil/trace.go:171","msg":"trace[763384514] transaction","detail":"{read_only:false; response_revision:8888; number_of_response:1; }","duration":"158.493879ms","start":"2026-10-15T06:52:24.420682Z","end":"2026-10-15T06:52:24.579176Z","steps":["trace[763384514] 'process raft request' (duration: 106.479421ms)","trace[763384514] 'compare' (duration: 51.870245ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:52:24.579373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.159981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T06:52:24.579431Z","caller":"traceutil/trace.go:171","msg":"trace[711229925] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8888; }","duration":"149.286243ms","start":"2026-10-15T06:52:24.430127Z","end":"2026-10-15T06:52:24.579414Z","steps":["trace[711229925] 'agreement among raft nodes before linearized reading' (duration: 149.15302ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:32.214774Z","caller":"traceutil/trace.go:171","msg":"trace[694939496] transaction","detail":"{read_only:false; response_revision:8928; number_of_response:1; }","duration":"173.635082ms","start":"2026-10-15T06:52:32.041112Z","end":"2026-10-15T06:52:32.214747Z","steps":["trace[694939496] 'process raft request' (duration: 173.473748ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:32.793212Z","caller":"traceutil/trace.go:171","msg":"trace[465819229] transaction","detail":"{read_only:false; response_revision:8934; number_of_response:1; }","duration":"146.293079ms","start":"2026-10-15T06:52:32.646887Z","end":"2026-10-15T06:52:32.79318Z","steps":["trace[465819229] 'process raft request' (duration: 146.253149ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:32.793348Z","caller":"traceutil/trace.go:171","msg":"trace[884721854] transaction","detail":"{read_only:false; response_revision:8933; number_of_response:1; }","duration":"146.709508ms","start":"2026-10-15T06:52:32.646629Z","end":"2026-10-15T06:52:32.793339Z","steps":["trace[884721854] 'process raft request' (duration: 102.653749ms)","trace[884721854] 'compare' (duration: 43.752862ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:52:34.832434Z","caller":"traceutil/trace.go:171","msg":"trace[1192640005] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8951; }","duration":"132.524136ms","start":"2026-10-15T06:52:34.699892Z","end":"2026-10-15T06:52:34.832417Z","steps":["trace[1192640005] 'process raft request' (duration: 120.248484ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:34.989293Z","caller":"traceutil/trace.go:171","msg":"trace[1082753781] transaction","detail":"{read_only:false; response_revision:8952; number_of_response:1; }","duration":"278.735113ms","start":"2026-10-15T06:52:34.71052Z","end":"2026-10-15T06:52:34.989255Z","steps":["trace[1082753781] 'process raft request' (duration: 236.690087ms)","trace[1082753781] 'compare' (duration: 41.811681ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:52:34.989302Z","caller":"traceutil/trace.go:171","msg":"trace[1936906965] linearizableReadLoop","detail":"{readStateIndex:9268; appliedIndex:9266; }","duration":"192.681848ms","start":"2026-10-15T06:52:34.796584Z","end":"2026-10-15T06:52:34.989265Z","steps":["trace[1936906965] 'read index received' (duration: 23.550932ms)","trace[1936906965] 'applied index is now lower than readState.Index' (duration: 169.129286ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:52:34.989666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.205937ms","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-10-15T06:52:34.989753Z","caller":"traceutil/trace.go:171","msg":"trace[1774867834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8953; }","duration":"192.314691ms","start":"2026-10-15T06:52:34.797425Z","end":"2026-10-15T06:52:34.98974Z","steps":["trace[1774867834] 'agreement among raft nodes before linearized reading' (duration: 192.123977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:52:34.989697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.332759ms","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:5"} {"level":"warn","ts":"2026-10-15T06:52:34.989673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.072326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T06:52:34.989888Z","caller":"traceutil/trace.go:171","msg":"trace[1079305534] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8953; }","duration":"160.545203ms","start":"2026-10-15T06:52:34.829306Z","end":"2026-10-15T06:52:34.989851Z","steps":["trace[1079305534] 'agreement among raft nodes before linearized reading' (duration: 160.303798ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:34.989948Z","caller":"traceutil/trace.go:171","msg":"trace[489387744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8953; }","duration":"193.357852ms","start":"2026-10-15T06:52:34.796556Z","end":"2026-10-15T06:52:34.989914Z","steps":["trace[489387744] 'agreement among raft nodes before linearized reading' (duration: 192.952604ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:52:35.853294Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243d.wal"} {"level":"info","ts":"2026-10-15T06:53:42.962342Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T06:53:42.97053Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T06:53:42.970628Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T06:55:33.596717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8015} {"level":"info","ts":"2026-10-15T06:55:33.671381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8015,"took":"72.517736ms","hash":3322698920,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":27971584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T06:55:33.671851Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3322698920,"revision":8015,"compact-revision":5853} {"level":"info","ts":"2026-10-15T07:00:33.603991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10412} {"level":"info","ts":"2026-10-15T07:00:33.690815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10412,"took":"84.252229ms","hash":3625804355,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":25784320,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T07:00:33.690886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3625804355,"revision":10412,"compact-revision":8015} {"level":"info","ts":"2026-10-15T07:05:33.613585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12588} {"level":"info","ts":"2026-10-15T07:05:33.702156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12588,"took":"86.15366ms","hash":2119605200,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":27697152,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T07:05:33.702233Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2119605200,"revision":12588,"compact-revision":10412} {"level":"info","ts":"2026-10-15T07:06:10.317784Z","caller":"traceutil/trace.go:171","msg":"trace[309888114] linearizableReadLoop","detail":"{readStateIndex:15370; appliedIndex:15368; }","duration":"128.088566ms","start":"2026-10-15T07:06:10.189651Z","end":"2026-10-15T07:06:10.317739Z","steps":["trace[309888114] 'read index received' (duration: 56.845655ms)","trace[309888114] 'applied index is now lower than readState.Index' (duration: 71.241541ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:06:10.317943Z","caller":"traceutil/trace.go:171","msg":"trace[298116290] transaction","detail":"{read_only:false; response_revision:14859; number_of_response:1; }","duration":"139.681984ms","start":"2026-10-15T07:06:10.178178Z","end":"2026-10-15T07:06:10.31786Z","steps":["trace[298116290] 'process raft request' (duration: 137.110839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:10.318144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.431284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-15T07:06:10.318231Z","caller":"traceutil/trace.go:171","msg":"trace[266252900] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:14859; }","duration":"128.588397ms","start":"2026-10-15T07:06:10.189621Z","end":"2026-10-15T07:06:10.318209Z","steps":["trace[266252900] 'agreement among raft nodes before linearized reading' (duration: 128.291601ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:19.163418Z","caller":"traceutil/trace.go:171","msg":"trace[1926739625] linearizableReadLoop","detail":"{readStateIndex:15430; appliedIndex:15429; }","duration":"190.470168ms","start":"2026-10-15T07:06:18.97291Z","end":"2026-10-15T07:06:19.163381Z","steps":["trace[1926739625] 'read index received' (duration: 189.949047ms)","trace[1926739625] 'applied index is now lower than readState.Index' (duration: 520.131µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:06:19.163862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.16699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T07:06:19.163912Z","caller":"traceutil/trace.go:171","msg":"trace[437462123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14916; }","duration":"108.266031ms","start":"2026-10-15T07:06:19.055633Z","end":"2026-10-15T07:06:19.163899Z","steps":["trace[437462123] 'agreement among raft nodes before linearized reading' (duration: 108.093718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:19.164089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.152502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:06:19.164165Z","caller":"traceutil/trace.go:171","msg":"trace[1875364749] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:14916; }","duration":"191.243755ms","start":"2026-10-15T07:06:18.972903Z","end":"2026-10-15T07:06:19.164146Z","steps":["trace[1875364749] 'agreement among raft nodes before linearized reading' (duration: 191.133012ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:19.164468Z","caller":"traceutil/trace.go:171","msg":"trace[30396388] transaction","detail":"{read_only:false; response_revision:14916; number_of_response:1; }","duration":"331.46271ms","start":"2026-10-15T07:06:18.832063Z","end":"2026-10-15T07:06:19.163526Z","steps":["trace[30396388] 'process raft request' (duration: 331.119002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:19.16467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:06:18.832041Z","time spent":"332.465351ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:06:28.787833Z","caller":"traceutil/trace.go:171","msg":"trace[377265075] transaction","detail":"{read_only:false; response_revision:15087; number_of_response:1; }","duration":"135.254949ms","start":"2026-10-15T07:06:28.652556Z","end":"2026-10-15T07:06:28.787811Z","steps":["trace[377265075] 'process raft request' (duration: 135.121006ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:28.787904Z","caller":"traceutil/trace.go:171","msg":"trace[1046188442] linearizableReadLoop","detail":"{readStateIndex:15604; appliedIndex:15604; }","duration":"110.107952ms","start":"2026-10-15T07:06:28.677749Z","end":"2026-10-15T07:06:28.787857Z","steps":["trace[1046188442] 'read index received' (duration: 110.084171ms)","trace[1046188442] 'applied index is now lower than readState.Index' (duration: 19.28µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:06:28.78929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.581052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-15T07:06:28.789426Z","caller":"traceutil/trace.go:171","msg":"trace[232127574] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:15087; }","duration":"111.685317ms","start":"2026-10-15T07:06:28.6777Z","end":"2026-10-15T07:06:28.789385Z","steps":["trace[232127574] 'agreement among raft nodes before linearized reading' (duration: 110.362448ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:28.827793Z","caller":"traceutil/trace.go:171","msg":"trace[1805653995] transaction","detail":"{read_only:false; response_revision:15088; number_of_response:1; }","duration":"134.902462ms","start":"2026-10-15T07:06:28.692864Z","end":"2026-10-15T07:06:28.827766Z","steps":["trace[1805653995] 'process raft request' (duration: 134.744209ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:28.993286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.215606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/data-ovn-ovsdb-nb-0.18dea22667ec06f3\" ","response":"range_response_count:1 size:956"} {"level":"info","ts":"2026-10-15T07:06:28.993928Z","caller":"traceutil/trace.go:171","msg":"trace[907147395] range","detail":"{range_begin:/registry/events/openstack/data-ovn-ovsdb-nb-0.18dea22667ec06f3; range_end:; response_count:1; response_revision:15089; }","duration":"142.86487ms","start":"2026-10-15T07:06:28.851004Z","end":"2026-10-15T07:06:28.993869Z","steps":["trace[907147395] 'range keys from in-memory index tree' (duration: 142.092815ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:29.136379Z","caller":"traceutil/trace.go:171","msg":"trace[719352237] transaction","detail":"{read_only:false; response_revision:15090; number_of_response:1; }","duration":"138.178231ms","start":"2026-10-15T07:06:28.998173Z","end":"2026-10-15T07:06:29.136351Z","steps":["trace[719352237] 'process raft request' (duration: 137.975017ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:06:29.15951Z","caller":"traceutil/trace.go:171","msg":"trace[200916327] transaction","detail":"{read_only:false; response_revision:15091; number_of_response:1; }","duration":"155.811678ms","start":"2026-10-15T07:06:29.003655Z","end":"2026-10-15T07:06:29.159466Z","steps":["trace[200916327] 'process raft request' (duration: 155.619134ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:08:11.48686Z","caller":"traceutil/trace.go:171","msg":"trace[1089538759] transaction","detail":"{read_only:false; response_revision:16189; number_of_response:1; }","duration":"132.151063ms","start":"2026-10-15T07:08:11.354681Z","end":"2026-10-15T07:08:11.486832Z","steps":["trace[1089538759] 'process raft request' (duration: 132.0023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:08:11.786356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.366041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T07:08:11.786454Z","caller":"traceutil/trace.go:171","msg":"trace[167889835] linearizableReadLoop","detail":"{readStateIndex:16739; appliedIndex:16738; }","duration":"153.5982ms","start":"2026-10-15T07:08:11.632841Z","end":"2026-10-15T07:08:11.78644Z","steps":["trace[167889835] 'read index received' (duration: 3.993834ms)","trace[167889835] 'applied index is now lower than readState.Index' (duration: 149.603406ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:08:11.786489Z","caller":"traceutil/trace.go:171","msg":"trace[356702971] transaction","detail":"{read_only:false; response_revision:16190; number_of_response:1; }","duration":"305.413554ms","start":"2026-10-15T07:08:11.481049Z","end":"2026-10-15T07:08:11.786463Z","steps":["trace[356702971] 'process raft request' (duration: 155.860949ms)","trace[356702971] 'compare' (duration: 149.265239ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:08:11.786582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:08:11.481023Z","time spent":"305.516797ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:08:11.786579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.712303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:08:11.786699Z","caller":"traceutil/trace.go:171","msg":"trace[1327210030] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16190; }","duration":"153.883906ms","start":"2026-10-15T07:08:11.632792Z","end":"2026-10-15T07:08:11.786676Z","steps":["trace[1327210030] 'agreement among raft nodes before linearized reading' (duration: 153.711173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:08:11.786722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.56116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"warn","ts":"2026-10-15T07:08:11.786733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.573061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:08:11.786751Z","caller":"traceutil/trace.go:171","msg":"trace[821712681] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16190; }","duration":"153.604592ms","start":"2026-10-15T07:08:11.633137Z","end":"2026-10-15T07:08:11.786742Z","steps":["trace[821712681] 'agreement among raft nodes before linearized reading' (duration: 153.53363ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:08:11.786764Z","caller":"traceutil/trace.go:171","msg":"trace[1185250262] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16190; }","duration":"153.650302ms","start":"2026-10-15T07:08:11.633104Z","end":"2026-10-15T07:08:11.786754Z","steps":["trace[1185250262] 'agreement among raft nodes before linearized reading' (duration: 153.596921ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:08:11.786824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.110785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T07:08:11.787683Z","caller":"traceutil/trace.go:171","msg":"trace[1908317890] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16190; }","duration":"148.958852ms","start":"2026-10-15T07:08:11.638671Z","end":"2026-10-15T07:08:11.78763Z","steps":["trace[1908317890] 'agreement among raft nodes before linearized reading' (duration: 147.974191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:08:11.786643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.498049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-15T07:08:11.788697Z","caller":"traceutil/trace.go:171","msg":"trace[20056809] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16190; }","duration":"155.585385ms","start":"2026-10-15T07:08:11.633096Z","end":"2026-10-15T07:08:11.788681Z","steps":["trace[20056809] 'agreement among raft nodes before linearized reading' (duration: 153.427759ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:08:11.91349Z","caller":"traceutil/trace.go:171","msg":"trace[1434174585] transaction","detail":"{read_only:false; response_revision:16191; number_of_response:1; }","duration":"120.930244ms","start":"2026-10-15T07:08:11.792528Z","end":"2026-10-15T07:08:11.913458Z","steps":["trace[1434174585] 'process raft request' (duration: 110.044732ms)","trace[1434174585] 'compare' (duration: 10.570895ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:08:11.913494Z","caller":"traceutil/trace.go:171","msg":"trace[719302904] transaction","detail":"{read_only:false; response_revision:16193; number_of_response:1; }","duration":"119.184486ms","start":"2026-10-15T07:08:11.794262Z","end":"2026-10-15T07:08:11.913447Z","steps":["trace[719302904] 'process raft request' (duration: 119.068653ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:08:11.914071Z","caller":"traceutil/trace.go:171","msg":"trace[1635343039] transaction","detail":"{read_only:false; response_revision:16192; number_of_response:1; }","duration":"121.179418ms","start":"2026-10-15T07:08:11.792874Z","end":"2026-10-15T07:08:11.914053Z","steps":["trace[1635343039] 'process raft request' (duration: 120.428903ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:08:12.06053Z","caller":"traceutil/trace.go:171","msg":"trace[210385531] transaction","detail":"{read_only:false; response_revision:16195; number_of_response:1; }","duration":"101.345846ms","start":"2026-10-15T07:08:11.959155Z","end":"2026-10-15T07:08:12.0605Z","steps":["trace[210385531] 'process raft request' (duration: 101.171482ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:10:33.620671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14663} {"level":"info","ts":"2026-10-15T07:10:33.709603Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14663,"took":"86.280193ms","hash":3197436873,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":32595968,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:10:33.709677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3197436873,"revision":14663,"compact-revision":12588} {"level":"info","ts":"2026-10-15T07:13:51.350168Z","caller":"traceutil/trace.go:171","msg":"trace[1360232696] linearizableReadLoop","detail":"{readStateIndex:19400; appliedIndex:19399; }","duration":"157.849282ms","start":"2026-10-15T07:13:51.1923Z","end":"2026-10-15T07:13:51.350149Z","steps":["trace[1360232696] 'read index received' (duration: 157.664318ms)","trace[1360232696] 'applied index is now lower than readState.Index' (duration: 183.714µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:13:51.350435Z","caller":"traceutil/trace.go:171","msg":"trace[715152633] transaction","detail":"{read_only:false; response_revision:18766; number_of_response:1; }","duration":"167.630261ms","start":"2026-10-15T07:13:51.182797Z","end":"2026-10-15T07:13:51.350427Z","steps":["trace[715152633] 'process raft request' (duration: 167.193871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:13:51.350671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.326483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rf9fk\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-10-15T07:13:51.350698Z","caller":"traceutil/trace.go:171","msg":"trace[91160156] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rf9fk; range_end:; response_count:1; response_revision:18766; }","duration":"158.426554ms","start":"2026-10-15T07:13:51.192265Z","end":"2026-10-15T07:13:51.350692Z","steps":["trace[91160156] 'agreement among raft nodes before linearized reading' (duration: 158.285722ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:13:51.350807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.627264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fc15fe5d-0d58-4573-abf6-1c44b7d075a9\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-10-15T07:13:51.350831Z","caller":"traceutil/trace.go:171","msg":"trace[1165617962] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fc15fe5d-0d58-4573-abf6-1c44b7d075a9; range_end:; response_count:1; response_revision:18766; }","duration":"125.679886ms","start":"2026-10-15T07:13:51.225144Z","end":"2026-10-15T07:13:51.350824Z","steps":["trace[1165617962] 'agreement among raft nodes before linearized reading' (duration: 125.641525ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:14:27.714028Z","caller":"traceutil/trace.go:171","msg":"trace[2093654084] transaction","detail":"{read_only:false; response_revision:19229; number_of_response:1; }","duration":"112.246748ms","start":"2026-10-15T07:14:27.601744Z","end":"2026-10-15T07:14:27.713991Z","steps":["trace[2093654084] 'process raft request' (duration: 111.966662ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:14:43.217027Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:14:43.228801Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T07:14:43.228976Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T07:14:50.309381Z","caller":"traceutil/trace.go:171","msg":"trace[1984658829] transaction","detail":"{read_only:false; response_revision:19430; number_of_response:1; }","duration":"105.643448ms","start":"2026-10-15T07:14:50.203713Z","end":"2026-10-15T07:14:50.309356Z","steps":["trace[1984658829] 'process raft request' (duration: 105.490864ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:14:50.309425Z","caller":"traceutil/trace.go:171","msg":"trace[1471755127] transaction","detail":"{read_only:false; response_revision:19431; number_of_response:1; }","duration":"105.699489ms","start":"2026-10-15T07:14:50.203713Z","end":"2026-10-15T07:14:50.309412Z","steps":["trace[1471755127] 'process raft request' (duration: 105.610378ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:03.190466Z","caller":"traceutil/trace.go:171","msg":"trace[1044829078] transaction","detail":"{read_only:false; response_revision:19550; number_of_response:1; }","duration":"132.311207ms","start":"2026-10-15T07:15:03.058138Z","end":"2026-10-15T07:15:03.190449Z","steps":["trace[1044829078] 'process raft request' (duration: 131.94577ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:21.346442Z","caller":"traceutil/trace.go:171","msg":"trace[220342640] linearizableReadLoop","detail":"{readStateIndex:20325; appliedIndex:20324; }","duration":"238.21734ms","start":"2026-10-15T07:15:21.108189Z","end":"2026-10-15T07:15:21.346406Z","steps":["trace[220342640] 'read index received' (duration: 238.054215ms)","trace[220342640] 'applied index is now lower than readState.Index' (duration: 161.974µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:15:21.346561Z","caller":"traceutil/trace.go:171","msg":"trace[1622783629] transaction","detail":"{read_only:false; response_revision:19666; number_of_response:1; }","duration":"247.345765ms","start":"2026-10-15T07:15:21.099179Z","end":"2026-10-15T07:15:21.346525Z","steps":["trace[1622783629] 'process raft request' (duration: 247.053558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:15:21.346742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.472085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T07:15:21.346742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.49953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T07:15:21.346742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.921095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:15:21.346789Z","caller":"traceutil/trace.go:171","msg":"trace[581696672] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:19666; }","duration":"238.622728ms","start":"2026-10-15T07:15:21.108153Z","end":"2026-10-15T07:15:21.346776Z","steps":["trace[581696672] 'agreement among raft nodes before linearized reading' (duration: 238.420114ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:21.346819Z","caller":"traceutil/trace.go:171","msg":"trace[853427822] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19666; }","duration":"108.025988ms","start":"2026-10-15T07:15:21.238777Z","end":"2026-10-15T07:15:21.346803Z","steps":["trace[853427822] 'agreement among raft nodes before linearized reading' (duration: 107.884095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:15:21.346836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.030218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:15:21.346813Z","caller":"traceutil/trace.go:171","msg":"trace[1243043849] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:19666; }","duration":"110.654555ms","start":"2026-10-15T07:15:21.236141Z","end":"2026-10-15T07:15:21.346796Z","steps":["trace[1243043849] 'agreement among raft nodes before linearized reading' (duration: 110.479781ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:21.346875Z","caller":"traceutil/trace.go:171","msg":"trace[1002682360] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19666; }","duration":"108.09781ms","start":"2026-10-15T07:15:21.238765Z","end":"2026-10-15T07:15:21.346863Z","steps":["trace[1002682360] 'agreement among raft nodes before linearized reading' (duration: 108.005068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:15:21.346901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.139773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:15:21.347001Z","caller":"traceutil/trace.go:171","msg":"trace[1839143513] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19666; }","duration":"110.281416ms","start":"2026-10-15T07:15:21.236702Z","end":"2026-10-15T07:15:21.346984Z","steps":["trace[1839143513] 'agreement among raft nodes before linearized reading' (duration: 110.118373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:15:21.347006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.709803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21464"} {"level":"info","ts":"2026-10-15T07:15:21.347105Z","caller":"traceutil/trace.go:171","msg":"trace[665785366] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:19666; }","duration":"108.889347ms","start":"2026-10-15T07:15:21.238189Z","end":"2026-10-15T07:15:21.347079Z","steps":["trace[665785366] 'agreement among raft nodes before linearized reading' (duration: 108.524048ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:33.694954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17236} {"level":"info","ts":"2026-10-15T07:15:33.890865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17236,"took":"192.937482ms","hash":3296531831,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":34545664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T07:15:33.890972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3296531831,"revision":17236,"compact-revision":14663} {"level":"info","ts":"2026-10-15T07:19:24.896493Z","caller":"traceutil/trace.go:171","msg":"trace[1619100197] linearizableReadLoop","detail":"{readStateIndex:22042; appliedIndex:22041; }","duration":"148.502374ms","start":"2026-10-15T07:19:24.747965Z","end":"2026-10-15T07:19:24.896468Z","steps":["trace[1619100197] 'read index received' (duration: 148.317651ms)","trace[1619100197] 'applied index is now lower than readState.Index' (duration: 183.863µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:19:24.896673Z","caller":"traceutil/trace.go:171","msg":"trace[725843286] transaction","detail":"{read_only:false; response_revision:21324; number_of_response:1; }","duration":"311.142151ms","start":"2026-10-15T07:19:24.585481Z","end":"2026-10-15T07:19:24.896623Z","steps":["trace[725843286] 'process raft request' (duration: 310.858776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:24.896749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.678248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:19:24.896789Z","caller":"traceutil/trace.go:171","msg":"trace[1431823832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21324; }","duration":"148.886553ms","start":"2026-10-15T07:19:24.74789Z","end":"2026-10-15T07:19:24.896777Z","steps":["trace[1431823832] 'agreement among raft nodes before linearized reading' (duration: 148.676769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:24.896918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:24.585456Z","time spent":"311.323766ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:19:35.242842Z","caller":"traceutil/trace.go:171","msg":"trace[1998970434] linearizableReadLoop","detail":"{readStateIndex:22098; appliedIndex:22097; }","duration":"101.756996ms","start":"2026-10-15T07:19:35.141068Z","end":"2026-10-15T07:19:35.242825Z","steps":["trace[1998970434] 'read index received' (duration: 101.161803ms)","trace[1998970434] 'applied index is now lower than readState.Index' (duration: 594.643µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:19:35.242966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.858107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:19:35.243102Z","caller":"traceutil/trace.go:171","msg":"trace[973650494] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:21378; }","duration":"102.033211ms","start":"2026-10-15T07:19:35.141056Z","end":"2026-10-15T07:19:35.24309Z","steps":["trace[973650494] 'agreement among raft nodes before linearized reading' (duration: 101.834387ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:35.242982Z","caller":"traceutil/trace.go:171","msg":"trace[487805021] transaction","detail":"{read_only:false; response_revision:21378; number_of_response:1; }","duration":"112.229769ms","start":"2026-10-15T07:19:35.13072Z","end":"2026-10-15T07:19:35.242949Z","steps":["trace[487805021] 'process raft request' (duration: 111.863942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:41.250018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.674864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:19:41.250163Z","caller":"traceutil/trace.go:171","msg":"trace[268817725] transaction","detail":"{read_only:false; response_revision:21410; number_of_response:1; }","duration":"209.225493ms","start":"2026-10-15T07:19:41.040905Z","end":"2026-10-15T07:19:41.25013Z","steps":["trace[268817725] 'process raft request' (duration: 49.272914ms)","trace[268817725] 'compare' (duration: 159.425758ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:19:41.370478Z","caller":"traceutil/trace.go:171","msg":"trace[1145260460] transaction","detail":"{read_only:false; response_revision:21411; number_of_response:1; }","duration":"100.957769ms","start":"2026-10-15T07:19:41.269474Z","end":"2026-10-15T07:19:41.370432Z","steps":["trace[1145260460] 'process raft request' (duration: 98.617878ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:41.761368Z","caller":"traceutil/trace.go:171","msg":"trace[1185015812] transaction","detail":"{read_only:false; response_revision:21412; number_of_response:1; }","duration":"145.581143ms","start":"2026-10-15T07:19:41.61575Z","end":"2026-10-15T07:19:41.761331Z","steps":["trace[1185015812] 'process raft request' (duration: 145.398768ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:19:42.022497Z","caller":"traceutil/trace.go:171","msg":"trace[307305866] linearizableReadLoop","detail":"{readStateIndex:22134; appliedIndex:22132; }","duration":"371.618226ms","start":"2026-10-15T07:19:41.650845Z","end":"2026-10-15T07:19:42.022463Z","steps":["trace[307305866] 'read index received' (duration: 110.3639ms)","trace[307305866] 'applied index is now lower than readState.Index' (duration: 261.252826ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:19:42.022549Z","caller":"traceutil/trace.go:171","msg":"trace[1486215493] transaction","detail":"{read_only:false; response_revision:21413; number_of_response:1; }","duration":"406.710655ms","start":"2026-10-15T07:19:41.615802Z","end":"2026-10-15T07:19:42.022512Z","steps":["trace[1486215493] 'process raft request' (duration: 322.929044ms)","trace[1486215493] 'compare' (duration: 83.465825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:19:42.022718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:41.615777Z","time spent":"406.852799ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:19:42.02275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.893791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-15T07:19:42.022779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.976749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-15T07:19:42.022797Z","caller":"traceutil/trace.go:171","msg":"trace[1405756592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21413; }","duration":"371.990213ms","start":"2026-10-15T07:19:41.650794Z","end":"2026-10-15T07:19:42.022784Z","steps":["trace[1405756592] 'agreement among raft nodes before linearized reading' (duration: 371.82857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:42.022833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:41.650774Z","time spent":"372.050674ms","remote":"127.0.0.1:37212","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":"info","ts":"2026-10-15T07:19:42.022832Z","caller":"traceutil/trace.go:171","msg":"trace[81049011] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:21413; }","duration":"370.072182ms","start":"2026-10-15T07:19:41.652742Z","end":"2026-10-15T07:19:42.022814Z","steps":["trace[81049011] 'agreement among raft nodes before linearized reading' (duration: 369.902459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:19:42.022875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:19:41.652724Z","time spent":"370.139504ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-10-15T07:19:42.022876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.158428ms","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-10-15T07:19:42.022985Z","caller":"traceutil/trace.go:171","msg":"trace[331344877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21413; }","duration":"112.323171ms","start":"2026-10-15T07:19:41.910638Z","end":"2026-10-15T07:19:42.022961Z","steps":["trace[331344877] 'agreement among raft nodes before linearized reading' (duration: 112.086636ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:20:33.702432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19752} {"level":"info","ts":"2026-10-15T07:20:33.831707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19752,"took":"125.469152ms","hash":3387891311,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":31047680,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T07:20:33.831806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3387891311,"revision":19752,"compact-revision":17236} {"level":"info","ts":"2026-10-15T07:23:39.085652Z","caller":"traceutil/trace.go:171","msg":"trace[2102934626] transaction","detail":"{read_only:false; response_revision:23869; number_of_response:1; }","duration":"104.596506ms","start":"2026-10-15T07:23:38.981019Z","end":"2026-10-15T07:23:39.085616Z","steps":["trace[2102934626] 'process raft request' (duration: 103.201656ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:23:39.431083Z","caller":"traceutil/trace.go:171","msg":"trace[694037542] transaction","detail":"{read_only:false; response_revision:23871; number_of_response:1; }","duration":"336.050175ms","start":"2026-10-15T07:23:39.095014Z","end":"2026-10-15T07:23:39.431064Z","steps":["trace[694037542] 'process raft request' (duration: 335.933813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:23:39.431234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:23:39.094988Z","time spent":"336.152758ms","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T07:23:39.432793Z","caller":"traceutil/trace.go:171","msg":"trace[672016398] linearizableReadLoop","detail":"{readStateIndex:24664; appliedIndex:24662; }","duration":"238.560511ms","start":"2026-10-15T07:23:39.194205Z","end":"2026-10-15T07:23:39.432765Z","steps":["trace[672016398] 'read index received' (duration: 236.783442ms)","trace[672016398] 'applied index is now lower than readState.Index' (duration: 1.775588ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:23:39.433122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.901707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:87035"} {"level":"info","ts":"2026-10-15T07:23:39.433186Z","caller":"traceutil/trace.go:171","msg":"trace[787199484] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23872; }","duration":"239.00513ms","start":"2026-10-15T07:23:39.194167Z","end":"2026-10-15T07:23:39.433172Z","steps":["trace[787199484] 'agreement among raft nodes before linearized reading' (duration: 238.814876ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:23:39.433409Z","caller":"traceutil/trace.go:171","msg":"trace[192077597] transaction","detail":"{read_only:false; response_revision:23872; number_of_response:1; }","duration":"338.240701ms","start":"2026-10-15T07:23:39.095156Z","end":"2026-10-15T07:23:39.433397Z","steps":["trace[192077597] 'process raft request' (duration: 337.465734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:23:39.433496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:23:39.095127Z","time spent":"338.310213ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:23:39.433136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.804259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:23:39.433639Z","caller":"traceutil/trace.go:171","msg":"trace[2063643319] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:23872; }","duration":"184.368842ms","start":"2026-10-15T07:23:39.249259Z","end":"2026-10-15T07:23:39.433628Z","steps":["trace[2063643319] 'agreement among raft nodes before linearized reading' (duration: 183.8201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:23:39.433731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.409408ms","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-10-15T07:23:39.433774Z","caller":"traceutil/trace.go:171","msg":"trace[1938938948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23872; }","duration":"152.487929ms","start":"2026-10-15T07:23:39.281275Z","end":"2026-10-15T07:23:39.433762Z","steps":["trace[1938938948] 'agreement among raft nodes before linearized reading' (duration: 152.401028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:23:39.434122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.082738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T07:23:39.43419Z","caller":"traceutil/trace.go:171","msg":"trace[1827243144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23872; }","duration":"164.169761ms","start":"2026-10-15T07:23:39.270002Z","end":"2026-10-15T07:23:39.434172Z","steps":["trace[1827243144] 'agreement among raft nodes before linearized reading' (duration: 164.005446ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:25:33.710177Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21981} {"level":"info","ts":"2026-10-15T07:25:33.835016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21981,"took":"119.356371ms","hash":576384382,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":44793856,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-15T07:25:33.835121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":576384382,"revision":21981,"compact-revision":19752} {"level":"info","ts":"2026-10-15T07:27:04.187747Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006881.wal"} {"level":"info","ts":"2026-10-15T07:27:09.455347Z","caller":"traceutil/trace.go:171","msg":"trace[1271972030] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"104.353589ms","start":"2026-10-15T07:27:09.350974Z","end":"2026-10-15T07:27:09.455328Z","steps":["trace[1271972030] 'process raft request' (duration: 104.241897ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:27:09.46742Z","caller":"traceutil/trace.go:171","msg":"trace[944979287] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"102.210985ms","start":"2026-10-15T07:27:09.365174Z","end":"2026-10-15T07:27:09.467385Z","steps":["trace[944979287] 'process raft request' (duration: 102.01377ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.195726Z","caller":"traceutil/trace.go:171","msg":"trace[1898525926] linearizableReadLoop","detail":"{readStateIndex:27344; appliedIndex:27343; }","duration":"217.490868ms","start":"2026-10-15T07:28:08.978207Z","end":"2026-10-15T07:28:09.195698Z","steps":["trace[1898525926] 'read index received' (duration: 217.284954ms)","trace[1898525926] 'applied index is now lower than readState.Index' (duration: 204.574µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:28:09.195799Z","caller":"traceutil/trace.go:171","msg":"trace[773963961] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"304.507978ms","start":"2026-10-15T07:28:08.891257Z","end":"2026-10-15T07:28:09.195765Z","steps":["trace[773963961] 'process raft request' (duration: 304.259834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:28:09.195992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:28:08.891238Z","time spent":"304.627661ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:28:09.196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.925277ms","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-10-15T07:28:09.196058Z","caller":"traceutil/trace.go:171","msg":"trace[249570106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26489; }","duration":"218.034939ms","start":"2026-10-15T07:28:08.978003Z","end":"2026-10-15T07:28:09.196038Z","steps":["trace[249570106] 'agreement among raft nodes before linearized reading' (duration: 217.852535ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.392464Z","caller":"traceutil/trace.go:171","msg":"trace[1714790896] transaction","detail":"{read_only:false; response_revision:26491; number_of_response:1; }","duration":"239.222563ms","start":"2026-10-15T07:28:09.153214Z","end":"2026-10-15T07:28:09.392436Z","steps":["trace[1714790896] 'process raft request' (duration: 239.018688ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.392632Z","caller":"traceutil/trace.go:171","msg":"trace[828904519] linearizableReadLoop","detail":"{readStateIndex:27346; appliedIndex:27344; }","duration":"196.790337ms","start":"2026-10-15T07:28:09.195822Z","end":"2026-10-15T07:28:09.392613Z","steps":["trace[828904519] 'read index received' (duration: 135.393564ms)","trace[828904519] 'applied index is now lower than readState.Index' (duration: 61.395483ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:28:09.392591Z","caller":"traceutil/trace.go:171","msg":"trace[838632546] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"192.003983ms","start":"2026-10-15T07:28:09.200554Z","end":"2026-10-15T07:28:09.392558Z","steps":["trace[838632546] 'process raft request' (duration: 191.727597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:28:09.392823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.954413ms","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-10-15T07:28:09.39286Z","caller":"traceutil/trace.go:171","msg":"trace[1675879104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26493; }","duration":"403.029524ms","start":"2026-10-15T07:28:08.989821Z","end":"2026-10-15T07:28:09.39285Z","steps":["trace[1675879104] 'agreement among raft nodes before linearized reading' (duration: 402.871731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:28:09.392887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:28:08.989808Z","time spent":"403.071756ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T07:28:09.393055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.113836ms","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-10-15T07:28:09.393109Z","caller":"traceutil/trace.go:171","msg":"trace[1622347508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26493; }","duration":"403.242888ms","start":"2026-10-15T07:28:08.989857Z","end":"2026-10-15T07:28:09.3931Z","steps":["trace[1622347508] 'agreement among raft nodes before linearized reading' (duration: 403.130745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:28:09.393109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.328903ms","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-10-15T07:28:09.393133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:28:08.989832Z","time spent":"403.29501ms","remote":"127.0.0.1:37212","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":"info","ts":"2026-10-15T07:28:09.393156Z","caller":"traceutil/trace.go:171","msg":"trace[559815538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26493; }","duration":"398.442256ms","start":"2026-10-15T07:28:08.994704Z","end":"2026-10-15T07:28:09.393146Z","steps":["trace[559815538] 'agreement among raft nodes before linearized reading' (duration: 398.265602ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.392886Z","caller":"traceutil/trace.go:171","msg":"trace[1833904207] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"249.86351ms","start":"2026-10-15T07:28:09.143005Z","end":"2026-10-15T07:28:09.392868Z","steps":["trace[1833904207] 'process raft request' (duration: 188.363225ms)","trace[1833904207] 'compare' (duration: 60.750209ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:28:09.393193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:28:08.994692Z","time spent":"398.493136ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T07:28:09.393306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.823137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:28:09.393353Z","caller":"traceutil/trace.go:171","msg":"trace[1167671960] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26493; }","duration":"284.910959ms","start":"2026-10-15T07:28:09.108431Z","end":"2026-10-15T07:28:09.393342Z","steps":["trace[1167671960] 'agreement among raft nodes before linearized reading' (duration: 284.837428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:28:09.393961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.95162ms","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-10-15T07:28:09.394016Z","caller":"traceutil/trace.go:171","msg":"trace[1058535421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26493; }","duration":"394.040101ms","start":"2026-10-15T07:28:08.999961Z","end":"2026-10-15T07:28:09.394001Z","steps":["trace[1058535421] 'agreement among raft nodes before linearized reading' (duration: 393.854908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:28:09.394049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:28:08.999944Z","time spent":"394.094462ms","remote":"127.0.0.1:37212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T07:28:09.601132Z","caller":"traceutil/trace.go:171","msg":"trace[1218902716] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"202.849865ms","start":"2026-10-15T07:28:09.398253Z","end":"2026-10-15T07:28:09.601102Z","steps":["trace[1218902716] 'process raft request' (duration: 202.111081ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.601528Z","caller":"traceutil/trace.go:171","msg":"trace[322214148] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"201.612069ms","start":"2026-10-15T07:28:09.399904Z","end":"2026-10-15T07:28:09.601516Z","steps":["trace[322214148] 'process raft request' (duration: 201.073747ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.60163Z","caller":"traceutil/trace.go:171","msg":"trace[376204174] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"201.077427ms","start":"2026-10-15T07:28:09.400528Z","end":"2026-10-15T07:28:09.601605Z","steps":["trace[376204174] 'process raft request' (duration: 200.909854ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.60177Z","caller":"traceutil/trace.go:171","msg":"trace[123949855] linearizableReadLoop","detail":"{readStateIndex:27352; appliedIndex:27349; }","duration":"166.282404ms","start":"2026-10-15T07:28:09.435475Z","end":"2026-10-15T07:28:09.601757Z","steps":["trace[123949855] 'read index received' (duration: 164.922104ms)","trace[123949855] 'applied index is now lower than readState.Index' (duration: 1.35924ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:28:09.601948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.450557ms","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-10-15T07:28:09.602001Z","caller":"traceutil/trace.go:171","msg":"trace[1299026488] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26497; }","duration":"166.56193ms","start":"2026-10-15T07:28:09.43543Z","end":"2026-10-15T07:28:09.601991Z","steps":["trace[1299026488] 'agreement among raft nodes before linearized reading' (duration: 166.373465ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.810352Z","caller":"traceutil/trace.go:171","msg":"trace[460190148] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"114.048259ms","start":"2026-10-15T07:28:09.696279Z","end":"2026-10-15T07:28:09.810327Z","steps":["trace[460190148] 'process raft request' (duration: 110.515153ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:28:09.81044Z","caller":"traceutil/trace.go:171","msg":"trace[1109931240] linearizableReadLoop","detail":"{readStateIndex:27353; appliedIndex:27352; }","duration":"102.122312ms","start":"2026-10-15T07:28:09.708297Z","end":"2026-10-15T07:28:09.810419Z","steps":["trace[1109931240] 'read index received' (duration: 98.502665ms)","trace[1109931240] 'applied index is now lower than readState.Index' (duration: 3.618057ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:28:09.810888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.580093ms","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-10-15T07:28:09.810942Z","caller":"traceutil/trace.go:171","msg":"trace[976829507] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26500; }","duration":"102.674985ms","start":"2026-10-15T07:28:09.708252Z","end":"2026-10-15T07:28:09.810927Z","steps":["trace[976829507] 'agreement among raft nodes before linearized reading' (duration: 102.533133ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:30:33.737421Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25094} {"level":"info","ts":"2026-10-15T07:30:33.941712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25094,"took":"200.068516ms","hash":692612482,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":37081088,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-15T07:30:33.941843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":692612482,"revision":25094,"compact-revision":21981} {"level":"info","ts":"2026-10-15T07:31:36.53104Z","caller":"traceutil/trace.go:171","msg":"trace[589236477] transaction","detail":"{read_only:false; response_revision:28548; number_of_response:1; }","duration":"155.943903ms","start":"2026-10-15T07:31:36.375059Z","end":"2026-10-15T07:31:36.531003Z","steps":["trace[589236477] 'process raft request' (duration: 155.678467ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:31:36.532756Z","caller":"traceutil/trace.go:171","msg":"trace[1043277297] transaction","detail":"{read_only:false; response_revision:28549; number_of_response:1; }","duration":"146.201326ms","start":"2026-10-15T07:31:36.386527Z","end":"2026-10-15T07:31:36.532728Z","steps":["trace[1043277297] 'process raft request' (duration: 145.926789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:31:48.832349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.775906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:31:48.832455Z","caller":"traceutil/trace.go:171","msg":"trace[1011893070] linearizableReadLoop","detail":"{readStateIndex:29554; appliedIndex:29553; }","duration":"143.207591ms","start":"2026-10-15T07:31:48.689232Z","end":"2026-10-15T07:31:48.83244Z","steps":["trace[1011893070] 'read index received' (duration: 41.481µs)","trace[1011893070] 'applied index is now lower than readState.Index' (duration: 143.16461ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:31:48.832713Z","caller":"traceutil/trace.go:171","msg":"trace[219173204] transaction","detail":"{read_only:false; response_revision:28645; number_of_response:1; }","duration":"248.819909ms","start":"2026-10-15T07:31:48.58381Z","end":"2026-10-15T07:31:48.83263Z","steps":["trace[219173204] 'process raft request' (duration: 73.622095ms)","trace[219173204] 'compare' (duration: 174.53666ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:31:48.832955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.687942ms","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-10-15T07:31:48.83362Z","caller":"traceutil/trace.go:171","msg":"trace[2120048100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28645; }","duration":"144.399576ms","start":"2026-10-15T07:31:48.689196Z","end":"2026-10-15T07:31:48.833595Z","steps":["trace[2120048100] 'agreement among raft nodes before linearized reading' (duration: 143.450295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:31:48.850649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.209429ms","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-10-15T07:31:48.850721Z","caller":"traceutil/trace.go:171","msg":"trace[905459408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28646; }","duration":"149.314321ms","start":"2026-10-15T07:31:48.701391Z","end":"2026-10-15T07:31:48.850706Z","steps":["trace[905459408] 'agreement among raft nodes before linearized reading' (duration: 148.804641ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:32:02.341926Z","caller":"traceutil/trace.go:171","msg":"trace[250597819] linearizableReadLoop","detail":"{readStateIndex:29732; appliedIndex:29731; }","duration":"114.803204ms","start":"2026-10-15T07:32:02.227096Z","end":"2026-10-15T07:32:02.3419Z","steps":["trace[250597819] 'read index received' (duration: 47.398823ms)","trace[250597819] 'applied index is now lower than readState.Index' (duration: 67.402431ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:32:02.342542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.379096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/octavia-db-user\" ","response":"range_response_count:1 size:719"} {"level":"info","ts":"2026-10-15T07:32:02.342589Z","caller":"traceutil/trace.go:171","msg":"trace[467298134] range","detail":"{range_begin:/registry/secrets/openstack/octavia-db-user; range_end:; response_count:1; response_revision:28817; }","duration":"115.507409ms","start":"2026-10-15T07:32:02.22707Z","end":"2026-10-15T07:32:02.342578Z","steps":["trace[467298134] 'agreement among raft nodes before linearized reading' (duration: 115.299315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:32:02.342633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.444745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:32:02.342663Z","caller":"traceutil/trace.go:171","msg":"trace[1671326514] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:28817; }","duration":"106.507107ms","start":"2026-10-15T07:32:02.236146Z","end":"2026-10-15T07:32:02.342653Z","steps":["trace[1671326514] 'agreement among raft nodes before linearized reading' (duration: 106.458656ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:32:32.760258Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:32:32.77618Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T07:32:32.776352Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T07:35:33.745341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27890} {"level":"info","ts":"2026-10-15T07:35:33.902813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27890,"took":"152.341314ms","hash":2424194850,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":33755136,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T07:35:33.902875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2424194850,"revision":27890,"compact-revision":25094} {"level":"info","ts":"2026-10-15T07:40:33.75188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30455} {"level":"info","ts":"2026-10-15T07:40:33.899646Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30455,"took":"143.580637ms","hash":4233557561,"current-db-size-bytes":56160256,"current-db-size":"56 MB","current-db-size-in-use-bytes":31469568,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T07:40:33.899843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4233557561,"revision":30455,"compact-revision":27890} {"level":"info","ts":"2026-10-15T07:45:33.76012Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32711} {"level":"info","ts":"2026-10-15T07:45:33.921339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32711,"took":"156.618598ms","hash":2092270537,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T07:45:33.921415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2092270537,"revision":32711,"compact-revision":30455} {"level":"info","ts":"2026-10-15T07:48:47.258377Z","caller":"traceutil/trace.go:171","msg":"trace[1323193127] transaction","detail":"{read_only:false; response_revision:36422; number_of_response:1; }","duration":"140.83209ms","start":"2026-10-15T07:48:47.117511Z","end":"2026-10-15T07:48:47.258343Z","steps":["trace[1323193127] 'process raft request' (duration: 62.550097ms)","trace[1323193127] 'compare' (duration: 77.365414ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:48:47.258373Z","caller":"traceutil/trace.go:171","msg":"trace[398916765] linearizableReadLoop","detail":"{readStateIndex:37554; appliedIndex:37552; }","duration":"108.613881ms","start":"2026-10-15T07:48:47.149719Z","end":"2026-10-15T07:48:47.258333Z","steps":["trace[398916765] 'read index received' (duration: 30.363209ms)","trace[398916765] 'applied index is now lower than readState.Index' (duration: 78.249452ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:48:47.258597Z","caller":"traceutil/trace.go:171","msg":"trace[2095768660] transaction","detail":"{read_only:false; response_revision:36423; number_of_response:1; }","duration":"116.848657ms","start":"2026-10-15T07:48:47.141738Z","end":"2026-10-15T07:48:47.258587Z","steps":["trace[2095768660] 'process raft request' (duration: 116.47751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:48:47.258592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.779465ms","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-10-15T07:48:47.259628Z","caller":"traceutil/trace.go:171","msg":"trace[1194476811] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:36423; }","duration":"109.756155ms","start":"2026-10-15T07:48:47.149707Z","end":"2026-10-15T07:48:47.259464Z","steps":["trace[1194476811] 'agreement among raft nodes before linearized reading' (duration: 108.698713ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:50:33.768447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34945} {"level":"info","ts":"2026-10-15T07:50:33.899448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34945,"took":"126.410992ms","hash":831162788,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":32993280,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:50:33.899515Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":831162788,"revision":34945,"compact-revision":32711} {"level":"info","ts":"2026-10-15T07:54:02.302186Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c8e9c1aa9bb537b7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:54:02.307713Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T07:54:02.307868Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T07:55:33.777Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37239} {"level":"info","ts":"2026-10-15T07:55:33.913179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37239,"took":"130.217813ms","hash":274083427,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":33189888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:55:33.91325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":274083427,"revision":37239,"compact-revision":34945} {"level":"info","ts":"2026-10-15T08:00:33.787054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39514} {"level":"info","ts":"2026-10-15T08:00:33.931978Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39514,"took":"140.314859ms","hash":321009604,"current-db-size-bytes":57204736,"current-db-size":"57 MB","current-db-size-in-use-bytes":31707136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T08:00:33.932048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":321009604,"revision":39514,"compact-revision":37239}