{"level":"warn","ts":"2026-10-16T14:27:21.493745Z","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-16T14:27:21.493956Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.167:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.167:2380","--initial-cluster=instance=https://162.253.55.167:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.167:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.167: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-16T14:27:21.494361Z","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-16T14:27:21.4944Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-10-16T14:27:21.494448Z","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-16T14:27:21.496614Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"]} {"level":"info","ts":"2026-10-16T14:27:21.496829Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.167: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-16T14:27:21.500614Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.230372ms"} {"level":"info","ts":"2026-10-16T14:27:21.507731Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"83287f3a81241ad4","cluster-id":"185ed9b8ed398b9a"} {"level":"info","ts":"2026-10-16T14:27:21.507903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T14:27:21.50795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 0"} {"level":"info","ts":"2026-10-16T14:27:21.507969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 83287f3a81241ad4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T14:27:21.507991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 1"} {"level":"info","ts":"2026-10-16T14:27:21.508064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"warn","ts":"2026-10-16T14:27:21.521026Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T14:27:21.522927Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T14:27:21.524649Z","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-16T14:27:21.527268Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"83287f3a81241ad4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T14:27:21.527502Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"83287f3a81241ad4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T14:27:21.527752Z","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-16T14:27:21.527873Z","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-16T14:27:21.527891Z","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-16T14:27:21.52975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"info","ts":"2026-10-16T14:27:21.529895Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","added-peer-id":"83287f3a81241ad4","added-peer-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-10-16T14:27:21.531832Z","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-16T14:27:21.532062Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"83287f3a81241ad4","initial-advertise-peer-urls":["https://162.253.55.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T14:27:21.532096Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T14:27:21.532136Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-10-16T14:27:21.532156Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-10-16T14:27:22.109893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T14:27:22.110016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T14:27:22.110063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgPreVoteResp from 83287f3a81241ad4 at term 1"} {"level":"info","ts":"2026-10-16T14:27:22.110089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became candidate at term 2"} {"level":"info","ts":"2026-10-16T14:27:22.110104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgVoteResp from 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-10-16T14:27:22.110124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became leader at term 2"} {"level":"info","ts":"2026-10-16T14:27:22.110168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 83287f3a81241ad4 elected leader 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-10-16T14:27:22.111396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:27:22.112439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T14:27:22.112451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"83287f3a81241ad4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.167:2379]}","request-path":"/0/members/83287f3a81241ad4/attributes","cluster-id":"185ed9b8ed398b9a","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T14:27:22.112576Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T14:27:22.112877Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T14:27:22.11296Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T14:27:22.11307Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:27:22.113202Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:27:22.113276Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:27:22.115967Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T14:27:22.116342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.167:2379"} {"level":"warn","ts":"2026-10-16T14:28:14.589591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.980481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:28:14.589719Z","caller":"traceutil/trace.go:171","msg":"trace[1927266676] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"242.60656ms","start":"2026-10-16T14:28:14.347095Z","end":"2026-10-16T14:28:14.589701Z","steps":["trace[1927266676] 'read index received' (duration: 44.896288ms)","trace[1927266676] 'applied index is now lower than readState.Index' (duration: 197.708822ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:28:14.589827Z","caller":"traceutil/trace.go:171","msg":"trace[2044155706] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"330.838114ms","start":"2026-10-16T14:28:14.258933Z","end":"2026-10-16T14:28:14.589772Z","steps":["trace[2044155706] 'process raft request' (duration: 132.973258ms)","trace[2044155706] 'compare' (duration: 196.822776ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:28:14.589873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.774004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-16T14:28:14.589903Z","caller":"traceutil/trace.go:171","msg":"trace[1323321047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"242.844087ms","start":"2026-10-16T14:28:14.34705Z","end":"2026-10-16T14:28:14.589894Z","steps":["trace[1323321047] 'agreement among raft nodes before linearized reading' (duration: 242.708763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:14.590006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:28:14.258907Z","time spent":"330.995848ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:28:14.624932Z","caller":"traceutil/trace.go:171","msg":"trace[917687099] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"265.900864ms","start":"2026-10-16T14:28:14.358953Z","end":"2026-10-16T14:28:14.624854Z","steps":["trace[917687099] 'process raft request' (duration: 262.839767ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:28:15.166506Z","caller":"traceutil/trace.go:171","msg":"trace[418646257] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"433.91915ms","start":"2026-10-16T14:28:14.732558Z","end":"2026-10-16T14:28:15.166477Z","steps":["trace[418646257] 'read index received' (duration: 428.741012ms)","trace[418646257] 'applied index is now lower than readState.Index' (duration: 5.177087ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:28:15.166671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.099775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:28:15.166711Z","caller":"traceutil/trace.go:171","msg":"trace[86476130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"434.208818ms","start":"2026-10-16T14:28:14.732491Z","end":"2026-10-16T14:28:15.1667Z","steps":["trace[86476130] 'agreement among raft nodes before linearized reading' (duration: 434.088944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:15.166743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:28:14.732466Z","time spent":"434.26977ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T14:28:15.166708Z","caller":"traceutil/trace.go:171","msg":"trace[101708708] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"465.449737ms","start":"2026-10-16T14:28:14.701205Z","end":"2026-10-16T14:28:15.166654Z","steps":["trace[101708708] 'process raft request' (duration: 465.108988ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:28:15.16676Z","caller":"traceutil/trace.go:171","msg":"trace[1582029036] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"483.959025ms","start":"2026-10-16T14:28:14.682738Z","end":"2026-10-16T14:28:15.166697Z","steps":["trace[1582029036] 'process raft request' (duration: 478.416807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:15.166884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:28:14.701173Z","time spent":"465.631393ms","remote":"127.0.0.1:51282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-16T14:28:15.166979Z","caller":"traceutil/trace.go:171","msg":"trace[827149973] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"159.970796ms","start":"2026-10-16T14:28:15.006995Z","end":"2026-10-16T14:28:15.166966Z","steps":["trace[827149973] 'process raft request' (duration: 159.422281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:15.167007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:28:14.682693Z","time spent":"484.203752ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:29:50.025085Z","caller":"traceutil/trace.go:171","msg":"trace[1886017433] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"291.306809ms","start":"2026-10-16T14:29:49.733738Z","end":"2026-10-16T14:29:50.025044Z","steps":["trace[1886017433] 'read index received' (duration: 290.442745ms)","trace[1886017433] 'applied index is now lower than readState.Index' (duration: 862.674µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:29:50.025179Z","caller":"traceutil/trace.go:171","msg":"trace[1050651557] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"347.1147ms","start":"2026-10-16T14:29:49.678036Z","end":"2026-10-16T14:29:50.025151Z","steps":["trace[1050651557] 'process raft request' (duration: 346.775461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:29:50.025327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.589357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:29:50.025372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:29:49.677982Z","time spent":"347.277885ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:29:50.025384Z","caller":"traceutil/trace.go:171","msg":"trace[2022817402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1870; }","duration":"291.659159ms","start":"2026-10-16T14:29:49.73371Z","end":"2026-10-16T14:29:50.025369Z","steps":["trace[2022817402] 'agreement among raft nodes before linearized reading' (duration: 291.557406ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:29:50.044472Z","caller":"traceutil/trace.go:171","msg":"trace[1775234620] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"160.966473ms","start":"2026-10-16T14:29:49.883463Z","end":"2026-10-16T14:29:50.044429Z","steps":["trace[1775234620] 'process raft request' (duration: 160.806598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:29:50.044509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.128337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:29:50.044711Z","caller":"traceutil/trace.go:171","msg":"trace[1961145642] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1871; }","duration":"250.375364ms","start":"2026-10-16T14:29:49.794312Z","end":"2026-10-16T14:29:50.044688Z","steps":["trace[1961145642] 'agreement among raft nodes before linearized reading' (duration: 250.138907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:29:50.42871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.496863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-10-16T14:29:50.428782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.91099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8107bf3f-2396-4df4-b99f-4ce73789ebc7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-16T14:29:50.428837Z","caller":"traceutil/trace.go:171","msg":"trace[1700401036] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"361.677007ms","start":"2026-10-16T14:29:50.067132Z","end":"2026-10-16T14:29:50.428809Z","steps":["trace[1700401036] 'range keys from in-memory index tree' (duration: 361.231056ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:29:50.428882Z","caller":"traceutil/trace.go:171","msg":"trace[1520810346] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8107bf3f-2396-4df4-b99f-4ce73789ebc7; range_end:; response_count:1; response_revision:1871; }","duration":"211.058674ms","start":"2026-10-16T14:29:50.2178Z","end":"2026-10-16T14:29:50.428858Z","steps":["trace[1520810346] 'range keys from in-memory index tree' (duration: 210.602251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:29:50.428895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:29:50.067121Z","time spent":"361.76219ms","remote":"127.0.0.1:52592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5723,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-16T14:29:50.428863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.97935ms","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-16T14:29:50.42896Z","caller":"traceutil/trace.go:171","msg":"trace[1056874098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1871; }","duration":"118.163495ms","start":"2026-10-16T14:29:50.310775Z","end":"2026-10-16T14:29:50.428939Z","steps":["trace[1056874098] 'range keys from in-memory index tree' (duration: 117.769844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:29.814663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.764422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:30:29.814829Z","caller":"traceutil/trace.go:171","msg":"trace[912982773] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"254.534792ms","start":"2026-10-16T14:30:29.560263Z","end":"2026-10-16T14:30:29.814798Z","steps":["trace[912982773] 'process raft request' (duration: 65.501232ms)","trace[912982773] 'compare' (duration: 188.574617ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:30:29.872351Z","caller":"traceutil/trace.go:171","msg":"trace[1457523835] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"151.454864ms","start":"2026-10-16T14:30:29.720834Z","end":"2026-10-16T14:30:29.872289Z","steps":["trace[1457523835] 'process raft request' (duration: 150.802256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:35.675979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.352883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.167\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-16T14:30:35.676085Z","caller":"traceutil/trace.go:171","msg":"trace[517214574] range","detail":"{range_begin:/registry/masterleases/162.253.55.167; range_end:; response_count:1; response_revision:2167; }","duration":"182.499478ms","start":"2026-10-16T14:30:35.493564Z","end":"2026-10-16T14:30:35.676063Z","steps":["trace[517214574] 'range keys from in-memory index tree' (duration: 182.138787ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:41.940312Z","caller":"traceutil/trace.go:171","msg":"trace[1199286916] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"193.241686ms","start":"2026-10-16T14:30:41.747025Z","end":"2026-10-16T14:30:41.940266Z","steps":["trace[1199286916] 'process raft request' (duration: 192.976819ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:42.029923Z","caller":"traceutil/trace.go:171","msg":"trace[1751237993] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"119.012021ms","start":"2026-10-16T14:30:41.910884Z","end":"2026-10-16T14:30:42.029896Z","steps":["trace[1751237993] 'process raft request' (duration: 118.792645ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:33.368302Z","caller":"traceutil/trace.go:171","msg":"trace[233101368] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"214.194712ms","start":"2026-10-16T14:31:33.154076Z","end":"2026-10-16T14:31:33.36827Z","steps":["trace[233101368] 'process raft request' (duration: 213.985996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:06.682942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.248626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:32:06.683087Z","caller":"traceutil/trace.go:171","msg":"trace[1781013415] linearizableReadLoop","detail":"{readStateIndex:2963; appliedIndex:2962; }","duration":"225.774401ms","start":"2026-10-16T14:32:06.457291Z","end":"2026-10-16T14:32:06.683066Z","steps":["trace[1781013415] 'read index received' (duration: 95.153703ms)","trace[1781013415] 'applied index is now lower than readState.Index' (duration: 130.618808ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:32:06.683174Z","caller":"traceutil/trace.go:171","msg":"trace[958441394] transaction","detail":"{read_only:false; response_revision:2852; number_of_response:1; }","duration":"238.212742ms","start":"2026-10-16T14:32:06.444946Z","end":"2026-10-16T14:32:06.683159Z","steps":["trace[958441394] 'process raft request' (duration: 107.605695ms)","trace[958441394] 'compare' (duration: 129.863295ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:32:06.68336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.051908ms","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-16T14:32:06.683459Z","caller":"traceutil/trace.go:171","msg":"trace[540897081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2852; }","duration":"226.215773ms","start":"2026-10-16T14:32:06.457221Z","end":"2026-10-16T14:32:06.683437Z","steps":["trace[540897081] 'agreement among raft nodes before linearized reading' (duration: 225.947875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:06.735132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.377292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:32:06.735283Z","caller":"traceutil/trace.go:171","msg":"trace[1354156662] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2855; }","duration":"199.611669ms","start":"2026-10-16T14:32:06.535645Z","end":"2026-10-16T14:32:06.735257Z","steps":["trace[1354156662] 'agreement among raft nodes before linearized reading' (duration: 199.400423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:32:06.735487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.972774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:32:06.735556Z","caller":"traceutil/trace.go:171","msg":"trace[1182667221] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2855; }","duration":"115.124638ms","start":"2026-10-16T14:32:06.620416Z","end":"2026-10-16T14:32:06.735541Z","steps":["trace[1182667221] 'agreement among raft nodes before linearized reading' (duration: 115.023615ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:32:11.969431Z","caller":"traceutil/trace.go:171","msg":"trace[669945907] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"127.392386ms","start":"2026-10-16T14:32:11.842003Z","end":"2026-10-16T14:32:11.969396Z","steps":["trace[669945907] 'process raft request' (duration: 127.142898ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:35:29.508079Z","caller":"traceutil/trace.go:171","msg":"trace[1725406507] transaction","detail":"{read_only:false; response_revision:4121; number_of_response:1; }","duration":"306.908504ms","start":"2026-10-16T14:35:29.201144Z","end":"2026-10-16T14:35:29.508053Z","steps":["trace[1725406507] 'process raft request' (duration: 306.599316ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:35:29.508041Z","caller":"traceutil/trace.go:171","msg":"trace[1937694153] linearizableReadLoop","detail":"{readStateIndex:4279; appliedIndex:4278; }","duration":"239.799492ms","start":"2026-10-16T14:35:29.268203Z","end":"2026-10-16T14:35:29.508003Z","steps":["trace[1937694153] 'read index received' (duration: 239.548685ms)","trace[1937694153] 'applied index is now lower than readState.Index' (duration: 248.367µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:35:29.508265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:35:29.201102Z","time spent":"307.053148ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:35:29.508594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.387958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-16T14:35:29.50867Z","caller":"traceutil/trace.go:171","msg":"trace[974840077] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4121; }","duration":"240.518702ms","start":"2026-10-16T14:35:29.268134Z","end":"2026-10-16T14:35:29.508653Z","steps":["trace[974840077] 'agreement among raft nodes before linearized reading' (duration: 239.998858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:56.797569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.035737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:36:56.797772Z","caller":"traceutil/trace.go:171","msg":"trace[1295110180] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"114.886847ms","start":"2026-10-16T14:36:56.682864Z","end":"2026-10-16T14:36:56.797751Z","steps":["trace[1295110180] 'process raft request' (duration: 14.51507ms)","trace[1295110180] 'compare' (duration: 99.819831ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:37:05.084128Z","caller":"traceutil/trace.go:171","msg":"trace[764980736] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"162.264393ms","start":"2026-10-16T14:37:04.921816Z","end":"2026-10-16T14:37:05.08408Z","steps":["trace[764980736] 'process raft request' (duration: 162.018976ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:07.190612Z","caller":"traceutil/trace.go:171","msg":"trace[1686566883] linearizableReadLoop","detail":"{readStateIndex:5578; appliedIndex:5577; }","duration":"107.165584ms","start":"2026-10-16T14:37:07.083403Z","end":"2026-10-16T14:37:07.190569Z","steps":["trace[1686566883] 'read index received' (duration: 106.936577ms)","trace[1686566883] 'applied index is now lower than readState.Index' (duration: 227.257µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:37:07.190808Z","caller":"traceutil/trace.go:171","msg":"trace[404536509] transaction","detail":"{read_only:false; response_revision:5372; number_of_response:1; }","duration":"140.02912ms","start":"2026-10-16T14:37:07.050729Z","end":"2026-10-16T14:37:07.190758Z","steps":["trace[404536509] 'process raft request' (duration: 139.641298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:07.191054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.725203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-16T14:37:07.191173Z","caller":"traceutil/trace.go:171","msg":"trace[2141194254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5372; }","duration":"102.893607ms","start":"2026-10-16T14:37:07.088252Z","end":"2026-10-16T14:37:07.191145Z","steps":["trace[2141194254] 'agreement among raft nodes before linearized reading' (duration: 102.62532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:07.191253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.810163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:37:07.191293Z","caller":"traceutil/trace.go:171","msg":"trace[98722764] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5372; }","duration":"107.944436ms","start":"2026-10-16T14:37:07.083337Z","end":"2026-10-16T14:37:07.191282Z","steps":["trace[98722764] 'agreement among raft nodes before linearized reading' (duration: 107.809182ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:09.31127Z","caller":"traceutil/trace.go:171","msg":"trace[625242724] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"103.149623ms","start":"2026-10-16T14:37:09.208043Z","end":"2026-10-16T14:37:09.311193Z","steps":["trace[625242724] 'process raft request' (duration: 71.720176ms)","trace[625242724] 'compare' (duration: 30.991545ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:37:21.111195Z","caller":"traceutil/trace.go:171","msg":"trace[2045978080] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"130.58657ms","start":"2026-10-16T14:37:20.980551Z","end":"2026-10-16T14:37:21.111138Z","steps":["trace[2045978080] 'process raft request' (duration: 104.698186ms)","trace[2045978080] 'compare' (duration: 25.574536ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:37:21.111201Z","caller":"traceutil/trace.go:171","msg":"trace[1907836989] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"129.181281ms","start":"2026-10-16T14:37:20.981987Z","end":"2026-10-16T14:37:21.111169Z","steps":["trace[1907836989] 'process raft request' (duration: 129.12276ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:21.111249Z","caller":"traceutil/trace.go:171","msg":"trace[962278265] transaction","detail":"{read_only:false; response_revision:5614; number_of_response:1; }","duration":"130.488117ms","start":"2026-10-16T14:37:20.980726Z","end":"2026-10-16T14:37:21.111214Z","steps":["trace[962278265] 'process raft request' (duration: 130.258741ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:21.111404Z","caller":"traceutil/trace.go:171","msg":"trace[127555780] transaction","detail":"{read_only:false; response_revision:5615; number_of_response:1; }","duration":"130.230881ms","start":"2026-10-16T14:37:20.981142Z","end":"2026-10-16T14:37:21.111373Z","steps":["trace[127555780] 'process raft request' (duration: 129.899732ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:21.526847Z","caller":"traceutil/trace.go:171","msg":"trace[363555858] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"145.10112ms","start":"2026-10-16T14:37:21.381717Z","end":"2026-10-16T14:37:21.526818Z","steps":["trace[363555858] 'process raft request' (duration: 144.916505ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:22.246507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"info","ts":"2026-10-16T14:37:22.32904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"80.91295ms","hash":1278391083,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":31543296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T14:37:22.329137Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1278391083,"revision":2939,"compact-revision":-1} {"level":"info","ts":"2026-10-16T14:37:29.540697Z","caller":"traceutil/trace.go:171","msg":"trace[622601606] transaction","detail":"{read_only:false; response_revision:5779; number_of_response:1; }","duration":"229.676081ms","start":"2026-10-16T14:37:29.31099Z","end":"2026-10-16T14:37:29.540666Z","steps":["trace[622601606] 'process raft request' (duration: 229.505576ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:29.540727Z","caller":"traceutil/trace.go:171","msg":"trace[576467091] linearizableReadLoop","detail":"{readStateIndex:5995; appliedIndex:5994; }","duration":"226.152914ms","start":"2026-10-16T14:37:29.314529Z","end":"2026-10-16T14:37:29.540682Z","steps":["trace[576467091] 'read index received' (duration: 226.105483ms)","trace[576467091] 'applied index is now lower than readState.Index' (duration: 43.241µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:37:29.540992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.452733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4939"} {"level":"info","ts":"2026-10-16T14:37:29.54102Z","caller":"traceutil/trace.go:171","msg":"trace[1782936420] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5779; }","duration":"226.552505ms","start":"2026-10-16T14:37:29.314462Z","end":"2026-10-16T14:37:29.541014Z","steps":["trace[1782936420] 'agreement among raft nodes before linearized reading' (duration: 226.400361ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:29.854727Z","caller":"traceutil/trace.go:171","msg":"trace[997615806] linearizableReadLoop","detail":"{readStateIndex:5996; appliedIndex:5995; }","duration":"313.872222ms","start":"2026-10-16T14:37:29.540825Z","end":"2026-10-16T14:37:29.854697Z","steps":["trace[997615806] 'read index received' (duration: 277.143059ms)","trace[997615806] 'applied index is now lower than readState.Index' (duration: 36.727553ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:37:29.854796Z","caller":"traceutil/trace.go:171","msg":"trace[396254508] transaction","detail":"{read_only:false; response_revision:5780; number_of_response:1; }","duration":"512.2684ms","start":"2026-10-16T14:37:29.342488Z","end":"2026-10-16T14:37:29.854757Z","steps":["trace[396254508] 'process raft request' (duration: 475.361913ms)","trace[396254508] 'compare' (duration: 36.679041ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:37:29.854986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.342459Z","time spent":"512.423145ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19171,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:37:29.855052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.615507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jp27k\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-16T14:37:29.855129Z","caller":"traceutil/trace.go:171","msg":"trace[1375630596] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jp27k; range_end:; response_count:1; response_revision:5780; }","duration":"530.770241ms","start":"2026-10-16T14:37:29.324341Z","end":"2026-10-16T14:37:29.855111Z","steps":["trace[1375630596] 'agreement among raft nodes before linearized reading' (duration: 530.484033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:29.855176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.32432Z","time spent":"530.845233ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-jp27k\" "} {"level":"warn","ts":"2026-10-16T14:37:29.856006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.028813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-16T14:37:29.856317Z","caller":"traceutil/trace.go:171","msg":"trace[1398949861] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5780; }","duration":"348.163737ms","start":"2026-10-16T14:37:29.507904Z","end":"2026-10-16T14:37:29.856068Z","steps":["trace[1398949861] 'agreement among raft nodes before linearized reading' (duration: 346.863552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:29.856398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.507879Z","time spent":"348.500585ms","remote":"127.0.0.1:51076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225431,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-10-16T14:37:30.07741Z","caller":"traceutil/trace.go:171","msg":"trace[275961808] transaction","detail":"{read_only:false; response_revision:5781; number_of_response:1; }","duration":"526.811471ms","start":"2026-10-16T14:37:29.550556Z","end":"2026-10-16T14:37:30.077368Z","steps":["trace[275961808] 'process raft request' (duration: 442.520458ms)","trace[275961808] 'compare' (duration: 83.920383ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:37:30.077421Z","caller":"traceutil/trace.go:171","msg":"trace[1586422023] linearizableReadLoop","detail":"{readStateIndex:5998; appliedIndex:5996; }","duration":"222.583226ms","start":"2026-10-16T14:37:29.854795Z","end":"2026-10-16T14:37:30.077378Z","steps":["trace[1586422023] 'read index received' (duration: 138.286552ms)","trace[1586422023] 'applied index is now lower than readState.Index' (duration: 84.295063ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:37:30.077458Z","caller":"traceutil/trace.go:171","msg":"trace[1663758228] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5781; }","duration":"526.204015ms","start":"2026-10-16T14:37:29.551193Z","end":"2026-10-16T14:37:30.077397Z","steps":["trace[1663758228] 'process raft request' (duration: 526.091752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.077562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.928065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:37:30.077569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.232947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-10-16T14:37:30.077649Z","caller":"traceutil/trace.go:171","msg":"trace[1746564412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5781; }","duration":"346.268654ms","start":"2026-10-16T14:37:29.731362Z","end":"2026-10-16T14:37:30.07763Z","steps":["trace[1746564412] 'agreement among raft nodes before linearized reading' (duration: 346.020658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.07764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.550528Z","time spent":"526.970646ms","remote":"127.0.0.1:38146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:37:30.077656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.918972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:37:30.077698Z","caller":"traceutil/trace.go:171","msg":"trace[621224295] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5781; }","duration":"464.413131ms","start":"2026-10-16T14:37:29.613251Z","end":"2026-10-16T14:37:30.077664Z","steps":["trace[621224295] 'agreement among raft nodes before linearized reading' (duration: 464.148164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.077708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.731325Z","time spent":"346.365717ms","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T14:37:30.077724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.55118Z","time spent":"526.38877ms","remote":"127.0.0.1:38146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":103,"response count":0,"response size":2528,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:37:30.077735Z","caller":"traceutil/trace.go:171","msg":"trace[243708486] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5781; }","duration":"293.987124ms","start":"2026-10-16T14:37:29.78371Z","end":"2026-10-16T14:37:30.077698Z","steps":["trace[243708486] 'agreement among raft nodes before linearized reading' (duration: 293.879021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.077764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.613236Z","time spent":"464.512004ms","remote":"127.0.0.1:51114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-16T14:37:30.077789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.146317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-16T14:37:30.077828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.212351ms","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-16T14:37:30.077881Z","caller":"traceutil/trace.go:171","msg":"trace[1598602417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5781; }","duration":"326.292654ms","start":"2026-10-16T14:37:29.751573Z","end":"2026-10-16T14:37:30.077866Z","steps":["trace[1598602417] 'agreement among raft nodes before linearized reading' (duration: 326.074638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.077911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.751557Z","time spent":"326.346445ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-16T14:37:30.078287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.087298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81247"} {"level":"info","ts":"2026-10-16T14:37:30.078346Z","caller":"traceutil/trace.go:171","msg":"trace[1756771974] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5781; }","duration":"216.221052ms","start":"2026-10-16T14:37:29.862109Z","end":"2026-10-16T14:37:30.07833Z","steps":["trace[1756771974] 'agreement among raft nodes before linearized reading' (duration: 215.787339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.078361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.982457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-16T14:37:30.078405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.721498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-16T14:37:30.078432Z","caller":"traceutil/trace.go:171","msg":"trace[1985976428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5781; }","duration":"112.111521ms","start":"2026-10-16T14:37:29.966298Z","end":"2026-10-16T14:37:30.078409Z","steps":["trace[1985976428] 'agreement among raft nodes before linearized reading' (duration: 111.832393ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:30.078471Z","caller":"traceutil/trace.go:171","msg":"trace[603738451] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5781; }","duration":"182.840971ms","start":"2026-10-16T14:37:29.895605Z","end":"2026-10-16T14:37:30.078446Z","steps":["trace[603738451] 'agreement among raft nodes before linearized reading' (duration: 182.601754ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.078491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.926062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-16T14:37:30.078603Z","caller":"traceutil/trace.go:171","msg":"trace[296756776] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5781; }","duration":"533.067135ms","start":"2026-10-16T14:37:29.545503Z","end":"2026-10-16T14:37:30.078571Z","steps":["trace[296756776] 'agreement among raft nodes before linearized reading' (duration: 532.618823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.078664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.54549Z","time spent":"533.165127ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-10-16T14:37:30.078877Z","caller":"traceutil/trace.go:171","msg":"trace[2101042903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5781; }","duration":"512.26889ms","start":"2026-10-16T14:37:29.565552Z","end":"2026-10-16T14:37:30.077821Z","steps":["trace[2101042903] 'agreement among raft nodes before linearized reading' (duration: 512.041293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:37:30.078985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:37:29.565525Z","time spent":"513.446633ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-16T14:38:23.979221Z","caller":"traceutil/trace.go:171","msg":"trace[306260484] transaction","detail":"{read_only:false; response_revision:6306; number_of_response:1; }","duration":"116.4501ms","start":"2026-10-16T14:38:23.862719Z","end":"2026-10-16T14:38:23.979169Z","steps":["trace[306260484] 'process raft request' (duration: 116.017988ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:42:22.25432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5630} {"level":"info","ts":"2026-10-16T14:42:22.342548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5630,"took":"86.142102ms","hash":1674315398,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":26832896,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-16T14:42:22.342651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1674315398,"revision":5630,"compact-revision":2939} {"level":"info","ts":"2026-10-16T14:43:59.167114Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000235b.wal"} {"level":"info","ts":"2026-10-16T14:44:12.946381Z","caller":"traceutil/trace.go:171","msg":"trace[101761081] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"110.012884ms","start":"2026-10-16T14:44:12.836314Z","end":"2026-10-16T14:44:12.946327Z","steps":["trace[101761081] 'process raft request' (duration: 109.523251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:44:14.274079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.847463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:44:14.274427Z","caller":"traceutil/trace.go:171","msg":"trace[1441792365] transaction","detail":"{read_only:false; response_revision:8813; number_of_response:1; }","duration":"306.138868ms","start":"2026-10-16T14:44:13.968268Z","end":"2026-10-16T14:44:14.274407Z","steps":["trace[1441792365] 'process raft request' (duration: 306.001364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:44:14.274499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:44:13.968221Z","time spent":"306.244022ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:44:14.274984Z","caller":"traceutil/trace.go:171","msg":"trace[1607889538] transaction","detail":"{read_only:false; response_revision:8812; number_of_response:1; }","duration":"328.569361ms","start":"2026-10-16T14:44:13.946395Z","end":"2026-10-16T14:44:14.274964Z","steps":["trace[1607889538] 'process raft request' (duration: 40.64887ms)","trace[1607889538] 'compare' (duration: 286.581475ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:44:14.275074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:44:13.946357Z","time spent":"328.670434ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:44:15.13247Z","caller":"traceutil/trace.go:171","msg":"trace[1649417780] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"145.205605ms","start":"2026-10-16T14:44:14.987223Z","end":"2026-10-16T14:44:15.132428Z","steps":["trace[1649417780] 'process raft request' (duration: 99.411405ms)","trace[1649417780] 'compare' (duration: 45.607015ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:44:56.964694Z","caller":"traceutil/trace.go:171","msg":"trace[237947178] transaction","detail":"{read_only:false; response_revision:9100; number_of_response:1; }","duration":"149.513712ms","start":"2026-10-16T14:44:56.815131Z","end":"2026-10-16T14:44:56.964645Z","steps":["trace[237947178] 'process raft request' (duration: 130.78336ms)","trace[237947178] 'compare' (duration: 18.529337ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:44:57.245003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.000771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:44:57.245142Z","caller":"traceutil/trace.go:171","msg":"trace[803294704] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:9100; }","duration":"148.251827ms","start":"2026-10-16T14:44:57.096858Z","end":"2026-10-16T14:44:57.24511Z","steps":["trace[803294704] 'count revisions from in-memory index tree' (duration: 147.848357ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:44:57.304705Z","caller":"traceutil/trace.go:171","msg":"trace[110135749] transaction","detail":"{read_only:false; response_revision:9101; number_of_response:1; }","duration":"177.65229ms","start":"2026-10-16T14:44:57.127015Z","end":"2026-10-16T14:44:57.304667Z","steps":["trace[110135749] 'process raft request' (duration: 177.479236ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:44:57.436106Z","caller":"traceutil/trace.go:171","msg":"trace[1014364864] transaction","detail":"{read_only:false; response_revision:9102; number_of_response:1; }","duration":"125.6353ms","start":"2026-10-16T14:44:57.31044Z","end":"2026-10-16T14:44:57.436076Z","steps":["trace[1014364864] 'process raft request' (duration: 47.409614ms)","trace[1014364864] 'compare' (duration: 77.534748ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:45:58.369862Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T14:45:58.378379Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T14:45:58.378524Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-16T14:46:28.119851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.819365ms","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-16T14:46:28.119967Z","caller":"traceutil/trace.go:171","msg":"trace[1892161387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9962; }","duration":"198.99129ms","start":"2026-10-16T14:46:27.920951Z","end":"2026-10-16T14:46:28.119943Z","steps":["trace[1892161387] 'range keys from in-memory index tree' (duration: 198.64103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:46:28.12038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.440856ms","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-16T14:46:28.120509Z","caller":"traceutil/trace.go:171","msg":"trace[1756701847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9962; }","duration":"117.618851ms","start":"2026-10-16T14:46:28.002859Z","end":"2026-10-16T14:46:28.120478Z","steps":["trace[1756701847] 'range keys from in-memory index tree' (duration: 117.213439ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:46:28.352593Z","caller":"traceutil/trace.go:171","msg":"trace[361277560] transaction","detail":"{read_only:false; response_revision:9965; number_of_response:1; }","duration":"130.403341ms","start":"2026-10-16T14:46:28.222136Z","end":"2026-10-16T14:46:28.352539Z","steps":["trace[361277560] 'process raft request' (duration: 130.111603ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:47:22.261715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7970} {"level":"info","ts":"2026-10-16T14:47:22.337593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7970,"took":"73.39098ms","hash":3287591893,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":27906048,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T14:47:22.337714Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3287591893,"revision":7970,"compact-revision":5630} {"level":"info","ts":"2026-10-16T14:51:49.434579Z","caller":"traceutil/trace.go:171","msg":"trace[582380843] linearizableReadLoop","detail":"{readStateIndex:12724; appliedIndex:12723; }","duration":"166.928851ms","start":"2026-10-16T14:51:49.267625Z","end":"2026-10-16T14:51:49.434553Z","steps":["trace[582380843] 'read index received' (duration: 166.742646ms)","trace[582380843] 'applied index is now lower than readState.Index' (duration: 185.195µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:51:49.434639Z","caller":"traceutil/trace.go:171","msg":"trace[525775981] transaction","detail":"{read_only:false; response_revision:12295; number_of_response:1; }","duration":"178.62739ms","start":"2026-10-16T14:51:49.255982Z","end":"2026-10-16T14:51:49.43461Z","steps":["trace[525775981] 'process raft request' (duration: 178.407714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:51:49.435028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.369803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12432"} {"level":"info","ts":"2026-10-16T14:51:49.43514Z","caller":"traceutil/trace.go:171","msg":"trace[1278239106] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:12295; }","duration":"167.548457ms","start":"2026-10-16T14:51:49.267562Z","end":"2026-10-16T14:51:49.43511Z","steps":["trace[1278239106] 'agreement among raft nodes before linearized reading' (duration: 167.192188ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:52:10.22444Z","caller":"traceutil/trace.go:171","msg":"trace[98392983] linearizableReadLoop","detail":"{readStateIndex:12846; appliedIndex:12845; }","duration":"233.013952ms","start":"2026-10-16T14:52:09.991393Z","end":"2026-10-16T14:52:10.224407Z","steps":["trace[98392983] 'read index received' (duration: 232.787456ms)","trace[98392983] 'applied index is now lower than readState.Index' (duration: 224.816µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:52:10.224487Z","caller":"traceutil/trace.go:171","msg":"trace[38534158] transaction","detail":"{read_only:false; response_revision:12413; number_of_response:1; }","duration":"281.257427ms","start":"2026-10-16T14:52:09.943192Z","end":"2026-10-16T14:52:10.224449Z","steps":["trace[38534158] 'process raft request' (duration: 280.970439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:52:10.224806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.48093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-16T14:52:10.224865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.434404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:52:10.225028Z","caller":"traceutil/trace.go:171","msg":"trace[10731753] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:12413; }","duration":"233.68185ms","start":"2026-10-16T14:52:09.991332Z","end":"2026-10-16T14:52:10.225014Z","steps":["trace[10731753] 'agreement among raft nodes before linearized reading' (duration: 233.250259ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:52:10.224897Z","caller":"traceutil/trace.go:171","msg":"trace[341101405] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:12413; }","duration":"151.592492ms","start":"2026-10-16T14:52:10.073283Z","end":"2026-10-16T14:52:10.224876Z","steps":["trace[341101405] 'agreement among raft nodes before linearized reading' (duration: 151.412868ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:52:22.269723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10337} {"level":"info","ts":"2026-10-16T14:52:22.36389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10337,"took":"89.752177ms","hash":1520747158,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":25686016,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T14:52:22.363958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1520747158,"revision":10337,"compact-revision":7970} {"level":"info","ts":"2026-10-16T14:57:22.277593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12501} {"level":"info","ts":"2026-10-16T14:57:22.356659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12501,"took":"76.267439ms","hash":2402010002,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":27648000,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T14:57:22.356734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2402010002,"revision":12501,"compact-revision":10337} {"level":"warn","ts":"2026-10-16T14:58:24.408309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.268008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:58:24.408358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.532541ms","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-16T14:58:24.40851Z","caller":"traceutil/trace.go:171","msg":"trace[1877169780] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:14927; }","duration":"132.688236ms","start":"2026-10-16T14:58:24.275737Z","end":"2026-10-16T14:58:24.408425Z","steps":["trace[1877169780] 'count revisions from in-memory index tree' (duration: 132.432799ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:58:24.408649Z","caller":"traceutil/trace.go:171","msg":"trace[284756843] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:14927; }","duration":"120.473463ms","start":"2026-10-16T14:58:24.287938Z","end":"2026-10-16T14:58:24.408411Z","steps":["trace[284756843] 'count revisions from in-memory index tree' (duration: 120.186565ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:58:27.613029Z","caller":"traceutil/trace.go:171","msg":"trace[87884608] transaction","detail":"{read_only:false; response_revision:14942; number_of_response:1; }","duration":"169.469128ms","start":"2026-10-16T14:58:27.443537Z","end":"2026-10-16T14:58:27.613006Z","steps":["trace[87884608] 'process raft request' (duration: 169.302254ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:58:48.848434Z","caller":"traceutil/trace.go:171","msg":"trace[1747497556] transaction","detail":"{read_only:false; response_revision:15057; number_of_response:1; }","duration":"294.389463ms","start":"2026-10-16T14:58:48.554014Z","end":"2026-10-16T14:58:48.848403Z","steps":["trace[1747497556] 'process raft request' (duration: 294.211248ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:58:48.849965Z","caller":"traceutil/trace.go:171","msg":"trace[42794278] transaction","detail":"{read_only:false; response_revision:15058; number_of_response:1; }","duration":"294.819454ms","start":"2026-10-16T14:58:48.555125Z","end":"2026-10-16T14:58:48.849945Z","steps":["trace[42794278] 'process raft request' (duration: 294.729431ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:59:08.078957Z","caller":"traceutil/trace.go:171","msg":"trace[1573126977] transaction","detail":"{read_only:false; response_revision:15168; number_of_response:1; }","duration":"245.367197ms","start":"2026-10-16T14:59:07.83356Z","end":"2026-10-16T14:59:08.078927Z","steps":["trace[1573126977] 'process raft request' (duration: 245.165151ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:59:08.28597Z","caller":"traceutil/trace.go:171","msg":"trace[1650014013] transaction","detail":"{read_only:false; response_revision:15169; number_of_response:1; }","duration":"393.062171ms","start":"2026-10-16T14:59:07.892878Z","end":"2026-10-16T14:59:08.28594Z","steps":["trace[1650014013] 'process raft request' (duration: 392.819715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:08.286219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:59:07.892852Z","time spent":"393.213576ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:59:08.286399Z","caller":"traceutil/trace.go:171","msg":"trace[2036431416] linearizableReadLoop","detail":"{readStateIndex:15706; appliedIndex:15701; }","duration":"274.568423ms","start":"2026-10-16T14:59:08.011804Z","end":"2026-10-16T14:59:08.286372Z","steps":["trace[2036431416] 'read index received' (duration: 67.001536ms)","trace[2036431416] 'applied index is now lower than readState.Index' (duration: 207.565117ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:59:08.286543Z","caller":"traceutil/trace.go:171","msg":"trace[520200814] transaction","detail":"{read_only:false; response_revision:15170; number_of_response:1; }","duration":"323.725943ms","start":"2026-10-16T14:59:07.962804Z","end":"2026-10-16T14:59:08.28653Z","steps":["trace[520200814] 'process raft request' (duration: 323.087476ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:59:08.286579Z","caller":"traceutil/trace.go:171","msg":"trace[1883368387] transaction","detail":"{read_only:false; response_revision:15173; number_of_response:1; }","duration":"219.125321ms","start":"2026-10-16T14:59:08.06744Z","end":"2026-10-16T14:59:08.286565Z","steps":["trace[1883368387] 'process raft request' (duration: 218.879204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:08.286653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:59:07.962773Z","time spent":"323.792864ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:59:08.286723Z","caller":"traceutil/trace.go:171","msg":"trace[1802513832] transaction","detail":"{read_only:false; response_revision:15171; number_of_response:1; }","duration":"317.479011ms","start":"2026-10-16T14:59:07.969233Z","end":"2026-10-16T14:59:08.286712Z","steps":["trace[1802513832] 'process raft request' (duration: 316.902535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:08.286782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:59:07.969208Z","time spent":"317.537803ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:59:08.286921Z","caller":"traceutil/trace.go:171","msg":"trace[605015056] transaction","detail":"{read_only:false; response_revision:15172; number_of_response:1; }","duration":"304.288661ms","start":"2026-10-16T14:59:07.982622Z","end":"2026-10-16T14:59:08.28691Z","steps":["trace[605015056] 'process raft request' (duration: 303.638894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:08.287001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:59:07.982573Z","time spent":"304.375443ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:59:08.287016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.21732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T14:59:08.287059Z","caller":"traceutil/trace.go:171","msg":"trace[1567427160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15173; }","duration":"275.303222ms","start":"2026-10-16T14:59:08.011744Z","end":"2026-10-16T14:59:08.287047Z","steps":["trace[1567427160] 'agreement among raft nodes before linearized reading' (duration: 275.109987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:08.287225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.881158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-16T14:59:08.287267Z","caller":"traceutil/trace.go:171","msg":"trace[1163584110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15173; }","duration":"195.919359ms","start":"2026-10-16T14:59:08.091334Z","end":"2026-10-16T14:59:08.287253Z","steps":["trace[1163584110] 'agreement among raft nodes before linearized reading' (duration: 195.778076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:08.287398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.43619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:59:08.287429Z","caller":"traceutil/trace.go:171","msg":"trace[492235309] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:15173; }","duration":"117.521733ms","start":"2026-10-16T14:59:08.169897Z","end":"2026-10-16T14:59:08.287419Z","steps":["trace[492235309] 'agreement among raft nodes before linearized reading' (duration: 117.442311ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:59:18.791403Z","caller":"traceutil/trace.go:171","msg":"trace[549458166] linearizableReadLoop","detail":"{readStateIndex:15882; appliedIndex:15881; }","duration":"134.299082ms","start":"2026-10-16T14:59:18.657077Z","end":"2026-10-16T14:59:18.791377Z","steps":["trace[549458166] 'read index received' (duration: 134.076176ms)","trace[549458166] 'applied index is now lower than readState.Index' (duration: 221.696µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:59:18.791489Z","caller":"traceutil/trace.go:171","msg":"trace[165989139] transaction","detail":"{read_only:false; response_revision:15345; number_of_response:1; }","duration":"150.917625ms","start":"2026-10-16T14:59:18.640502Z","end":"2026-10-16T14:59:18.79142Z","steps":["trace[165989139] 'process raft request' (duration: 150.71778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:18.791643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.665403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:59:18.791698Z","caller":"traceutil/trace.go:171","msg":"trace[587476888] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:15345; }","duration":"119.746225ms","start":"2026-10-16T14:59:18.671938Z","end":"2026-10-16T14:59:18.791684Z","steps":["trace[587476888] 'agreement among raft nodes before linearized reading' (duration: 119.603001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:18.792915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.769351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:632622"} {"level":"info","ts":"2026-10-16T14:59:18.793102Z","caller":"traceutil/trace.go:171","msg":"trace[251411708] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:15345; }","duration":"136.04345ms","start":"2026-10-16T14:59:18.657034Z","end":"2026-10-16T14:59:18.793078Z","steps":["trace[251411708] 'agreement among raft nodes before linearized reading' (duration: 134.480337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:19.03731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.024555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-gmxhw.18df0a88544dfd66\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-10-16T14:59:19.037413Z","caller":"traceutil/trace.go:171","msg":"trace[1382420500] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-gmxhw.18df0a88544dfd66; range_end:; response_count:1; response_revision:15345; }","duration":"145.21317ms","start":"2026-10-16T14:59:18.892174Z","end":"2026-10-16T14:59:19.037387Z","steps":["trace[1382420500] 'range keys from in-memory index tree' (duration: 144.750457ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:03.068631Z","caller":"traceutil/trace.go:171","msg":"trace[1065742030] transaction","detail":"{read_only:false; response_revision:16448; number_of_response:1; }","duration":"251.213689ms","start":"2026-10-16T15:01:02.817353Z","end":"2026-10-16T15:01:03.068566Z","steps":["trace[1065742030] 'process raft request' (duration: 250.923991ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:03.101589Z","caller":"traceutil/trace.go:171","msg":"trace[1455757277] linearizableReadLoop","detail":"{readStateIndex:17020; appliedIndex:17018; }","duration":"105.27538ms","start":"2026-10-16T15:01:02.99629Z","end":"2026-10-16T15:01:03.101566Z","steps":["trace[1455757277] 'read index received' (duration: 74.920252ms)","trace[1455757277] 'applied index is now lower than readState.Index' (duration: 30.353458ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:01:03.101697Z","caller":"traceutil/trace.go:171","msg":"trace[306798667] transaction","detail":"{read_only:false; response_revision:16450; number_of_response:1; }","duration":"182.068364ms","start":"2026-10-16T15:01:02.919574Z","end":"2026-10-16T15:01:03.101642Z","steps":["trace[306798667] 'process raft request' (duration: 181.91292ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:03.102416Z","caller":"traceutil/trace.go:171","msg":"trace[1239533754] transaction","detail":"{read_only:false; response_revision:16449; number_of_response:1; }","duration":"196.092876ms","start":"2026-10-16T15:01:02.906264Z","end":"2026-10-16T15:01:03.102357Z","steps":["trace[1239533754] 'process raft request' (duration: 195.032658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.102574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.265417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-16T15:01:03.102634Z","caller":"traceutil/trace.go:171","msg":"trace[255807396] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16450; }","duration":"106.39286ms","start":"2026-10-16T15:01:02.996227Z","end":"2026-10-16T15:01:03.102619Z","steps":["trace[255807396] 'agreement among raft nodes before linearized reading' (duration: 105.519166ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:03.373067Z","caller":"traceutil/trace.go:171","msg":"trace[1306689706] transaction","detail":"{read_only:false; response_revision:16451; number_of_response:1; }","duration":"265.055038ms","start":"2026-10-16T15:01:03.107969Z","end":"2026-10-16T15:01:03.373024Z","steps":["trace[1306689706] 'process raft request' (duration: 182.562349ms)","trace[1306689706] 'compare' (duration: 82.17604ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:01:03.373103Z","caller":"traceutil/trace.go:171","msg":"trace[284246045] linearizableReadLoop","detail":"{readStateIndex:17022; appliedIndex:17020; }","duration":"237.075005ms","start":"2026-10-16T15:01:03.135992Z","end":"2026-10-16T15:01:03.373067Z","steps":["trace[284246045] 'read index received' (duration: 154.490863ms)","trace[284246045] 'applied index is now lower than readState.Index' (duration: 82.582712ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:01:03.373189Z","caller":"traceutil/trace.go:171","msg":"trace[1667590274] transaction","detail":"{read_only:false; response_revision:16452; number_of_response:1; }","duration":"264.859461ms","start":"2026-10-16T15:01:03.108284Z","end":"2026-10-16T15:01:03.373143Z","steps":["trace[1667590274] 'process raft request' (duration: 264.682596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.3733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.29911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:01:03.373385Z","caller":"traceutil/trace.go:171","msg":"trace[1239761415] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16452; }","duration":"237.438064ms","start":"2026-10-16T15:01:03.135929Z","end":"2026-10-16T15:01:03.373367Z","steps":["trace[1239761415] 'agreement among raft nodes before linearized reading' (duration: 237.30162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.373616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.94016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T15:01:03.373718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.20625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:01:03.373764Z","caller":"traceutil/trace.go:171","msg":"trace[1980504157] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16452; }","duration":"236.267922ms","start":"2026-10-16T15:01:03.137486Z","end":"2026-10-16T15:01:03.373754Z","steps":["trace[1980504157] 'agreement among raft nodes before linearized reading' (duration: 236.18478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.373878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.295099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"info","ts":"2026-10-16T15:01:03.373675Z","caller":"traceutil/trace.go:171","msg":"trace[585635700] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16452; }","duration":"237.053843ms","start":"2026-10-16T15:01:03.136607Z","end":"2026-10-16T15:01:03.37366Z","steps":["trace[585635700] 'agreement among raft nodes before linearized reading' (duration: 236.93739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.373909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.408766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"info","ts":"2026-10-16T15:01:03.373949Z","caller":"traceutil/trace.go:171","msg":"trace[105347651] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16452; }","duration":"179.384941ms","start":"2026-10-16T15:01:03.194551Z","end":"2026-10-16T15:01:03.373936Z","steps":["trace[105347651] 'agreement among raft nodes before linearized reading' (duration: 179.195296ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:03.373973Z","caller":"traceutil/trace.go:171","msg":"trace[1653346011] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16452; }","duration":"236.498788ms","start":"2026-10-16T15:01:03.137459Z","end":"2026-10-16T15:01:03.373958Z","steps":["trace[1653346011] 'agreement among raft nodes before linearized reading' (duration: 236.255561ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.373712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.861652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:01:03.374085Z","caller":"traceutil/trace.go:171","msg":"trace[389021480] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16452; }","duration":"199.276023ms","start":"2026-10-16T15:01:03.174799Z","end":"2026-10-16T15:01:03.374075Z","steps":["trace[389021480] 'agreement among raft nodes before linearized reading' (duration: 198.839481ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.374292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.655373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-16T15:01:03.37436Z","caller":"traceutil/trace.go:171","msg":"trace[1164611672] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16452; }","duration":"199.757507ms","start":"2026-10-16T15:01:03.174584Z","end":"2026-10-16T15:01:03.374342Z","steps":["trace[1164611672] 'agreement among raft nodes before linearized reading' (duration: 199.209551ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:01:03.655707Z","caller":"traceutil/trace.go:171","msg":"trace[425619123] transaction","detail":"{read_only:false; response_revision:16453; number_of_response:1; }","duration":"129.984524ms","start":"2026-10-16T15:01:03.525674Z","end":"2026-10-16T15:01:03.655659Z","steps":["trace[425619123] 'process raft request' (duration: 129.507831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.832408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.47927ms","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-16T15:01:03.832508Z","caller":"traceutil/trace.go:171","msg":"trace[1473628631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16453; }","duration":"140.593043ms","start":"2026-10-16T15:01:03.691893Z","end":"2026-10-16T15:01:03.832486Z","steps":["trace[1473628631] 'range keys from in-memory index tree' (duration: 140.302946ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:01:03.832749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.411396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T15:01:03.832848Z","caller":"traceutil/trace.go:171","msg":"trace[1854495078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16453; }","duration":"137.57403ms","start":"2026-10-16T15:01:03.695251Z","end":"2026-10-16T15:01:03.832825Z","steps":["trace[1854495078] 'range keys from in-memory index tree' (duration: 137.19447ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:02:22.286936Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14527} {"level":"info","ts":"2026-10-16T15:02:22.379716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14527,"took":"88.72935ms","hash":774949187,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":31301632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:02:22.379842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":774949187,"revision":14527,"compact-revision":12501} {"level":"warn","ts":"2026-10-16T15:06:35.425223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.864845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-10-16T15:06:35.425303Z","caller":"traceutil/trace.go:171","msg":"trace[139883986] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18870; }","duration":"208.000249ms","start":"2026-10-16T15:06:35.217288Z","end":"2026-10-16T15:06:35.425288Z","steps":["trace[139883986] 'range keys from in-memory index tree' (duration: 207.68385ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:44.077342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.337664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:06:44.077465Z","caller":"traceutil/trace.go:171","msg":"trace[1367532127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18918; }","duration":"344.524779ms","start":"2026-10-16T15:06:43.732915Z","end":"2026-10-16T15:06:44.07744Z","steps":["trace[1367532127] 'range keys from in-memory index tree' (duration: 344.233762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:44.077514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:06:43.732894Z","time spent":"344.609282ms","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T15:06:44.078105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.25869ms","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-16T15:06:44.078238Z","caller":"traceutil/trace.go:171","msg":"trace[1797975194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18918; }","duration":"119.458165ms","start":"2026-10-16T15:06:43.958759Z","end":"2026-10-16T15:06:44.078218Z","steps":["trace[1797975194] 'range keys from in-memory index tree' (duration: 119.041913ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:06:44.216643Z","caller":"traceutil/trace.go:171","msg":"trace[824248304] transaction","detail":"{read_only:false; response_revision:18919; number_of_response:1; }","duration":"125.768867ms","start":"2026-10-16T15:06:44.090841Z","end":"2026-10-16T15:06:44.21661Z","steps":["trace[824248304] 'process raft request' (duration: 125.569902ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:06:44.240744Z","caller":"traceutil/trace.go:171","msg":"trace[309293772] linearizableReadLoop","detail":"{readStateIndex:19571; appliedIndex:19569; }","duration":"129.943171ms","start":"2026-10-16T15:06:44.110771Z","end":"2026-10-16T15:06:44.240714Z","steps":["trace[309293772] 'read index received' (duration: 105.622869ms)","trace[309293772] 'applied index is now lower than readState.Index' (duration: 24.319052ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:06:44.240958Z","caller":"traceutil/trace.go:171","msg":"trace[307161516] transaction","detail":"{read_only:false; response_revision:18920; number_of_response:1; }","duration":"148.677792ms","start":"2026-10-16T15:06:44.092192Z","end":"2026-10-16T15:06:44.24087Z","steps":["trace[307161516] 'process raft request' (duration: 148.083086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:44.241299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.050759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jfjpq\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-10-16T15:06:44.241392Z","caller":"traceutil/trace.go:171","msg":"trace[1135226149] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jfjpq; range_end:; response_count:1; response_revision:18920; }","duration":"106.227905ms","start":"2026-10-16T15:06:44.135137Z","end":"2026-10-16T15:06:44.241365Z","steps":["trace[1135226149] 'agreement among raft nodes before linearized reading' (duration: 105.872025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:06:44.241481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.240389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:06:44.241538Z","caller":"traceutil/trace.go:171","msg":"trace[1053121241] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:18920; }","duration":"130.824105ms","start":"2026-10-16T15:06:44.110696Z","end":"2026-10-16T15:06:44.24152Z","steps":["trace[1053121241] 'agreement among raft nodes before linearized reading' (duration: 130.231068ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:07:22.294675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16947} {"level":"info","ts":"2026-10-16T15:07:22.417098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16947,"took":"118.272083ms","hash":166680803,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":32423936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T15:07:22.417207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":166680803,"revision":16947,"compact-revision":14527} {"level":"info","ts":"2026-10-16T15:07:26.515707Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:07:26.570572Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T15:07:26.571122Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T15:12:22.303343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19292} {"level":"info","ts":"2026-10-16T15:12:22.423123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19292,"took":"115.696593ms","hash":3562453362,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":33882112,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T15:12:22.423194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3562453362,"revision":19292,"compact-revision":16947} {"level":"info","ts":"2026-10-16T15:13:33.456036Z","caller":"traceutil/trace.go:171","msg":"trace[416362549] transaction","detail":"{read_only:false; response_revision:21929; number_of_response:1; }","duration":"215.086203ms","start":"2026-10-16T15:13:33.240914Z","end":"2026-10-16T15:13:33.456Z","steps":["trace[416362549] 'process raft request' (duration: 214.834676ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:13:33.743443Z","caller":"traceutil/trace.go:171","msg":"trace[667718021] linearizableReadLoop","detail":"{readStateIndex:22680; appliedIndex:22678; }","duration":"477.010564ms","start":"2026-10-16T15:13:33.266398Z","end":"2026-10-16T15:13:33.743408Z","steps":["trace[667718021] 'read index received' (duration: 189.336111ms)","trace[667718021] 'applied index is now lower than readState.Index' (duration: 287.672963ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:13:33.743897Z","caller":"traceutil/trace.go:171","msg":"trace[1569774137] transaction","detail":"{read_only:false; response_revision:21930; number_of_response:1; }","duration":"498.821518ms","start":"2026-10-16T15:13:33.244994Z","end":"2026-10-16T15:13:33.743816Z","steps":["trace[1569774137] 'process raft request' (duration: 498.226362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:13:33.744297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:13:33.244945Z","time spent":"499.22673ms","remote":"127.0.0.1:55248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8040,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:13:33.744128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.699152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12433"} {"level":"info","ts":"2026-10-16T15:13:33.745011Z","caller":"traceutil/trace.go:171","msg":"trace[485695331] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21930; }","duration":"478.596537ms","start":"2026-10-16T15:13:33.266319Z","end":"2026-10-16T15:13:33.744916Z","steps":["trace[485695331] 'agreement among raft nodes before linearized reading' (duration: 477.472507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:13:33.745122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:13:33.266291Z","time spent":"478.812664ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12456,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-16T15:13:34.036677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.323577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:13:34.03679Z","caller":"traceutil/trace.go:171","msg":"trace[903931146] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21930; }","duration":"165.471041ms","start":"2026-10-16T15:13:33.871289Z","end":"2026-10-16T15:13:34.03676Z","steps":["trace[903931146] 'range keys from in-memory index tree' (duration: 165.256005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:13:34.037452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.605886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-5gn5h\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-10-16T15:13:34.037484Z","caller":"traceutil/trace.go:171","msg":"trace[768625461] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-5gn5h; range_end:; response_count:1; response_revision:21930; }","duration":"168.681358ms","start":"2026-10-16T15:13:33.868792Z","end":"2026-10-16T15:13:34.037474Z","steps":["trace[768625461] 'range keys from in-memory index tree' (duration: 168.409781ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:13:34.223753Z","caller":"traceutil/trace.go:171","msg":"trace[1080130064] linearizableReadLoop","detail":"{readStateIndex:22681; appliedIndex:22680; }","duration":"137.015165ms","start":"2026-10-16T15:13:34.086709Z","end":"2026-10-16T15:13:34.223724Z","steps":["trace[1080130064] 'read index received' (duration: 136.82718ms)","trace[1080130064] 'applied index is now lower than readState.Index' (duration: 183.165µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:13:34.223844Z","caller":"traceutil/trace.go:171","msg":"trace[592641770] transaction","detail":"{read_only:false; response_revision:21931; number_of_response:1; }","duration":"157.981717ms","start":"2026-10-16T15:13:34.065802Z","end":"2026-10-16T15:13:34.223784Z","steps":["trace[592641770] 'process raft request' (duration: 157.74188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:13:34.224008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.259602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-16T15:13:34.224058Z","caller":"traceutil/trace.go:171","msg":"trace[1389614555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21931; }","duration":"137.424467ms","start":"2026-10-16T15:13:34.086618Z","end":"2026-10-16T15:13:34.224043Z","steps":["trace[1389614555] 'agreement among raft nodes before linearized reading' (duration: 137.218941ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:13:34.390806Z","caller":"traceutil/trace.go:171","msg":"trace[582151033] transaction","detail":"{read_only:false; response_revision:21934; number_of_response:1; }","duration":"151.86407ms","start":"2026-10-16T15:13:34.238905Z","end":"2026-10-16T15:13:34.390769Z","steps":["trace[582151033] 'process raft request' (duration: 151.790029ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:13:34.390911Z","caller":"traceutil/trace.go:171","msg":"trace[173634795] transaction","detail":"{read_only:false; response_revision:21932; number_of_response:1; }","duration":"213.965802ms","start":"2026-10-16T15:13:34.176907Z","end":"2026-10-16T15:13:34.390873Z","steps":["trace[173634795] 'process raft request' (duration: 120.268108ms)","trace[173634795] 'compare' (duration: 93.214051ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:13:34.391176Z","caller":"traceutil/trace.go:171","msg":"trace[172849542] transaction","detail":"{read_only:false; response_revision:21933; number_of_response:1; }","duration":"158.978654ms","start":"2026-10-16T15:13:34.232172Z","end":"2026-10-16T15:13:34.39115Z","steps":["trace[172849542] 'process raft request' (duration: 158.441719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:13:34.69265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.176713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:13:34.692901Z","caller":"traceutil/trace.go:171","msg":"trace[2108297429] transaction","detail":"{read_only:false; response_revision:21936; number_of_response:1; }","duration":"264.299696ms","start":"2026-10-16T15:13:34.428581Z","end":"2026-10-16T15:13:34.69288Z","steps":["trace[2108297429] 'process raft request' (duration: 264.195633ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:13:34.69302Z","caller":"traceutil/trace.go:171","msg":"trace[430028235] transaction","detail":"{read_only:false; response_revision:21935; number_of_response:1; }","duration":"352.447799ms","start":"2026-10-16T15:13:34.340509Z","end":"2026-10-16T15:13:34.692957Z","steps":["trace[430028235] 'process raft request' (duration: 186.861285ms)","trace[430028235] 'compare' (duration: 165.021358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:13:34.693244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:13:34.340471Z","time spent":"352.644644ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:13:34.965265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.187776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:13:34.965621Z","caller":"traceutil/trace.go:171","msg":"trace[1209020989] linearizableReadLoop","detail":"{readStateIndex:22687; appliedIndex:22686; }","duration":"242.317835ms","start":"2026-10-16T15:13:34.723253Z","end":"2026-10-16T15:13:34.965571Z","steps":["trace[1209020989] 'read index received' (duration: 106.640227ms)","trace[1209020989] 'applied index is now lower than readState.Index' (duration: 135.674298ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:13:34.965676Z","caller":"traceutil/trace.go:171","msg":"trace[54158251] transaction","detail":"{read_only:false; response_revision:21937; number_of_response:1; }","duration":"266.858805ms","start":"2026-10-16T15:13:34.698774Z","end":"2026-10-16T15:13:34.965632Z","steps":["trace[54158251] 'process raft request' (duration: 131.176757ms)","trace[54158251] 'compare' (duration: 134.99743ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:13:34.965872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.600964ms","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-16T15:13:34.965923Z","caller":"traceutil/trace.go:171","msg":"trace[2016135912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21938; }","duration":"242.736397ms","start":"2026-10-16T15:13:34.723175Z","end":"2026-10-16T15:13:34.965912Z","steps":["trace[2016135912] 'agreement among raft nodes before linearized reading' (duration: 242.519651ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:13:35.150848Z","caller":"traceutil/trace.go:171","msg":"trace[171517247] linearizableReadLoop","detail":"{readStateIndex:22689; appliedIndex:22688; }","duration":"157.359849ms","start":"2026-10-16T15:13:34.993448Z","end":"2026-10-16T15:13:35.150808Z","steps":["trace[171517247] 'read index received' (duration: 83.785264ms)","trace[171517247] 'applied index is now lower than readState.Index' (duration: 73.573065ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:13:35.151031Z","caller":"traceutil/trace.go:171","msg":"trace[1877519460] transaction","detail":"{read_only:false; response_revision:21939; number_of_response:1; }","duration":"180.007407ms","start":"2026-10-16T15:13:34.970983Z","end":"2026-10-16T15:13:35.15099Z","steps":["trace[1877519460] 'process raft request' (duration: 106.180975ms)","trace[1877519460] 'compare' (duration: 73.403471ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:13:35.151137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.656178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T15:13:35.151178Z","caller":"traceutil/trace.go:171","msg":"trace[1411922377] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:21939; }","duration":"157.790621ms","start":"2026-10-16T15:13:34.993374Z","end":"2026-10-16T15:13:35.151165Z","steps":["trace[1411922377] 'agreement among raft nodes before linearized reading' (duration: 157.648117ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:13:35.659589Z","caller":"traceutil/trace.go:171","msg":"trace[515904073] transaction","detail":"{read_only:false; response_revision:21940; number_of_response:1; }","duration":"156.395404ms","start":"2026-10-16T15:13:35.503118Z","end":"2026-10-16T15:13:35.659513Z","steps":["trace[515904073] 'process raft request' (duration: 156.128907ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:22.309974Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21520} {"level":"info","ts":"2026-10-16T15:17:22.424818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21520,"took":"110.404259ms","hash":1407302249,"current-db-size-bytes":56131584,"current-db-size":"56 MB","current-db-size-in-use-bytes":30531584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:17:22.424906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1407302249,"revision":21520,"compact-revision":19292} {"level":"warn","ts":"2026-10-16T15:18:05.84631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.103403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:18:05.846522Z","caller":"traceutil/trace.go:171","msg":"trace[1991731048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24576; }","duration":"113.34936ms","start":"2026-10-16T15:18:05.733138Z","end":"2026-10-16T15:18:05.846488Z","steps":["trace[1991731048] 'range keys from in-memory index tree' (duration: 113.025621ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:18:06.192089Z","caller":"traceutil/trace.go:171","msg":"trace[567122285] linearizableReadLoop","detail":"{readStateIndex:25411; appliedIndex:25410; }","duration":"310.107544ms","start":"2026-10-16T15:18:05.881955Z","end":"2026-10-16T15:18:06.192063Z","steps":["trace[567122285] 'read index received' (duration: 270.219007ms)","trace[567122285] 'applied index is now lower than readState.Index' (duration: 39.887197ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:18:06.192175Z","caller":"traceutil/trace.go:171","msg":"trace[567130888] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"316.300563ms","start":"2026-10-16T15:18:05.87583Z","end":"2026-10-16T15:18:06.192131Z","steps":["trace[567130888] 'process raft request' (duration: 276.302193ms)","trace[567130888] 'compare' (duration: 39.336052ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:18:06.192337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:18:05.875812Z","time spent":"316.437827ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:18:06.192413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.433873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12433"} {"level":"info","ts":"2026-10-16T15:18:06.1929Z","caller":"traceutil/trace.go:171","msg":"trace[219407799] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24579; }","duration":"310.565547ms","start":"2026-10-16T15:18:05.881899Z","end":"2026-10-16T15:18:06.192464Z","steps":["trace[219407799] 'agreement among raft nodes before linearized reading' (duration: 310.31696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:18:06.193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:18:05.881886Z","time spent":"311.093861ms","remote":"127.0.0.1:51138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12456,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-16T15:18:06.219142Z","caller":"traceutil/trace.go:171","msg":"trace[1836574399] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"320.399965ms","start":"2026-10-16T15:18:05.898712Z","end":"2026-10-16T15:18:06.219112Z","steps":["trace[1836574399] 'process raft request' (duration: 320.173297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:18:06.219347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:18:05.898692Z","time spent":"320.539689ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:18:06.219812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.927603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-16T15:18:06.219929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.205111ms","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-16T15:18:06.219949Z","caller":"traceutil/trace.go:171","msg":"trace[1890959839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24581; }","duration":"294.274912ms","start":"2026-10-16T15:18:05.925667Z","end":"2026-10-16T15:18:06.219942Z","steps":["trace[1890959839] 'agreement among raft nodes before linearized reading' (duration: 294.2158ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:18:06.219941Z","caller":"traceutil/trace.go:171","msg":"trace[1469270499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24581; }","duration":"137.090158ms","start":"2026-10-16T15:18:06.0828Z","end":"2026-10-16T15:18:06.219891Z","steps":["trace[1469270499] 'agreement among raft nodes before linearized reading' (duration: 136.690967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:18:06.219863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.836074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:70568"} {"level":"info","ts":"2026-10-16T15:18:06.220187Z","caller":"traceutil/trace.go:171","msg":"trace[1705500677] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:24581; }","duration":"300.181253ms","start":"2026-10-16T15:18:05.919997Z","end":"2026-10-16T15:18:06.220178Z","steps":["trace[1705500677] 'agreement among raft nodes before linearized reading' (duration: 299.67566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:18:06.221365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:18:05.919966Z","time spent":"301.354675ms","remote":"127.0.0.1:51014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":1,"response size":70591,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io\" "} {"level":"info","ts":"2026-10-16T15:18:06.221481Z","caller":"traceutil/trace.go:171","msg":"trace[786805923] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"105.231659ms","start":"2026-10-16T15:18:06.11494Z","end":"2026-10-16T15:18:06.220172Z","steps":["trace[786805923] 'process raft request' (duration: 104.127099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:18:06.220022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.807973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jfjpq\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-10-16T15:18:06.222038Z","caller":"traceutil/trace.go:171","msg":"trace[199274251] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jfjpq; range_end:; response_count:1; response_revision:24581; }","duration":"199.911511ms","start":"2026-10-16T15:18:06.021989Z","end":"2026-10-16T15:18:06.2219Z","steps":["trace[199274251] 'agreement among raft nodes before linearized reading' (duration: 197.531636ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:37.174406Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006951.wal"} {"level":"info","ts":"2026-10-16T15:21:47.484395Z","caller":"traceutil/trace.go:171","msg":"trace[76044901] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"114.904675ms","start":"2026-10-16T15:21:47.369453Z","end":"2026-10-16T15:21:47.484358Z","steps":["trace[76044901] 'process raft request' (duration: 92.36101ms)","trace[76044901] 'compare' (duration: 22.199156ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:21:52.966231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.948682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:21:52.966777Z","caller":"traceutil/trace.go:171","msg":"trace[2032191940] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"287.481853ms","start":"2026-10-16T15:21:52.679008Z","end":"2026-10-16T15:21:52.96649Z","steps":["trace[2032191940] 'process raft request' (duration: 117.977138ms)","trace[2032191940] 'compare' (duration: 167.573252ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:21:52.96691Z","caller":"traceutil/trace.go:171","msg":"trace[2101448338] linearizableReadLoop","detail":"{readStateIndex:27656; appliedIndex:27655; }","duration":"275.065303ms","start":"2026-10-16T15:21:52.691777Z","end":"2026-10-16T15:21:52.966842Z","steps":["trace[2101448338] 'read index received' (duration: 105.127157ms)","trace[2101448338] 'applied index is now lower than readState.Index' (duration: 169.930116ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:21:52.967135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.297799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:21:52.967251Z","caller":"traceutil/trace.go:171","msg":"trace[34325856] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26768; }","duration":"275.461625ms","start":"2026-10-16T15:21:52.691763Z","end":"2026-10-16T15:21:52.967225Z","steps":["trace[34325856] 'agreement among raft nodes before linearized reading' (duration: 275.225548ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:21:52.967342Z","caller":"traceutil/trace.go:171","msg":"trace[2041315711] transaction","detail":"{read_only:false; response_revision:26769; number_of_response:1; }","duration":"124.496075ms","start":"2026-10-16T15:21:52.842831Z","end":"2026-10-16T15:21:52.967327Z","steps":["trace[2041315711] 'process raft request' (duration: 124.046883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:21:52.967619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.734026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:21:52.967683Z","caller":"traceutil/trace.go:171","msg":"trace[1237216776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26770; }","duration":"233.882899ms","start":"2026-10-16T15:21:52.733783Z","end":"2026-10-16T15:21:52.967666Z","steps":["trace[1237216776] 'agreement among raft nodes before linearized reading' (duration: 233.727245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:21:53.288632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.50686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T15:21:53.288729Z","caller":"traceutil/trace.go:171","msg":"trace[267523466] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26770; }","duration":"189.666684ms","start":"2026-10-16T15:21:53.099042Z","end":"2026-10-16T15:21:53.288709Z","steps":["trace[267523466] 'count revisions from in-memory index tree' (duration: 189.422638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:21:53.289098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.175265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:21:53.289151Z","caller":"traceutil/trace.go:171","msg":"trace[1710787902] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:26770; }","duration":"136.265367ms","start":"2026-10-16T15:21:53.152868Z","end":"2026-10-16T15:21:53.289133Z","steps":["trace[1710787902] 'count revisions from in-memory index tree' (duration: 136.062482ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:21:53.448865Z","caller":"traceutil/trace.go:171","msg":"trace[1039384456] transaction","detail":"{read_only:false; response_revision:26771; number_of_response:1; }","duration":"266.561381ms","start":"2026-10-16T15:21:53.18228Z","end":"2026-10-16T15:21:53.448841Z","steps":["trace[1039384456] 'process raft request' (duration: 266.394617ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:21:53.508656Z","caller":"traceutil/trace.go:171","msg":"trace[933743610] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"213.294048ms","start":"2026-10-16T15:21:53.295344Z","end":"2026-10-16T15:21:53.508638Z","steps":["trace[933743610] 'process raft request' (duration: 213.247017ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:21:53.509071Z","caller":"traceutil/trace.go:171","msg":"trace[1418094920] transaction","detail":"{read_only:false; response_revision:26772; number_of_response:1; }","duration":"319.65836ms","start":"2026-10-16T15:21:53.189395Z","end":"2026-10-16T15:21:53.509053Z","steps":["trace[1418094920] 'process raft request' (duration: 317.040018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:21:53.509197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:21:53.18938Z","time spent":"319.725702ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:22:22.316805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23706} {"level":"info","ts":"2026-10-16T15:22:22.4371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23706,"took":"115.354006ms","hash":2713655111,"current-db-size-bytes":59084800,"current-db-size":"59 MB","current-db-size-in-use-bytes":44630016,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-16T15:22:22.43718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2713655111,"revision":23706,"compact-revision":21520} {"level":"info","ts":"2026-10-16T15:22:52.238502Z","caller":"traceutil/trace.go:171","msg":"trace[2035320573] transaction","detail":"{read_only:false; response_revision:27308; number_of_response:1; }","duration":"115.415638ms","start":"2026-10-16T15:22:52.123035Z","end":"2026-10-16T15:22:52.238451Z","steps":["trace[2035320573] 'process raft request' (duration: 100.07977ms)","trace[2035320573] 'compare' (duration: 14.957658ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:22:57.982674Z","caller":"traceutil/trace.go:171","msg":"trace[220924627] linearizableReadLoop","detail":"{readStateIndex:28251; appliedIndex:28250; }","duration":"247.602565ms","start":"2026-10-16T15:22:57.735036Z","end":"2026-10-16T15:22:57.982639Z","steps":["trace[220924627] 'read index received' (duration: 247.394199ms)","trace[220924627] 'applied index is now lower than readState.Index' (duration: 206.575µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:22:57.982783Z","caller":"traceutil/trace.go:171","msg":"trace[218986605] transaction","detail":"{read_only:false; response_revision:27348; number_of_response:1; }","duration":"264.192196ms","start":"2026-10-16T15:22:57.71855Z","end":"2026-10-16T15:22:57.982742Z","steps":["trace[218986605] 'process raft request' (duration: 263.817636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:57.982914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.891662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:22:57.982968Z","caller":"traceutil/trace.go:171","msg":"trace[1203220141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27348; }","duration":"248.019425ms","start":"2026-10-16T15:22:57.734935Z","end":"2026-10-16T15:22:57.982954Z","steps":["trace[1203220141] 'agreement among raft nodes before linearized reading' (duration: 247.903172ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:57.999863Z","caller":"traceutil/trace.go:171","msg":"trace[143475016] transaction","detail":"{read_only:false; response_revision:27349; number_of_response:1; }","duration":"221.132922ms","start":"2026-10-16T15:22:57.778668Z","end":"2026-10-16T15:22:57.999801Z","steps":["trace[143475016] 'process raft request' (duration: 220.886896ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:57.999953Z","caller":"traceutil/trace.go:171","msg":"trace[1417385160] transaction","detail":"{read_only:false; response_revision:27350; number_of_response:1; }","duration":"221.161073ms","start":"2026-10-16T15:22:57.778751Z","end":"2026-10-16T15:22:57.999913Z","steps":["trace[1417385160] 'process raft request' (duration: 220.964408ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:58.000077Z","caller":"traceutil/trace.go:171","msg":"trace[1412001243] transaction","detail":"{read_only:false; response_revision:27351; number_of_response:1; }","duration":"188.501332ms","start":"2026-10-16T15:22:57.811548Z","end":"2026-10-16T15:22:58.000049Z","steps":["trace[1412001243] 'process raft request' (duration: 188.205744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.002302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.687585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-16T15:22:58.00237Z","caller":"traceutil/trace.go:171","msg":"trace[882811021] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:27351; }","duration":"167.816728ms","start":"2026-10-16T15:22:57.834533Z","end":"2026-10-16T15:22:58.00235Z","steps":["trace[882811021] 'agreement among raft nodes before linearized reading' (duration: 165.598937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.211755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.234029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-16T15:22:58.211866Z","caller":"traceutil/trace.go:171","msg":"trace[695378591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27351; }","duration":"139.395303ms","start":"2026-10-16T15:22:58.072445Z","end":"2026-10-16T15:22:58.21184Z","steps":["trace[695378591] 'range keys from in-memory index tree' (duration: 139.003042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.21189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.890571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-16T15:22:58.211755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.763384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-16T15:22:58.21191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.417889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"info","ts":"2026-10-16T15:22:58.211969Z","caller":"traceutil/trace.go:171","msg":"trace[1246681333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27351; }","duration":"131.032985ms","start":"2026-10-16T15:22:58.080921Z","end":"2026-10-16T15:22:58.211954Z","steps":["trace[1246681333] 'range keys from in-memory index tree' (duration: 130.707316ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:58.212032Z","caller":"traceutil/trace.go:171","msg":"trace[1400805307] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:27351; }","duration":"170.607425ms","start":"2026-10-16T15:22:58.041401Z","end":"2026-10-16T15:22:58.212009Z","steps":["trace[1400805307] 'range keys from in-memory index tree' (duration: 170.196274ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:58.212079Z","caller":"traceutil/trace.go:171","msg":"trace[1553422774] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:27351; }","duration":"169.058182ms","start":"2026-10-16T15:22:58.042928Z","end":"2026-10-16T15:22:58.211987Z","steps":["trace[1553422774] 'count revisions from in-memory index tree' (duration: 168.589929ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:58.366076Z","caller":"traceutil/trace.go:171","msg":"trace[842671224] transaction","detail":"{read_only:false; response_revision:27352; number_of_response:1; }","duration":"149.163379ms","start":"2026-10-16T15:22:58.216824Z","end":"2026-10-16T15:22:58.365987Z","steps":["trace[842671224] 'process raft request' (duration: 148.994574ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:58.545404Z","caller":"traceutil/trace.go:171","msg":"trace[833028446] linearizableReadLoop","detail":"{readStateIndex:28256; appliedIndex:28254; }","duration":"220.502516ms","start":"2026-10-16T15:22:58.324877Z","end":"2026-10-16T15:22:58.54538Z","steps":["trace[833028446] 'read index received' (duration: 41.011199ms)","trace[833028446] 'applied index is now lower than readState.Index' (duration: 179.490397ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:22:58.545488Z","caller":"traceutil/trace.go:171","msg":"trace[76316878] transaction","detail":"{read_only:false; response_revision:27353; number_of_response:1; }","duration":"328.47416ms","start":"2026-10-16T15:22:58.216981Z","end":"2026-10-16T15:22:58.545455Z","steps":["trace[76316878] 'process raft request' (duration: 309.546553ms)","trace[76316878] 'compare' (duration: 18.717921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:22:58.545667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:22:58.216956Z","time spent":"328.599103ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:22:58.54567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.765383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T15:22:58.545733Z","caller":"traceutil/trace.go:171","msg":"trace[141698977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27353; }","duration":"220.906597ms","start":"2026-10-16T15:22:58.324809Z","end":"2026-10-16T15:22:58.545716Z","steps":["trace[141698977] 'agreement among raft nodes before linearized reading' (duration: 220.66058ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:58.830486Z","caller":"traceutil/trace.go:171","msg":"trace[1491637925] transaction","detail":"{read_only:false; response_revision:27354; number_of_response:1; }","duration":"419.347019ms","start":"2026-10-16T15:22:58.411108Z","end":"2026-10-16T15:22:58.830455Z","steps":["trace[1491637925] 'process raft request' (duration: 419.159714ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:22:58.830613Z","caller":"traceutil/trace.go:171","msg":"trace[301592643] linearizableReadLoop","detail":"{readStateIndex:28257; appliedIndex:28257; }","duration":"285.071507ms","start":"2026-10-16T15:22:58.545469Z","end":"2026-10-16T15:22:58.830541Z","steps":["trace[301592643] 'read index received' (duration: 285.046816ms)","trace[301592643] 'applied index is now lower than readState.Index' (duration: 20.511µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:22:58.830696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:22:58.411081Z","time spent":"419.495624ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:22:58.831049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.431797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:22:58.831107Z","caller":"traceutil/trace.go:171","msg":"trace[910240368] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:27354; }","duration":"303.598992ms","start":"2026-10-16T15:22:58.527489Z","end":"2026-10-16T15:22:58.831088Z","steps":["trace[910240368] 'agreement among raft nodes before linearized reading' (duration: 303.279003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.831156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:22:58.527467Z","time spent":"303.673603ms","remote":"127.0.0.1:49348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-10-16T15:22:58.831356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.940973ms","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-16T15:22:58.831412Z","caller":"traceutil/trace.go:171","msg":"trace[1947047374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27354; }","duration":"379.996365ms","start":"2026-10-16T15:22:58.451402Z","end":"2026-10-16T15:22:58.831398Z","steps":["trace[1947047374] 'agreement among raft nodes before linearized reading' (duration: 379.82987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.831442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:22:58.451329Z","time spent":"380.105608ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-16T15:22:58.83162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.122333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T15:22:58.831698Z","caller":"traceutil/trace.go:171","msg":"trace[1730795745] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27354; }","duration":"463.268927ms","start":"2026-10-16T15:22:58.36841Z","end":"2026-10-16T15:22:58.831679Z","steps":["trace[1730795745] 'agreement among raft nodes before linearized reading' (duration: 463.119543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.831757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:22:58.368382Z","time spent":"463.358789ms","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-16T15:22:58.905173Z","caller":"traceutil/trace.go:171","msg":"trace[1236449551] transaction","detail":"{read_only:false; response_revision:27355; number_of_response:1; }","duration":"355.090627ms","start":"2026-10-16T15:22:58.550045Z","end":"2026-10-16T15:22:58.905136Z","steps":["trace[1236449551] 'process raft request' (duration: 352.26952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.905306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.287281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:22:58.9054Z","caller":"traceutil/trace.go:171","msg":"trace[1147341885] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:27356; }","duration":"201.447545ms","start":"2026-10-16T15:22:58.703927Z","end":"2026-10-16T15:22:58.905374Z","steps":["trace[1147341885] 'agreement among raft nodes before linearized reading' (duration: 201.25464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.905411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.660268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419264"} {"level":"warn","ts":"2026-10-16T15:22:58.905445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:22:58.550026Z","time spent":"355.23384ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:22:58.905527Z","caller":"traceutil/trace.go:171","msg":"trace[1036439277] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:27356; }","duration":"250.816803ms","start":"2026-10-16T15:22:58.654681Z","end":"2026-10-16T15:22:58.905498Z","steps":["trace[1036439277] 'agreement among raft nodes before linearized reading' (duration: 250.429502ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:25:57.989277Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:25:58.001064Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T15:25:58.001187Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T15:27:22.324784Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27073} {"level":"info","ts":"2026-10-16T15:27:22.498688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27073,"took":"168.655171ms","hash":3766080212,"current-db-size-bytes":60723200,"current-db-size":"61 MB","current-db-size-in-use-bytes":35962880,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T15:27:22.498765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3766080212,"revision":27073,"compact-revision":23706} {"level":"info","ts":"2026-10-16T15:32:22.336839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29936} {"level":"info","ts":"2026-10-16T15:32:22.498985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29936,"took":"146.103878ms","hash":2526756817,"current-db-size-bytes":60723200,"current-db-size":"61 MB","current-db-size-in-use-bytes":31248384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:32:22.499093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2526756817,"revision":29936,"compact-revision":27073} {"level":"info","ts":"2026-10-16T15:37:22.345713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32210} {"level":"info","ts":"2026-10-16T15:37:22.51488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32210,"took":"163.574883ms","hash":3946854041,"current-db-size-bytes":61767680,"current-db-size":"62 MB","current-db-size-in-use-bytes":30162944,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:37:22.514958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3946854041,"revision":32210,"compact-revision":29936} {"level":"info","ts":"2026-10-16T15:42:22.354706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34436} {"level":"info","ts":"2026-10-16T15:42:22.483628Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34436,"took":"125.017323ms","hash":2309139898,"current-db-size-bytes":61767680,"current-db-size":"62 MB","current-db-size-in-use-bytes":30392320,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:42:22.483695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2309139898,"revision":34436,"compact-revision":32210} {"level":"info","ts":"2026-10-16T15:46:53.235805Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:46:53.242394Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T15:46:53.242545Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T15:47:22.363078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36666} {"level":"info","ts":"2026-10-16T15:47:22.503885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36666,"took":"134.640968ms","hash":2410678848,"current-db-size-bytes":61767680,"current-db-size":"62 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:47:22.503987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2410678848,"revision":36666,"compact-revision":34436} {"level":"info","ts":"2026-10-16T15:52:22.373657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38999} {"level":"info","ts":"2026-10-16T15:52:22.514163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38999,"took":"136.56016ms","hash":2639751616,"current-db-size-bytes":61767680,"current-db-size":"62 MB","current-db-size-in-use-bytes":30666752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:52:22.514224Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639751616,"revision":38999,"compact-revision":36666}