{"level":"warn","ts":"2026-08-27T19:14:52.295428Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-27T19:14:52.295564Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.193: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.193:2380","--initial-cluster=instance=https://162.253.55.193:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.193:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.193: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-08-27T19:14:52.295653Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-27T19:14:52.295671Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-08-27T19:14:52.295703Z","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-08-27T19:14:52.296607Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"]} {"level":"info","ts":"2026-08-27T19:14:52.29671Z","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.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.193: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-08-27T19:14:52.302419Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.403068ms"} {"level":"info","ts":"2026-08-27T19:14:52.311482Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6814e3e3d6b4d713","cluster-id":"e5e99c9118b1492a"} {"level":"info","ts":"2026-08-27T19:14:52.311577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=()"} {"level":"info","ts":"2026-08-27T19:14:52.311604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 0"} {"level":"info","ts":"2026-08-27T19:14:52.311616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6814e3e3d6b4d713 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-27T19:14:52.311634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became follower at term 1"} {"level":"info","ts":"2026-08-27T19:14:52.311682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"warn","ts":"2026-08-27T19:14:52.320311Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-27T19:14:52.322077Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-27T19:14:52.325942Z","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-08-27T19:14:52.328446Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6814e3e3d6b4d713","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-27T19:14:52.329154Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6814e3e3d6b4d713","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-27T19:14:52.329684Z","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-08-27T19:14:52.329792Z","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-08-27T19:14:52.329862Z","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-08-27T19:14:52.332071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 switched to configuration voters=(7499869847177975571)"} {"level":"info","ts":"2026-08-27T19:14:52.332354Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","added-peer-id":"6814e3e3d6b4d713","added-peer-peer-urls":["https://162.253.55.193:2380"]} {"level":"info","ts":"2026-08-27T19:14:52.337172Z","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-08-27T19:14:52.337299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-08-27T19:14:52.337366Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.193:2380"} {"level":"info","ts":"2026-08-27T19:14:52.337598Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6814e3e3d6b4d713","initial-advertise-peer-urls":["https://162.253.55.193:2380"],"listen-peer-urls":["https://162.253.55.193:2380"],"advertise-client-urls":["https://162.253.55.193:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.193:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-27T19:14:52.33767Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-27T19:14:52.912201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 is starting a new election at term 1"} {"level":"info","ts":"2026-08-27T19:14:52.912268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-27T19:14:52.912301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgPreVoteResp from 6814e3e3d6b4d713 at term 1"} {"level":"info","ts":"2026-08-27T19:14:52.91232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became candidate at term 2"} {"level":"info","ts":"2026-08-27T19:14:52.912335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 received MsgVoteResp from 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-08-27T19:14:52.912354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6814e3e3d6b4d713 became leader at term 2"} {"level":"info","ts":"2026-08-27T19:14:52.912371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6814e3e3d6b4d713 elected leader 6814e3e3d6b4d713 at term 2"} {"level":"info","ts":"2026-08-27T19:14:52.913659Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6814e3e3d6b4d713","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.193:2379]}","request-path":"/0/members/6814e3e3d6b4d713/attributes","cluster-id":"e5e99c9118b1492a","publish-timeout":"7s"} {"level":"info","ts":"2026-08-27T19:14:52.913674Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-27T19:14:52.913908Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-27T19:14:52.9141Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-27T19:14:52.914164Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-27T19:14:52.913698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-27T19:14:52.915276Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e5e99c9118b1492a","local-member-id":"6814e3e3d6b4d713","cluster-version":"3.5"} {"level":"info","ts":"2026-08-27T19:14:52.915427Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-27T19:14:52.915468Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-27T19:14:52.917901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.193:2379"} {"level":"info","ts":"2026-08-27T19:14:52.918657Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-27T19:15:14.913472Z","caller":"traceutil/trace.go:171","msg":"trace[843482872] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"151.484785ms","start":"2026-08-27T19:15:14.761939Z","end":"2026-08-27T19:15:14.913424Z","steps":["trace[843482872] 'process raft request' (duration: 149.502331ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:15:37.353801Z","caller":"traceutil/trace.go:171","msg":"trace[1212326502] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"295.541577ms","start":"2026-08-27T19:15:37.058129Z","end":"2026-08-27T19:15:37.35367Z","steps":["trace[1212326502] 'read index received' (duration: 288.647455ms)","trace[1212326502] 'applied index is now lower than readState.Index' (duration: 6.892872ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:15:37.353855Z","caller":"traceutil/trace.go:171","msg":"trace[1006886907] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"341.965315ms","start":"2026-08-27T19:15:37.011801Z","end":"2026-08-27T19:15:37.353766Z","steps":["trace[1006886907] 'process raft request' (duration: 335.615606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:15:37.354149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.982546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-27T19:15:37.354336Z","caller":"traceutil/trace.go:171","msg":"trace[311902792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"296.246552ms","start":"2026-08-27T19:15:37.058059Z","end":"2026-08-27T19:15:37.354305Z","steps":["trace[311902792] 'agreement among raft nodes before linearized reading' (duration: 295.799592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:15:37.35496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:15:37.011767Z","time spent":"342.257822ms","remote":"127.0.0.1:33390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T19:15:37.625725Z","caller":"traceutil/trace.go:171","msg":"trace[2061518930] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"121.072407ms","start":"2026-08-27T19:15:37.504622Z","end":"2026-08-27T19:15:37.625695Z","steps":["trace[2061518930] 'process raft request' (duration: 93.449551ms)","trace[2061518930] 'compare' (duration: 27.004613ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:15:38.16302Z","caller":"traceutil/trace.go:171","msg":"trace[676111171] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"118.406408ms","start":"2026-08-27T19:15:38.044592Z","end":"2026-08-27T19:15:38.162998Z","steps":["trace[676111171] 'read index received' (duration: 116.66552ms)","trace[676111171] 'applied index is now lower than readState.Index' (duration: 1.740148ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:15:38.16319Z","caller":"traceutil/trace.go:171","msg":"trace[924537016] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"243.374402ms","start":"2026-08-27T19:15:37.919778Z","end":"2026-08-27T19:15:38.163153Z","steps":["trace[924537016] 'process raft request' (duration: 241.684805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:15:38.163228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.624643ms","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-08-27T19:15:38.16327Z","caller":"traceutil/trace.go:171","msg":"trace[551823419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"118.720865ms","start":"2026-08-27T19:15:38.044539Z","end":"2026-08-27T19:15:38.163259Z","steps":["trace[551823419] 'agreement among raft nodes before linearized reading' (duration: 118.572862ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:15:39.769452Z","caller":"traceutil/trace.go:171","msg":"trace[688474546] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"120.831762ms","start":"2026-08-27T19:15:39.648587Z","end":"2026-08-27T19:15:39.769418Z","steps":["trace[688474546] 'process raft request' (duration: 118.46038ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:17:13.295874Z","caller":"traceutil/trace.go:171","msg":"trace[997182310] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"367.349567ms","start":"2026-08-27T19:17:12.928493Z","end":"2026-08-27T19:17:13.295843Z","steps":["trace[997182310] 'process raft request' (duration: 271.624875ms)","trace[997182310] 'compare' (duration: 95.550001ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:17:13.296046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:17:12.928475Z","time spent":"367.493546ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:17:13.296124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.788347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-27T19:17:13.296297Z","caller":"traceutil/trace.go:171","msg":"trace[801373714] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"351.072465ms","start":"2026-08-27T19:17:12.945207Z","end":"2026-08-27T19:17:13.29628Z","steps":["trace[801373714] 'agreement among raft nodes before linearized reading' (duration: 350.727263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:17:13.296337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:17:12.945187Z","time spent":"351.14052ms","remote":"127.0.0.1:34238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-27T19:17:13.295819Z","caller":"traceutil/trace.go:171","msg":"trace[1864515031] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"350.537171ms","start":"2026-08-27T19:17:12.945253Z","end":"2026-08-27T19:17:13.29579Z","steps":["trace[1864515031] 'read index received' (duration: 254.863872ms)","trace[1864515031] 'applied index is now lower than readState.Index' (duration: 95.672409ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:17:13.343665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.140887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-96e89e2f-0e7a-4d0c-b4d6-a1fbb2788266\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-27T19:17:13.343602Z","caller":"traceutil/trace.go:171","msg":"trace[883661347] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"233.94956ms","start":"2026-08-27T19:17:13.109597Z","end":"2026-08-27T19:17:13.343546Z","steps":["trace[883661347] 'process raft request' (duration: 233.78252ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:17:13.343723Z","caller":"traceutil/trace.go:171","msg":"trace[173035206] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-96e89e2f-0e7a-4d0c-b4d6-a1fbb2788266; range_end:; response_count:1; response_revision:1888; }","duration":"274.237513ms","start":"2026-08-27T19:17:13.069471Z","end":"2026-08-27T19:17:13.343708Z","steps":["trace[173035206] 'agreement among raft nodes before linearized reading' (duration: 274.049741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:17:13.34376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.221667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-96e89e2f-0e7a-4d0c-b4d6-a1fbb2788266\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-27T19:17:13.343811Z","caller":"traceutil/trace.go:171","msg":"trace[36227155] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-96e89e2f-0e7a-4d0c-b4d6-a1fbb2788266; range_end:; response_count:1; response_revision:1888; }","duration":"334.306102ms","start":"2026-08-27T19:17:13.00949Z","end":"2026-08-27T19:17:13.343796Z","steps":["trace[36227155] 'agreement among raft nodes before linearized reading' (duration: 334.155333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:17:13.343848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:17:13.009477Z","time spent":"334.362846ms","remote":"127.0.0.1:33458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-96e89e2f-0e7a-4d0c-b4d6-a1fbb2788266\" "} {"level":"info","ts":"2026-08-27T19:17:13.514638Z","caller":"traceutil/trace.go:171","msg":"trace[731918461] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"163.754387ms","start":"2026-08-27T19:17:13.350866Z","end":"2026-08-27T19:17:13.514621Z","steps":["trace[731918461] 'process raft request' (duration: 163.628739ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:17:13.751654Z","caller":"traceutil/trace.go:171","msg":"trace[1931952437] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"115.280966ms","start":"2026-08-27T19:17:13.636314Z","end":"2026-08-27T19:17:13.751595Z","steps":["trace[1931952437] 'process raft request' (duration: 67.356561ms)","trace[1931952437] 'compare' (duration: 47.628876ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:17:28.925325Z","caller":"traceutil/trace.go:171","msg":"trace[15046384] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"160.264596ms","start":"2026-08-27T19:17:28.765027Z","end":"2026-08-27T19:17:28.925292Z","steps":["trace[15046384] 'read index received' (duration: 115.992µs)","trace[15046384] 'applied index is now lower than readState.Index' (duration: 160.146964ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:17:28.925455Z","caller":"traceutil/trace.go:171","msg":"trace[1498908148] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"160.44584ms","start":"2026-08-27T19:17:28.764981Z","end":"2026-08-27T19:17:28.925427Z","steps":["trace[1498908148] 'process raft request' (duration: 160.117013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:17:28.925637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.579874ms","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-08-27T19:17:28.925689Z","caller":"traceutil/trace.go:171","msg":"trace[1590444922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2024; }","duration":"160.673685ms","start":"2026-08-27T19:17:28.764998Z","end":"2026-08-27T19:17:28.925672Z","steps":["trace[1590444922] 'agreement among raft nodes before linearized reading' (duration: 160.455781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:18:02.80412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.708571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-27T19:18:02.804288Z","caller":"traceutil/trace.go:171","msg":"trace[1546724420] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2216; }","duration":"150.939196ms","start":"2026-08-27T19:18:02.653321Z","end":"2026-08-27T19:18:02.804261Z","steps":["trace[1546724420] 'range keys from in-memory index tree' (duration: 150.460665ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:18:02.915313Z","caller":"traceutil/trace.go:171","msg":"trace[718664193] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"192.682305ms","start":"2026-08-27T19:18:02.722602Z","end":"2026-08-27T19:18:02.915284Z","steps":["trace[718664193] 'process raft request' (duration: 192.521562ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:18:02.930938Z","caller":"traceutil/trace.go:171","msg":"trace[1589157163] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"187.087734ms","start":"2026-08-27T19:18:02.743824Z","end":"2026-08-27T19:18:02.930911Z","steps":["trace[1589157163] 'process raft request' (duration: 186.939601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:18:09.14166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.534656ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:18:09.141789Z","caller":"traceutil/trace.go:171","msg":"trace[561098679] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2248; }","duration":"265.68155ms","start":"2026-08-27T19:18:08.876088Z","end":"2026-08-27T19:18:09.14177Z","steps":["trace[561098679] 'range keys from in-memory index tree' (duration: 265.519366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:18:09.142034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.872318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-27T19:18:09.142113Z","caller":"traceutil/trace.go:171","msg":"trace[1363288416] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2248; }","duration":"293.96501ms","start":"2026-08-27T19:18:08.848129Z","end":"2026-08-27T19:18:09.142094Z","steps":["trace[1363288416] 'range keys from in-memory index tree' (duration: 293.591902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:18:09.142272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.42707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-27T19:18:09.142335Z","caller":"traceutil/trace.go:171","msg":"trace[1325008588] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"209.375536ms","start":"2026-08-27T19:18:08.932943Z","end":"2026-08-27T19:18:09.142319Z","steps":["trace[1325008588] 'process raft request' (duration: 23.686511ms)","trace[1325008588] 'compare' (duration: 185.094353ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:18:09.462447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.856932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-27T19:18:09.462544Z","caller":"traceutil/trace.go:171","msg":"trace[701483746] linearizableReadLoop","detail":"{readStateIndex:2331; appliedIndex:2330; }","duration":"294.061431ms","start":"2026-08-27T19:18:09.168469Z","end":"2026-08-27T19:18:09.46253Z","steps":["trace[701483746] 'read index received' (duration: 92.117727ms)","trace[701483746] 'applied index is now lower than readState.Index' (duration: 201.942284ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:18:09.462697Z","caller":"traceutil/trace.go:171","msg":"trace[54060398] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"306.751065ms","start":"2026-08-27T19:18:09.155937Z","end":"2026-08-27T19:18:09.462688Z","steps":["trace[54060398] 'process raft request' (duration: 104.558965ms)","trace[54060398] 'compare' (duration: 201.650688ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:18:09.462744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:18:09.155894Z","time spent":"306.825116ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:18:09.462976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.47834ms","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":"info","ts":"2026-08-27T19:18:09.463054Z","caller":"traceutil/trace.go:171","msg":"trace[1176255627] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2250; }","duration":"294.619753ms","start":"2026-08-27T19:18:09.168419Z","end":"2026-08-27T19:18:09.463038Z","steps":["trace[1176255627] 'agreement among raft nodes before linearized reading' (duration: 294.45075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:18:09.463095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.584495ms","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-08-27T19:18:09.46312Z","caller":"traceutil/trace.go:171","msg":"trace[1960005863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2250; }","duration":"283.635996ms","start":"2026-08-27T19:18:09.179478Z","end":"2026-08-27T19:18:09.463114Z","steps":["trace[1960005863] 'agreement among raft nodes before linearized reading' (duration: 283.532044ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:18:09.597341Z","caller":"traceutil/trace.go:171","msg":"trace[726553518] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"129.870921ms","start":"2026-08-27T19:18:09.467432Z","end":"2026-08-27T19:18:09.597303Z","steps":["trace[726553518] 'process raft request' (duration: 123.503534ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:19:00.650696Z","caller":"traceutil/trace.go:171","msg":"trace[2020562332] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"145.095609ms","start":"2026-08-27T19:19:00.505566Z","end":"2026-08-27T19:19:00.650662Z","steps":["trace[2020562332] 'process raft request' (duration: 144.756312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:19:30.600352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.764754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-27T19:19:30.600501Z","caller":"traceutil/trace.go:171","msg":"trace[321132271] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2882; }","duration":"132.970919ms","start":"2026-08-27T19:19:30.467502Z","end":"2026-08-27T19:19:30.600473Z","steps":["trace[321132271] 'range keys from in-memory index tree' (duration: 132.509448ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:19:36.07983Z","caller":"traceutil/trace.go:171","msg":"trace[1392536868] transaction","detail":"{read_only:false; response_revision:2912; number_of_response:1; }","duration":"133.225773ms","start":"2026-08-27T19:19:35.946576Z","end":"2026-08-27T19:19:36.079802Z","steps":["trace[1392536868] 'process raft request' (duration: 132.63295ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:19:36.271644Z","caller":"traceutil/trace.go:171","msg":"trace[1876638267] linearizableReadLoop","detail":"{readStateIndex:3021; appliedIndex:3019; }","duration":"175.321671ms","start":"2026-08-27T19:19:36.09629Z","end":"2026-08-27T19:19:36.271612Z","steps":["trace[1876638267] 'read index received' (duration: 126.053638ms)","trace[1876638267] 'applied index is now lower than readState.Index' (duration: 49.265873ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:19:36.271812Z","caller":"traceutil/trace.go:171","msg":"trace[380414058] transaction","detail":"{read_only:false; response_revision:2913; number_of_response:1; }","duration":"186.545243ms","start":"2026-08-27T19:19:36.085205Z","end":"2026-08-27T19:19:36.27175Z","steps":["trace[380414058] 'process raft request' (duration: 137.041515ms)","trace[380414058] 'compare' (duration: 49.096379ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:19:36.271811Z","caller":"traceutil/trace.go:171","msg":"trace[542878129] transaction","detail":"{read_only:false; response_revision:2914; number_of_response:1; }","duration":"185.345108ms","start":"2026-08-27T19:19:36.086439Z","end":"2026-08-27T19:19:36.271784Z","steps":["trace[542878129] 'process raft request' (duration: 185.111723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:19:36.272036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.711459ms","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-08-27T19:19:36.27214Z","caller":"traceutil/trace.go:171","msg":"trace[827154923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2914; }","duration":"175.853372ms","start":"2026-08-27T19:19:36.096272Z","end":"2026-08-27T19:19:36.272125Z","steps":["trace[827154923] 'agreement among raft nodes before linearized reading' (duration: 175.463694ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:32.669305Z","caller":"traceutil/trace.go:171","msg":"trace[1814437602] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"125.65326ms","start":"2026-08-27T19:24:32.543611Z","end":"2026-08-27T19:24:32.669265Z","steps":["trace[1814437602] 'process raft request' (duration: 101.939429ms)","trace[1814437602] 'compare' (duration: 23.396014ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:24:32.669357Z","caller":"traceutil/trace.go:171","msg":"trace[1355488001] transaction","detail":"{read_only:false; response_revision:5234; number_of_response:1; }","duration":"125.099368ms","start":"2026-08-27T19:24:32.544218Z","end":"2026-08-27T19:24:32.669317Z","steps":["trace[1355488001] 'process raft request' (duration: 124.970165ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:32.669404Z","caller":"traceutil/trace.go:171","msg":"trace[2080828566] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"124.7615ms","start":"2026-08-27T19:24:32.544607Z","end":"2026-08-27T19:24:32.669369Z","steps":["trace[2080828566] 'process raft request' (duration: 124.632537ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:32.669515Z","caller":"traceutil/trace.go:171","msg":"trace[595152750] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5429; }","duration":"120.716534ms","start":"2026-08-27T19:24:32.54876Z","end":"2026-08-27T19:24:32.669476Z","steps":["trace[595152750] 'read index received' (duration: 96.89698ms)","trace[595152750] 'applied index is now lower than readState.Index' (duration: 23.817734ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:24:32.669581Z","caller":"traceutil/trace.go:171","msg":"trace[71664071] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"123.587385ms","start":"2026-08-27T19:24:32.545973Z","end":"2026-08-27T19:24:32.669561Z","steps":["trace[71664071] 'process raft request' (duration: 123.377521ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:32.669592Z","caller":"traceutil/trace.go:171","msg":"trace[627443652] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"123.225388ms","start":"2026-08-27T19:24:32.546355Z","end":"2026-08-27T19:24:32.669581Z","steps":["trace[627443652] 'process raft request' (duration: 123.035744ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:32.669509Z","caller":"traceutil/trace.go:171","msg":"trace[1488549135] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"124.468744ms","start":"2026-08-27T19:24:32.544997Z","end":"2026-08-27T19:24:32.669466Z","steps":["trace[1488549135] 'process raft request' (duration: 124.312121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:32.670007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.386064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62904"} {"level":"info","ts":"2026-08-27T19:24:32.670062Z","caller":"traceutil/trace.go:171","msg":"trace[1431950476] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5238; }","duration":"125.516737ms","start":"2026-08-27T19:24:32.544532Z","end":"2026-08-27T19:24:32.670049Z","steps":["trace[1431950476] 'agreement among raft nodes before linearized reading' (duration: 125.126509ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:32.673343Z","caller":"traceutil/trace.go:171","msg":"trace[954932036] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"122.8597ms","start":"2026-08-27T19:24:32.550446Z","end":"2026-08-27T19:24:32.673306Z","steps":["trace[954932036] 'process raft request' (duration: 122.636595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:32.673433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.788458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a93d3ec5-420d-49ae-9e86-46aa6bead743\" ","response":"range_response_count:1 size:4067"} {"level":"info","ts":"2026-08-27T19:24:32.673465Z","caller":"traceutil/trace.go:171","msg":"trace[57782301] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a93d3ec5-420d-49ae-9e86-46aa6bead743; range_end:; response_count:1; response_revision:5239; }","duration":"115.836498ms","start":"2026-08-27T19:24:32.557615Z","end":"2026-08-27T19:24:32.673452Z","steps":["trace[57782301] 'agreement among raft nodes before linearized reading' (duration: 115.772567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:32.673366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.470524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-27T19:24:32.673723Z","caller":"traceutil/trace.go:171","msg":"trace[634092744] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5239; }","duration":"117.817531ms","start":"2026-08-27T19:24:32.555848Z","end":"2026-08-27T19:24:32.673666Z","steps":["trace[634092744] 'agreement among raft nodes before linearized reading' (duration: 117.352301ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:37.006697Z","caller":"traceutil/trace.go:171","msg":"trace[248594299] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"184.952899ms","start":"2026-08-27T19:24:36.821715Z","end":"2026-08-27T19:24:37.006668Z","steps":["trace[248594299] 'process raft request' (duration: 184.747824ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:37.017047Z","caller":"traceutil/trace.go:171","msg":"trace[526778229] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"122.563884ms","start":"2026-08-27T19:24:36.89446Z","end":"2026-08-27T19:24:37.017024Z","steps":["trace[526778229] 'process raft request' (duration: 122.514983ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:37.017221Z","caller":"traceutil/trace.go:171","msg":"trace[1134776134] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"177.862987ms","start":"2026-08-27T19:24:36.839323Z","end":"2026-08-27T19:24:37.017186Z","steps":["trace[1134776134] 'process raft request' (duration: 177.524839ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:38.763409Z","caller":"traceutil/trace.go:171","msg":"trace[1384306485] linearizableReadLoop","detail":"{readStateIndex:5647; appliedIndex:5646; }","duration":"146.427538ms","start":"2026-08-27T19:24:38.616945Z","end":"2026-08-27T19:24:38.763373Z","steps":["trace[1384306485] 'read index received' (duration: 146.199983ms)","trace[1384306485] 'applied index is now lower than readState.Index' (duration: 226.315µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:24:38.763579Z","caller":"traceutil/trace.go:171","msg":"trace[1528715097] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"195.472195ms","start":"2026-08-27T19:24:38.568053Z","end":"2026-08-27T19:24:38.763525Z","steps":["trace[1528715097] 'process raft request' (duration: 195.085407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:38.763773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.799156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-27T19:24:38.763891Z","caller":"traceutil/trace.go:171","msg":"trace[2077547759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5443; }","duration":"146.99416ms","start":"2026-08-27T19:24:38.61687Z","end":"2026-08-27T19:24:38.763865Z","steps":["trace[2077547759] 'agreement among raft nodes before linearized reading' (duration: 146.665503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:38.764021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.877065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be5f025e-db46-4693-a982-48fc9b763251\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-08-27T19:24:38.764077Z","caller":"traceutil/trace.go:171","msg":"trace[16213442] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be5f025e-db46-4693-a982-48fc9b763251; range_end:; response_count:1; response_revision:5443; }","duration":"107.09197ms","start":"2026-08-27T19:24:38.656971Z","end":"2026-08-27T19:24:38.764063Z","steps":["trace[16213442] 'agreement among raft nodes before linearized reading' (duration: 106.60935ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:46.205124Z","caller":"traceutil/trace.go:171","msg":"trace[1624276440] linearizableReadLoop","detail":"{readStateIndex:5805; appliedIndex:5804; }","duration":"114.110202ms","start":"2026-08-27T19:24:46.090986Z","end":"2026-08-27T19:24:46.205096Z","steps":["trace[1624276440] 'read index received' (duration: 86.624209ms)","trace[1624276440] 'applied index is now lower than readState.Index' (duration: 27.484933ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:24:46.205601Z","caller":"traceutil/trace.go:171","msg":"trace[901732817] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"116.617825ms","start":"2026-08-27T19:24:46.088955Z","end":"2026-08-27T19:24:46.205572Z","steps":["trace[901732817] 'process raft request' (duration: 88.640382ms)","trace[901732817] 'compare' (duration: 27.008112ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:24:46.206328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.309157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:109866"} {"level":"info","ts":"2026-08-27T19:24:46.20637Z","caller":"traceutil/trace.go:171","msg":"trace[1730777257] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5596; }","duration":"115.386389ms","start":"2026-08-27T19:24:46.09097Z","end":"2026-08-27T19:24:46.206356Z","steps":["trace[1730777257] 'agreement among raft nodes before linearized reading' (duration: 114.98645ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:46.224137Z","caller":"traceutil/trace.go:171","msg":"trace[281083737] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"132.323884ms","start":"2026-08-27T19:24:46.091785Z","end":"2026-08-27T19:24:46.224109Z","steps":["trace[281083737] 'process raft request' (duration: 131.726291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:46.224761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.486592ms","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-08-27T19:24:46.224812Z","caller":"traceutil/trace.go:171","msg":"trace[137510065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5598; }","duration":"116.592795ms","start":"2026-08-27T19:24:46.108206Z","end":"2026-08-27T19:24:46.224798Z","steps":["trace[137510065] 'agreement among raft nodes before linearized reading' (duration: 116.415191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:46.224985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.508244ms","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-08-27T19:24:46.225019Z","caller":"traceutil/trace.go:171","msg":"trace[1017185742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5598; }","duration":"110.591935ms","start":"2026-08-27T19:24:46.114417Z","end":"2026-08-27T19:24:46.225009Z","steps":["trace[1017185742] 'agreement among raft nodes before linearized reading' (duration: 110.509434ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:52.354853Z","caller":"traceutil/trace.go:171","msg":"trace[1042619731] linearizableReadLoop","detail":"{readStateIndex:5901; appliedIndex:5900; }","duration":"106.087968ms","start":"2026-08-27T19:24:52.248728Z","end":"2026-08-27T19:24:52.354816Z","steps":["trace[1042619731] 'read index received' (duration: 105.845923ms)","trace[1042619731] 'applied index is now lower than readState.Index' (duration: 241.035µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:24:52.354903Z","caller":"traceutil/trace.go:171","msg":"trace[442424114] transaction","detail":"{read_only:false; response_revision:5689; number_of_response:1; }","duration":"110.37207ms","start":"2026-08-27T19:24:52.244493Z","end":"2026-08-27T19:24:52.354865Z","steps":["trace[442424114] 'process raft request' (duration: 110.133375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:24:52.355239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.472796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-27T19:24:52.355361Z","caller":"traceutil/trace.go:171","msg":"trace[1771519382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5689; }","duration":"106.66339ms","start":"2026-08-27T19:24:52.248673Z","end":"2026-08-27T19:24:52.355336Z","steps":["trace[1771519382] 'agreement among raft nodes before linearized reading' (duration: 106.298993ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:24:52.944197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-08-27T19:24:53.078173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"132.422197ms","hash":4197999348,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":29335552,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-27T19:24:53.078266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4197999348,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-08-27T19:25:00.660321Z","caller":"traceutil/trace.go:171","msg":"trace[1057463431] linearizableReadLoop","detail":"{readStateIndex:6069; appliedIndex:6068; }","duration":"125.825724ms","start":"2026-08-27T19:25:00.53445Z","end":"2026-08-27T19:25:00.660276Z","steps":["trace[1057463431] 'read index received' (duration: 103.876771ms)","trace[1057463431] 'applied index is now lower than readState.Index' (duration: 21.946623ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:25:00.660373Z","caller":"traceutil/trace.go:171","msg":"trace[816982100] transaction","detail":"{read_only:false; response_revision:5855; number_of_response:1; }","duration":"126.170671ms","start":"2026-08-27T19:25:00.534152Z","end":"2026-08-27T19:25:00.660322Z","steps":["trace[816982100] 'process raft request' (duration: 104.270789ms)","trace[816982100] 'compare' (duration: 21.556165ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:25:00.660548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.083829ms","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-08-27T19:25:00.660649Z","caller":"traceutil/trace.go:171","msg":"trace[1743923212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5855; }","duration":"126.391666ms","start":"2026-08-27T19:25:00.534236Z","end":"2026-08-27T19:25:00.660628Z","steps":["trace[1743923212] 'agreement among raft nodes before linearized reading' (duration: 126.15655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:25:00.914978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.203348ms","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-08-27T19:25:00.915095Z","caller":"traceutil/trace.go:171","msg":"trace[8740721] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5856; }","duration":"143.407414ms","start":"2026-08-27T19:25:00.771661Z","end":"2026-08-27T19:25:00.915068Z","steps":["trace[8740721] 'range keys from in-memory index tree' (duration: 143.111997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:25:00.915334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.308161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ddbd4eee-b2a0-4419-8618-d08ee9313ecb\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-27T19:25:00.915437Z","caller":"traceutil/trace.go:171","msg":"trace[1349677421] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ddbd4eee-b2a0-4419-8618-d08ee9313ecb; range_end:; response_count:1; response_revision:5856; }","duration":"118.482546ms","start":"2026-08-27T19:25:00.796929Z","end":"2026-08-27T19:25:00.915412Z","steps":["trace[1349677421] 'range keys from in-memory index tree' (duration: 118.089397ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:25:01.282847Z","caller":"traceutil/trace.go:171","msg":"trace[1763643798] linearizableReadLoop","detail":"{readStateIndex:6074; appliedIndex:6071; }","duration":"271.976385ms","start":"2026-08-27T19:25:01.01085Z","end":"2026-08-27T19:25:01.282826Z","steps":["trace[1763643798] 'read index received' (duration: 271.571166ms)","trace[1763643798] 'applied index is now lower than readState.Index' (duration: 404.099µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:25:01.283034Z","caller":"traceutil/trace.go:171","msg":"trace[1043414840] transaction","detail":"{read_only:false; response_revision:5859; number_of_response:1; }","duration":"361.792212ms","start":"2026-08-27T19:25:00.921213Z","end":"2026-08-27T19:25:01.283005Z","steps":["trace[1043414840] 'process raft request' (duration: 361.500896ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:25:01.283096Z","caller":"traceutil/trace.go:171","msg":"trace[1841582187] transaction","detail":"{read_only:false; response_revision:5858; number_of_response:1; }","duration":"362.282052ms","start":"2026-08-27T19:25:00.920501Z","end":"2026-08-27T19:25:01.282784Z","steps":["trace[1841582187] 'process raft request' (duration: 362.019276ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:25:01.283025Z","caller":"traceutil/trace.go:171","msg":"trace[1154758562] transaction","detail":"{read_only:false; response_revision:5860; number_of_response:1; }","duration":"358.268097ms","start":"2026-08-27T19:25:00.92467Z","end":"2026-08-27T19:25:01.282939Z","steps":["trace[1154758562] 'process raft request' (duration: 358.091333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:25:01.283233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:25:00.920476Z","time spent":"362.656451ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:25:01.283266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:25:00.921193Z","time spent":"361.938425ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:25:01.28329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:25:00.924649Z","time spent":"358.563522ms","remote":"127.0.0.1:33458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:25:01.283302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.14893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80049"} {"level":"info","ts":"2026-08-27T19:25:01.283438Z","caller":"traceutil/trace.go:171","msg":"trace[981862921] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5860; }","duration":"362.265312ms","start":"2026-08-27T19:25:00.921118Z","end":"2026-08-27T19:25:01.283384Z","steps":["trace[981862921] 'agreement among raft nodes before linearized reading' (duration: 361.832013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:25:01.283481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:25:00.921055Z","time spent":"362.417225ms","remote":"127.0.0.1:33458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80072,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-27T19:25:01.284021Z","caller":"traceutil/trace.go:171","msg":"trace[658951896] transaction","detail":"{read_only:false; response_revision:5861; number_of_response:1; }","duration":"154.796769ms","start":"2026-08-27T19:25:01.129211Z","end":"2026-08-27T19:25:01.284008Z","steps":["trace[658951896] 'process raft request' (duration: 154.619215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:25:01.284375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.866719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-27T19:25:01.284432Z","caller":"traceutil/trace.go:171","msg":"trace[156437922] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5862; }","duration":"269.944872ms","start":"2026-08-27T19:25:01.014476Z","end":"2026-08-27T19:25:01.284421Z","steps":["trace[156437922] 'agreement among raft nodes before linearized reading' (duration: 269.665025ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:25:01.284384Z","caller":"traceutil/trace.go:171","msg":"trace[343961171] transaction","detail":"{read_only:false; response_revision:5862; number_of_response:1; }","duration":"130.452304ms","start":"2026-08-27T19:25:01.153886Z","end":"2026-08-27T19:25:01.284339Z","steps":["trace[343961171] 'process raft request' (duration: 130.087266ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:25:15.160881Z","caller":"traceutil/trace.go:171","msg":"trace[58183579] transaction","detail":"{read_only:false; response_revision:6023; number_of_response:1; }","duration":"118.253521ms","start":"2026-08-27T19:25:15.04259Z","end":"2026-08-27T19:25:15.160844Z","steps":["trace[58183579] 'process raft request' (duration: 117.989695ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:25:15.1802Z","caller":"traceutil/trace.go:171","msg":"trace[1224112397] transaction","detail":"{read_only:false; response_revision:6026; number_of_response:1; }","duration":"133.947859ms","start":"2026-08-27T19:25:15.04619Z","end":"2026-08-27T19:25:15.180138Z","steps":["trace[1224112397] 'process raft request' (duration: 133.885418ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:25:15.180393Z","caller":"traceutil/trace.go:171","msg":"trace[1358343419] transaction","detail":"{read_only:false; response_revision:6025; number_of_response:1; }","duration":"136.974835ms","start":"2026-08-27T19:25:15.043296Z","end":"2026-08-27T19:25:15.180271Z","steps":["trace[1358343419] 'process raft request' (duration: 136.710039ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:25:15.180453Z","caller":"traceutil/trace.go:171","msg":"trace[2143152119] transaction","detail":"{read_only:false; response_revision:6024; number_of_response:1; }","duration":"136.918323ms","start":"2026-08-27T19:25:15.043184Z","end":"2026-08-27T19:25:15.180102Z","steps":["trace[2143152119] 'process raft request' (duration: 136.511364ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:29:52.954747Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5709} {"level":"info","ts":"2026-08-27T19:29:53.054679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5709,"took":"97.247657ms","hash":1649009661,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":27848704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-27T19:29:53.054762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1649009661,"revision":5709,"compact-revision":3009} {"level":"info","ts":"2026-08-27T19:31:28.481648Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002394.wal"} {"level":"warn","ts":"2026-08-27T19:31:31.478259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.092986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-27T19:31:31.478383Z","caller":"traceutil/trace.go:171","msg":"trace[1540484421] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"534.825347ms","start":"2026-08-27T19:31:30.943534Z","end":"2026-08-27T19:31:31.478359Z","steps":["trace[1540484421] 'process raft request' (duration: 335.472005ms)","trace[1540484421] 'compare' (duration: 198.82718ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:31:31.478634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:31:30.943496Z","time spent":"534.920979ms","remote":"127.0.0.1:33516","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-08-27T19:31:31.496023Z","caller":"traceutil/trace.go:171","msg":"trace[1056877435] linearizableReadLoop","detail":"{readStateIndex:9124; appliedIndex:9121; }","duration":"350.63659ms","start":"2026-08-27T19:31:31.145361Z","end":"2026-08-27T19:31:31.495998Z","steps":["trace[1056877435] 'read index received' (duration: 133.727261ms)","trace[1056877435] 'applied index is now lower than readState.Index' (duration: 216.907979ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:31:31.496064Z","caller":"traceutil/trace.go:171","msg":"trace[1543280286] transaction","detail":"{read_only:false; response_revision:8810; number_of_response:1; }","duration":"552.451066ms","start":"2026-08-27T19:31:30.943585Z","end":"2026-08-27T19:31:31.496036Z","steps":["trace[1543280286] 'process raft request' (duration: 552.219611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:31:31.49619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:31:30.943547Z","time spent":"552.567679ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:31:31.496359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.799125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:31:31.496409Z","caller":"traceutil/trace.go:171","msg":"trace[509912527] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:8811; }","duration":"215.876876ms","start":"2026-08-27T19:31:31.280517Z","end":"2026-08-27T19:31:31.496394Z","steps":["trace[509912527] 'agreement among raft nodes before linearized reading' (duration: 215.667502ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:31.496402Z","caller":"traceutil/trace.go:171","msg":"trace[1549992636] transaction","detail":"{read_only:false; response_revision:8811; number_of_response:1; }","duration":"538.489528ms","start":"2026-08-27T19:31:30.957869Z","end":"2026-08-27T19:31:31.496359Z","steps":["trace[1549992636] 'process raft request' (duration: 538.077329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:31:31.496543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.122462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-27T19:31:31.4967Z","caller":"traceutil/trace.go:171","msg":"trace[1575502400] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8811; }","duration":"351.370627ms","start":"2026-08-27T19:31:31.145296Z","end":"2026-08-27T19:31:31.496666Z","steps":["trace[1575502400] 'agreement among raft nodes before linearized reading' (duration: 350.929178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:31:31.496604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:31:30.957848Z","time spent":"538.640851ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:31:31.496782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:31:31.145269Z","time spent":"351.498249ms","remote":"127.0.0.1:46192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":55,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-08-27T19:31:44.275455Z","caller":"traceutil/trace.go:171","msg":"trace[884034812] transaction","detail":"{read_only:false; response_revision:8875; number_of_response:1; }","duration":"148.304404ms","start":"2026-08-27T19:31:44.127118Z","end":"2026-08-27T19:31:44.275422Z","steps":["trace[884034812] 'process raft request' (duration: 148.075259ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:31:45.200797Z","caller":"traceutil/trace.go:171","msg":"trace[1326724331] transaction","detail":"{read_only:false; response_revision:8879; number_of_response:1; }","duration":"165.510815ms","start":"2026-08-27T19:31:45.035237Z","end":"2026-08-27T19:31:45.200748Z","steps":["trace[1326724331] 'process raft request' (duration: 154.837589ms)","trace[1326724331] 'compare' (duration: 10.505432ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:32:14.144291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.005331ms","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-08-27T19:32:14.144431Z","caller":"traceutil/trace.go:171","msg":"trace[1113101093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9089; }","duration":"323.219645ms","start":"2026-08-27T19:32:13.821183Z","end":"2026-08-27T19:32:14.144403Z","steps":["trace[1113101093] 'range keys from in-memory index tree' (duration: 322.810416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:32:14.144476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:32:13.82116Z","time spent":"323.303967ms","remote":"127.0.0.1:33516","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-08-27T19:32:14.144886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.986625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-27T19:32:14.145004Z","caller":"traceutil/trace.go:171","msg":"trace[1056636292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9089; }","duration":"311.163409ms","start":"2026-08-27T19:32:13.833819Z","end":"2026-08-27T19:32:14.144982Z","steps":["trace[1056636292] 'range keys from in-memory index tree' (duration: 310.75017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:32:14.145046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:32:13.833796Z","time spent":"311.240231ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-27T19:32:14.3084Z","caller":"traceutil/trace.go:171","msg":"trace[1507166323] transaction","detail":"{read_only:false; response_revision:9090; number_of_response:1; }","duration":"156.942605ms","start":"2026-08-27T19:32:14.151429Z","end":"2026-08-27T19:32:14.308371Z","steps":["trace[1507166323] 'process raft request' (duration: 156.761041ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:32:14.374737Z","caller":"traceutil/trace.go:171","msg":"trace[979179337] transaction","detail":"{read_only:false; response_revision:9091; number_of_response:1; }","duration":"222.247797ms","start":"2026-08-27T19:32:14.15247Z","end":"2026-08-27T19:32:14.374717Z","steps":["trace[979179337] 'process raft request' (duration: 205.280853ms)","trace[979179337] 'compare' (duration: 16.892063ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:32:16.575168Z","caller":"traceutil/trace.go:171","msg":"trace[666462177] transaction","detail":"{read_only:false; response_revision:9103; number_of_response:1; }","duration":"167.1378ms","start":"2026-08-27T19:32:16.407981Z","end":"2026-08-27T19:32:16.575119Z","steps":["trace[666462177] 'process raft request' (duration: 167.069289ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:32:16.575431Z","caller":"traceutil/trace.go:171","msg":"trace[1891733482] transaction","detail":"{read_only:false; response_revision:9102; number_of_response:1; }","duration":"175.493634ms","start":"2026-08-27T19:32:16.399898Z","end":"2026-08-27T19:32:16.575392Z","steps":["trace[1891733482] 'process raft request' (duration: 86.53726ms)","trace[1891733482] 'compare' (duration: 88.469853ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:32:16.575478Z","caller":"traceutil/trace.go:171","msg":"trace[132410277] linearizableReadLoop","detail":"{readStateIndex:9427; appliedIndex:9426; }","duration":"173.545512ms","start":"2026-08-27T19:32:16.401915Z","end":"2026-08-27T19:32:16.57546Z","steps":["trace[132410277] 'read index received' (duration: 84.499026ms)","trace[132410277] 'applied index is now lower than readState.Index' (duration: 89.045256ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:32:16.575679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.753166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.193\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-27T19:32:16.575724Z","caller":"traceutil/trace.go:171","msg":"trace[1250779796] range","detail":"{range_begin:/registry/masterleases/162.253.55.193; range_end:; response_count:1; response_revision:9103; }","duration":"173.865558ms","start":"2026-08-27T19:32:16.401846Z","end":"2026-08-27T19:32:16.575711Z","steps":["trace[1250779796] 'agreement among raft nodes before linearized reading' (duration: 173.710745ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:32:24.776632Z","caller":"traceutil/trace.go:171","msg":"trace[507642731] linearizableReadLoop","detail":"{readStateIndex:9473; appliedIndex:9472; }","duration":"158.825197ms","start":"2026-08-27T19:32:24.617772Z","end":"2026-08-27T19:32:24.776597Z","steps":["trace[507642731] 'read index received' (duration: 133.796464ms)","trace[507642731] 'applied index is now lower than readState.Index' (duration: 25.027603ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:32:24.77675Z","caller":"traceutil/trace.go:171","msg":"trace[211993394] transaction","detail":"{read_only:false; response_revision:9146; number_of_response:1; }","duration":"172.13257ms","start":"2026-08-27T19:32:24.604566Z","end":"2026-08-27T19:32:24.776698Z","steps":["trace[211993394] 'process raft request' (duration: 146.953574ms)","trace[211993394] 'compare' (duration: 24.933061ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:32:24.776886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.104503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-27T19:32:24.776927Z","caller":"traceutil/trace.go:171","msg":"trace[1833208450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9146; }","duration":"159.226646ms","start":"2026-08-27T19:32:24.617688Z","end":"2026-08-27T19:32:24.776915Z","steps":["trace[1833208450] 'agreement among raft nodes before linearized reading' (duration: 159.043982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:32:27.25041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.024341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-27T19:32:27.250535Z","caller":"traceutil/trace.go:171","msg":"trace[58913600] linearizableReadLoop","detail":"{readStateIndex:9497; appliedIndex:9496; }","duration":"144.412269ms","start":"2026-08-27T19:32:27.106104Z","end":"2026-08-27T19:32:27.250516Z","steps":["trace[58913600] 'read index received' (duration: 14.159803ms)","trace[58913600] 'applied index is now lower than readState.Index' (duration: 130.251156ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:32:27.250593Z","caller":"traceutil/trace.go:171","msg":"trace[863855105] transaction","detail":"{read_only:false; response_revision:9169; number_of_response:1; }","duration":"147.079728ms","start":"2026-08-27T19:32:27.103467Z","end":"2026-08-27T19:32:27.250547Z","steps":["trace[863855105] 'process raft request' (duration: 16.827972ms)","trace[863855105] 'compare' (duration: 129.878297ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:32:27.250746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.250116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-9gqrp\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-08-27T19:32:27.250773Z","caller":"traceutil/trace.go:171","msg":"trace[288289834] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-9gqrp; range_end:; response_count:1; response_revision:9169; }","duration":"149.306126ms","start":"2026-08-27T19:32:27.101458Z","end":"2026-08-27T19:32:27.250764Z","steps":["trace[288289834] 'agreement among raft nodes before linearized reading' (duration: 149.150113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:32:27.250864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.041336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9gqrp\" ","response":"range_response_count:1 size:4673"} {"level":"info","ts":"2026-08-27T19:32:27.250958Z","caller":"traceutil/trace.go:171","msg":"trace[2113722803] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-9gqrp; range_end:; response_count:1; response_revision:9169; }","duration":"142.162649ms","start":"2026-08-27T19:32:27.108771Z","end":"2026-08-27T19:32:27.250934Z","steps":["trace[2113722803] 'agreement among raft nodes before linearized reading' (duration: 141.902993ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:33:21.549002Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-27T19:33:21.558114Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-27T19:33:21.558295Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-27T19:34:52.962172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8012} {"level":"info","ts":"2026-08-27T19:34:53.042788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8012,"took":"78.273188ms","hash":3565690532,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":28041216,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-27T19:34:53.042883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3565690532,"revision":8012,"compact-revision":5709} {"level":"info","ts":"2026-08-27T19:39:41.503556Z","caller":"traceutil/trace.go:171","msg":"trace[796449070] transaction","detail":"{read_only:false; response_revision:12480; number_of_response:1; }","duration":"114.258463ms","start":"2026-08-27T19:39:41.389265Z","end":"2026-08-27T19:39:41.503524Z","steps":["trace[796449070] 'process raft request' (duration: 114.012418ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:39:41.719734Z","caller":"traceutil/trace.go:171","msg":"trace[1198078357] transaction","detail":"{read_only:false; response_revision:12482; number_of_response:1; }","duration":"128.405264ms","start":"2026-08-27T19:39:41.591301Z","end":"2026-08-27T19:39:41.719706Z","steps":["trace[1198078357] 'process raft request' (duration: 128.22516ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:39:41.726597Z","caller":"traceutil/trace.go:171","msg":"trace[1170356212] transaction","detail":"{read_only:false; response_revision:12483; number_of_response:1; }","duration":"133.180189ms","start":"2026-08-27T19:39:41.593386Z","end":"2026-08-27T19:39:41.726566Z","steps":["trace[1170356212] 'process raft request' (duration: 133.002555ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:39:52.974794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10421} {"level":"info","ts":"2026-08-27T19:39:53.078065Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10421,"took":"100.06797ms","hash":2012434053,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":25628672,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-27T19:39:53.078146Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2012434053,"revision":10421,"compact-revision":8012} {"level":"info","ts":"2026-08-27T19:44:52.982951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12567} {"level":"info","ts":"2026-08-27T19:44:53.070546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12567,"took":"84.617824ms","hash":2458575544,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":27541504,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-27T19:44:53.070634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2458575544,"revision":12567,"compact-revision":10421} {"level":"info","ts":"2026-08-27T19:46:13.691729Z","caller":"traceutil/trace.go:171","msg":"trace[1522432689] linearizableReadLoop","detail":"{readStateIndex:15617; appliedIndex:15616; }","duration":"288.282747ms","start":"2026-08-27T19:46:13.403405Z","end":"2026-08-27T19:46:13.691688Z","steps":["trace[1522432689] 'read index received' (duration: 222.652759ms)","trace[1522432689] 'applied index is now lower than readState.Index' (duration: 65.628538ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:46:13.691808Z","caller":"traceutil/trace.go:171","msg":"trace[2091064899] transaction","detail":"{read_only:false; response_revision:15090; number_of_response:1; }","duration":"415.865194ms","start":"2026-08-27T19:46:13.275884Z","end":"2026-08-27T19:46:13.69175Z","steps":["trace[2091064899] 'process raft request' (duration: 350.031591ms)","trace[2091064899] 'compare' (duration: 65.627588ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:46:13.691997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:46:13.275853Z","time spent":"416.042928ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T19:46:13.692018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.596995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g2tl7\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-08-27T19:46:13.692054Z","caller":"traceutil/trace.go:171","msg":"trace[78912071] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g2tl7; range_end:; response_count:1; response_revision:15090; }","duration":"288.704117ms","start":"2026-08-27T19:46:13.403338Z","end":"2026-08-27T19:46:13.692042Z","steps":["trace[78912071] 'agreement among raft nodes before linearized reading' (duration: 288.522013ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:13.692104Z","caller":"traceutil/trace.go:171","msg":"trace[2059681044] transaction","detail":"{read_only:false; response_revision:15091; number_of_response:1; }","duration":"258.71111ms","start":"2026-08-27T19:46:13.433353Z","end":"2026-08-27T19:46:13.692064Z","steps":["trace[2059681044] 'process raft request' (duration: 258.512265ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:13.692437Z","caller":"traceutil/trace.go:171","msg":"trace[1581686546] transaction","detail":"{read_only:false; response_revision:15093; number_of_response:1; }","duration":"174.127986ms","start":"2026-08-27T19:46:13.518279Z","end":"2026-08-27T19:46:13.692407Z","steps":["trace[1581686546] 'process raft request' (duration: 173.983663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:46:13.692547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.866356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-27T19:46:13.692601Z","caller":"traceutil/trace.go:171","msg":"trace[560858840] transaction","detail":"{read_only:false; response_revision:15092; number_of_response:1; }","duration":"219.965421ms","start":"2026-08-27T19:46:13.472607Z","end":"2026-08-27T19:46:13.692572Z","steps":["trace[560858840] 'process raft request' (duration: 219.411279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:46:13.692579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.754636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-27T19:46:13.692639Z","caller":"traceutil/trace.go:171","msg":"trace[1442957148] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:15093; }","duration":"271.03838ms","start":"2026-08-27T19:46:13.42158Z","end":"2026-08-27T19:46:13.692618Z","steps":["trace[1442957148] 'agreement among raft nodes before linearized reading' (duration: 270.790844ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:13.692657Z","caller":"traceutil/trace.go:171","msg":"trace[1555907128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15093; }","duration":"125.900889ms","start":"2026-08-27T19:46:13.56674Z","end":"2026-08-27T19:46:13.692641Z","steps":["trace[1555907128] 'agreement among raft nodes before linearized reading' (duration: 125.768626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:46:13.692863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.278826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-08-27T19:46:13.692882Z","caller":"traceutil/trace.go:171","msg":"trace[950292496] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15093; }","duration":"167.336057ms","start":"2026-08-27T19:46:13.52554Z","end":"2026-08-27T19:46:13.692876Z","steps":["trace[950292496] 'agreement among raft nodes before linearized reading' (duration: 167.266586ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:32.037944Z","caller":"traceutil/trace.go:171","msg":"trace[216020162] transaction","detail":"{read_only:false; response_revision:15201; number_of_response:1; }","duration":"267.984052ms","start":"2026-08-27T19:46:31.769931Z","end":"2026-08-27T19:46:32.037915Z","steps":["trace[216020162] 'process raft request' (duration: 267.769498ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:32.330417Z","caller":"traceutil/trace.go:171","msg":"trace[1979609868] linearizableReadLoop","detail":"{readStateIndex:15735; appliedIndex:15733; }","duration":"544.822389ms","start":"2026-08-27T19:46:31.785562Z","end":"2026-08-27T19:46:32.330385Z","steps":["trace[1979609868] 'read index received' (duration: 252.104665ms)","trace[1979609868] 'applied index is now lower than readState.Index' (duration: 292.715954ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:46:32.330505Z","caller":"traceutil/trace.go:171","msg":"trace[1673387828] transaction","detail":"{read_only:false; response_revision:15202; number_of_response:1; }","duration":"556.165838ms","start":"2026-08-27T19:46:31.774303Z","end":"2026-08-27T19:46:32.330469Z","steps":["trace[1673387828] 'process raft request' (duration: 546.366564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:46:32.330746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.195128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-27T19:46:32.330772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:46:31.774267Z","time spent":"556.379202ms","remote":"127.0.0.1:33516","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-08-27T19:46:32.330798Z","caller":"traceutil/trace.go:171","msg":"trace[380784158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15202; }","duration":"545.261259ms","start":"2026-08-27T19:46:31.785522Z","end":"2026-08-27T19:46:32.330784Z","steps":["trace[380784158] 'agreement among raft nodes before linearized reading' (duration: 545.039974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:46:32.330836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:46:31.785473Z","time spent":"545.355221ms","remote":"127.0.0.1:33516","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-08-27T19:46:32.330873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.079273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g2tl7\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-08-27T19:46:32.330997Z","caller":"traceutil/trace.go:171","msg":"trace[1674075867] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g2tl7; range_end:; response_count:1; response_revision:15202; }","duration":"466.268938ms","start":"2026-08-27T19:46:31.864704Z","end":"2026-08-27T19:46:32.330973Z","steps":["trace[1674075867] 'agreement among raft nodes before linearized reading' (duration: 465.980772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:46:32.331037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.597603ms","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-08-27T19:46:32.331125Z","caller":"traceutil/trace.go:171","msg":"trace[291268761] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:15202; }","duration":"282.686435ms","start":"2026-08-27T19:46:32.048424Z","end":"2026-08-27T19:46:32.331111Z","steps":["trace[291268761] 'agreement among raft nodes before linearized reading' (duration: 282.536092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:46:32.331147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.19723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-27T19:46:32.331155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.111646ms","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-08-27T19:46:32.331241Z","caller":"traceutil/trace.go:171","msg":"trace[1001678969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15202; }","duration":"254.346304ms","start":"2026-08-27T19:46:32.076873Z","end":"2026-08-27T19:46:32.331219Z","steps":["trace[1001678969] 'agreement among raft nodes before linearized reading' (duration: 254.080398ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:46:32.33126Z","caller":"traceutil/trace.go:171","msg":"trace[1942206221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15202; }","duration":"253.293341ms","start":"2026-08-27T19:46:32.077939Z","end":"2026-08-27T19:46:32.331232Z","steps":["trace[1942206221] 'agreement among raft nodes before linearized reading' (duration: 253.000024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:46:32.331046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:46:31.864679Z","time spent":"466.35871ms","remote":"127.0.0.1:33458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6474,"request content":"key:\"/registry/pods/openstack/keepalived-g2tl7\" "} {"level":"info","ts":"2026-08-27T19:46:58.395032Z","caller":"traceutil/trace.go:171","msg":"trace[1738677824] transaction","detail":"{read_only:false; response_revision:15530; number_of_response:1; }","duration":"244.823355ms","start":"2026-08-27T19:46:58.150173Z","end":"2026-08-27T19:46:58.394996Z","steps":["trace[1738677824] 'process raft request' (duration: 244.6308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:32.014315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.096188ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-27T19:48:32.014462Z","caller":"traceutil/trace.go:171","msg":"trace[572672472] linearizableReadLoop","detail":"{readStateIndex:16949; appliedIndex:16948; }","duration":"113.81172ms","start":"2026-08-27T19:48:31.900624Z","end":"2026-08-27T19:48:32.014436Z","steps":["trace[572672472] 'read index received' (duration: 80.391µs)","trace[572672472] 'applied index is now lower than readState.Index' (duration: 113.729289ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:48:32.015076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.445384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g425x\" ","response":"range_response_count:1 size:22021"} {"level":"info","ts":"2026-08-27T19:48:32.01511Z","caller":"traceutil/trace.go:171","msg":"trace[1548669466] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g425x; range_end:; response_count:1; response_revision:16380; }","duration":"114.545746ms","start":"2026-08-27T19:48:31.900552Z","end":"2026-08-27T19:48:32.015098Z","steps":["trace[1548669466] 'agreement among raft nodes before linearized reading' (duration: 114.340672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:48:32.045663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.425015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g425x\" ","response":"range_response_count:1 size:22021"} {"level":"info","ts":"2026-08-27T19:48:32.045889Z","caller":"traceutil/trace.go:171","msg":"trace[106657519] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g425x; range_end:; response_count:1; response_revision:16381; }","duration":"115.700951ms","start":"2026-08-27T19:48:31.930126Z","end":"2026-08-27T19:48:32.045827Z","steps":["trace[106657519] 'agreement among raft nodes before linearized reading' (duration: 115.233531ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:49:52.990004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14597} {"level":"info","ts":"2026-08-27T19:49:53.080092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14597,"took":"86.569094ms","hash":133278358,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":31109120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-27T19:49:53.080264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":133278358,"revision":14597,"compact-revision":12567} {"level":"warn","ts":"2026-08-27T19:54:35.848145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.93748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-n7btp\" ","response":"range_response_count:1 size:9710"} {"level":"info","ts":"2026-08-27T19:54:35.848282Z","caller":"traceutil/trace.go:171","msg":"trace[1814534985] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-n7btp; range_end:; response_count:1; response_revision:19043; }","duration":"178.118254ms","start":"2026-08-27T19:54:35.670141Z","end":"2026-08-27T19:54:35.848259Z","steps":["trace[1814534985] 'range keys from in-memory index tree' (duration: 176.640341ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:54:42.663581Z","caller":"traceutil/trace.go:171","msg":"trace[1126320676] linearizableReadLoop","detail":"{readStateIndex:19739; appliedIndex:19738; }","duration":"192.715113ms","start":"2026-08-27T19:54:42.470842Z","end":"2026-08-27T19:54:42.663557Z","steps":["trace[1126320676] 'read index received' (duration: 192.633342ms)","trace[1126320676] 'applied index is now lower than readState.Index' (duration: 80.021µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T19:54:42.663622Z","caller":"traceutil/trace.go:171","msg":"trace[2133339908] transaction","detail":"{read_only:false; response_revision:19079; number_of_response:1; }","duration":"288.112828ms","start":"2026-08-27T19:54:42.375482Z","end":"2026-08-27T19:54:42.663595Z","steps":["trace[2133339908] 'process raft request' (duration: 287.922104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:54:42.663722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.861766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T19:54:42.663758Z","caller":"traceutil/trace.go:171","msg":"trace[1882868033] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:19079; }","duration":"192.947589ms","start":"2026-08-27T19:54:42.470799Z","end":"2026-08-27T19:54:42.663746Z","steps":["trace[1882868033] 'agreement among raft nodes before linearized reading' (duration: 192.867247ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T19:54:42.93398Z","caller":"traceutil/trace.go:171","msg":"trace[160314098] linearizableReadLoop","detail":"{readStateIndex:19741; appliedIndex:19739; }","duration":"270.290189ms","start":"2026-08-27T19:54:42.663644Z","end":"2026-08-27T19:54:42.933934Z","steps":["trace[160314098] 'read index received' (duration: 219.11745ms)","trace[160314098] 'applied index is now lower than readState.Index' (duration: 51.171889ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:54:42.934102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.013241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T19:54:42.934135Z","caller":"traceutil/trace.go:171","msg":"trace[846438400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19081; }","duration":"366.096033ms","start":"2026-08-27T19:54:42.56803Z","end":"2026-08-27T19:54:42.934126Z","steps":["trace[846438400] 'agreement among raft nodes before linearized reading' (duration: 366.010552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:54:42.934172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:54:42.568012Z","time spent":"366.148964ms","remote":"127.0.0.1:57432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-27T19:54:42.934355Z","caller":"traceutil/trace.go:171","msg":"trace[68835810] transaction","detail":"{read_only:false; response_revision:19080; number_of_response:1; }","duration":"317.617714ms","start":"2026-08-27T19:54:42.616722Z","end":"2026-08-27T19:54:42.93434Z","steps":["trace[68835810] 'process raft request' (duration: 266.172479ms)","trace[68835810] 'compare' (duration: 50.867152ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T19:54:42.934437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:54:42.616698Z","time spent":"317.685445ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T19:54:42.934466Z","caller":"traceutil/trace.go:171","msg":"trace[401711419] transaction","detail":"{read_only:false; response_revision:19081; number_of_response:1; }","duration":"309.257861ms","start":"2026-08-27T19:54:42.625164Z","end":"2026-08-27T19:54:42.934422Z","steps":["trace[401711419] 'process raft request' (duration: 308.724379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T19:54:42.93468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T19:54:42.625137Z","time spent":"309.441515ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T19:54:52.99641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16914} {"level":"info","ts":"2026-08-27T19:54:53.101226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16914,"took":"101.132452ms","hash":3258276083,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":32514048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-27T19:54:53.101298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3258276083,"revision":16914,"compact-revision":14597} {"level":"info","ts":"2026-08-27T19:55:01.005003Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-27T19:55:01.011421Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-27T19:55:01.011535Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-27T19:59:53.007799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19272} {"level":"info","ts":"2026-08-27T19:59:53.126845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19272,"took":"115.094736ms","hash":634197355,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":33759232,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-27T19:59:53.126915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":634197355,"revision":19272,"compact-revision":16914} {"level":"info","ts":"2026-08-27T20:00:18.468685Z","caller":"traceutil/trace.go:171","msg":"trace[147450513] transaction","detail":"{read_only:false; response_revision:21754; number_of_response:1; }","duration":"135.179685ms","start":"2026-08-27T20:00:18.333485Z","end":"2026-08-27T20:00:18.468664Z","steps":["trace[147450513] 'process raft request' (duration: 134.98462ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:18.469002Z","caller":"traceutil/trace.go:171","msg":"trace[1750738813] transaction","detail":"{read_only:false; response_revision:21755; number_of_response:1; }","duration":"135.078913ms","start":"2026-08-27T20:00:18.333887Z","end":"2026-08-27T20:00:18.468965Z","steps":["trace[1750738813] 'process raft request' (duration: 134.94557ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:21.472407Z","caller":"traceutil/trace.go:171","msg":"trace[2034495451] transaction","detail":"{read_only:false; response_revision:21780; number_of_response:1; }","duration":"152.890523ms","start":"2026-08-27T20:00:21.319475Z","end":"2026-08-27T20:00:21.472366Z","steps":["trace[2034495451] 'process raft request' (duration: 152.581506ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:21.486493Z","caller":"traceutil/trace.go:171","msg":"trace[699687753] linearizableReadLoop","detail":"{readStateIndex:22528; appliedIndex:22526; }","duration":"163.372482ms","start":"2026-08-27T20:00:21.323089Z","end":"2026-08-27T20:00:21.486462Z","steps":["trace[699687753] 'read index received' (duration: 148.988977ms)","trace[699687753] 'applied index is now lower than readState.Index' (duration: 14.382215ms)"],"step_count":2} {"level":"warn","ts":"2026-08-27T20:00:21.486654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.527635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-27T20:00:21.486702Z","caller":"traceutil/trace.go:171","msg":"trace[1353030425] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:21781; }","duration":"163.647047ms","start":"2026-08-27T20:00:21.323037Z","end":"2026-08-27T20:00:21.486684Z","steps":["trace[1353030425] 'agreement among raft nodes before linearized reading' (duration: 163.543905ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:21.486667Z","caller":"traceutil/trace.go:171","msg":"trace[8351533] transaction","detail":"{read_only:false; response_revision:21781; number_of_response:1; }","duration":"163.74411ms","start":"2026-08-27T20:00:21.322866Z","end":"2026-08-27T20:00:21.48661Z","steps":["trace[8351533] 'process raft request' (duration: 163.423433ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:44.311481Z","caller":"traceutil/trace.go:171","msg":"trace[1054430203] transaction","detail":"{read_only:false; response_revision:21907; number_of_response:1; }","duration":"130.794269ms","start":"2026-08-27T20:00:44.180654Z","end":"2026-08-27T20:00:44.311448Z","steps":["trace[1054430203] 'process raft request' (duration: 130.494603ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:44.685587Z","caller":"traceutil/trace.go:171","msg":"trace[1948619199] transaction","detail":"{read_only:false; response_revision:21908; number_of_response:1; }","duration":"107.636084ms","start":"2026-08-27T20:00:44.577919Z","end":"2026-08-27T20:00:44.685555Z","steps":["trace[1948619199] 'process raft request' (duration: 107.432959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:44.870113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.838682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-27T20:00:44.870334Z","caller":"traceutil/trace.go:171","msg":"trace[1716020530] transaction","detail":"{read_only:false; response_revision:21909; number_of_response:1; }","duration":"291.574694ms","start":"2026-08-27T20:00:44.578673Z","end":"2026-08-27T20:00:44.870248Z","steps":["trace[1716020530] 'process raft request' (duration: 148.485506ms)","trace[1716020530] 'compare' (duration: 141.911682ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:00:44.911496Z","caller":"traceutil/trace.go:171","msg":"trace[1776356240] transaction","detail":"{read_only:false; response_revision:21910; number_of_response:1; }","duration":"218.858915ms","start":"2026-08-27T20:00:44.69258Z","end":"2026-08-27T20:00:44.911439Z","steps":["trace[1776356240] 'process raft request' (duration: 218.678561ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:45.174075Z","caller":"traceutil/trace.go:171","msg":"trace[1089430062] transaction","detail":"{read_only:false; response_revision:21911; number_of_response:1; }","duration":"252.441977ms","start":"2026-08-27T20:00:44.921592Z","end":"2026-08-27T20:00:45.174034Z","steps":["trace[1089430062] 'process raft request' (duration: 252.149881ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:45.490692Z","caller":"traceutil/trace.go:171","msg":"trace[1119422167] linearizableReadLoop","detail":"{readStateIndex:22664; appliedIndex:22663; }","duration":"316.703073ms","start":"2026-08-27T20:00:45.173964Z","end":"2026-08-27T20:00:45.490667Z","steps":["trace[1119422167] 'read index received' (duration: 316.56901ms)","trace[1119422167] 'applied index is now lower than readState.Index' (duration: 132.743µs)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:00:45.490737Z","caller":"traceutil/trace.go:171","msg":"trace[617741778] transaction","detail":"{read_only:false; response_revision:21912; number_of_response:1; }","duration":"400.882444ms","start":"2026-08-27T20:00:45.089824Z","end":"2026-08-27T20:00:45.490707Z","steps":["trace[617741778] 'process raft request' (duration: 400.6794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:45.491607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:00:45.089805Z","time spent":"400.994636ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-27T20:00:45.492104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.265309ms","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-08-27T20:00:45.492397Z","caller":"traceutil/trace.go:171","msg":"trace[1260164310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21912; }","duration":"367.466653ms","start":"2026-08-27T20:00:45.12476Z","end":"2026-08-27T20:00:45.492226Z","steps":["trace[1260164310] 'agreement among raft nodes before linearized reading' (duration: 366.035732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:45.492504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:00:45.124745Z","time spent":"367.741169ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-27T20:00:45.493891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.528913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-27T20:00:45.493987Z","caller":"traceutil/trace.go:171","msg":"trace[324746413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21912; }","duration":"404.656176ms","start":"2026-08-27T20:00:45.089303Z","end":"2026-08-27T20:00:45.493959Z","steps":["trace[324746413] 'agreement among raft nodes before linearized reading' (duration: 401.458776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:45.494055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:00:45.089293Z","time spent":"404.737738ms","remote":"127.0.0.1:33516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-27T20:00:45.501413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.156094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-27T20:00:45.501405Z","caller":"traceutil/trace.go:171","msg":"trace[2038114096] transaction","detail":"{read_only:false; response_revision:21913; number_of_response:1; }","duration":"319.29809ms","start":"2026-08-27T20:00:45.182051Z","end":"2026-08-27T20:00:45.501349Z","steps":["trace[2038114096] 'process raft request' (duration: 319.028784ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:45.501472Z","caller":"traceutil/trace.go:171","msg":"trace[1336791649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21913; }","duration":"167.255456ms","start":"2026-08-27T20:00:45.3342Z","end":"2026-08-27T20:00:45.501455Z","steps":["trace[1336791649] 'agreement among raft nodes before linearized reading' (duration: 167.070132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:45.501501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.17155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-27T20:00:45.501592Z","caller":"traceutil/trace.go:171","msg":"trace[137843675] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:21913; }","duration":"159.324373ms","start":"2026-08-27T20:00:45.342244Z","end":"2026-08-27T20:00:45.501568Z","steps":["trace[137843675] 'agreement among raft nodes before linearized reading' (duration: 159.124308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:45.501583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-27T20:00:45.182023Z","time spent":"319.462604ms","remote":"127.0.0.1:46192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-27T20:00:45.72944Z","caller":"traceutil/trace.go:171","msg":"trace[1706637629] transaction","detail":"{read_only:false; response_revision:21914; number_of_response:1; }","duration":"228.340752ms","start":"2026-08-27T20:00:45.50107Z","end":"2026-08-27T20:00:45.729411Z","steps":["trace[1706637629] 'process raft request' (duration: 203.1121ms)","trace[1706637629] 'compare' (duration: 24.979556ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:00:45.77141Z","caller":"traceutil/trace.go:171","msg":"trace[70832065] transaction","detail":"{read_only:false; response_revision:21915; number_of_response:1; }","duration":"267.083819ms","start":"2026-08-27T20:00:45.504289Z","end":"2026-08-27T20:00:45.771373Z","steps":["trace[70832065] 'process raft request' (duration: 266.854084ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:45.771674Z","caller":"traceutil/trace.go:171","msg":"trace[1985621832] linearizableReadLoop","detail":"{readStateIndex:22669; appliedIndex:22665; }","duration":"124.356239ms","start":"2026-08-27T20:00:45.647295Z","end":"2026-08-27T20:00:45.771652Z","steps":["trace[1985621832] 'read index received' (duration: 56.903014ms)","trace[1985621832] 'applied index is now lower than readState.Index' (duration: 67.452345ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:00:45.771772Z","caller":"traceutil/trace.go:171","msg":"trace[985813555] transaction","detail":"{read_only:false; response_revision:21917; number_of_response:1; }","duration":"262.553059ms","start":"2026-08-27T20:00:45.509196Z","end":"2026-08-27T20:00:45.771749Z","steps":["trace[985813555] 'process raft request' (duration: 262.361955ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:00:45.771848Z","caller":"traceutil/trace.go:171","msg":"trace[579784325] transaction","detail":"{read_only:false; response_revision:21916; number_of_response:1; }","duration":"265.590456ms","start":"2026-08-27T20:00:45.506239Z","end":"2026-08-27T20:00:45.77183Z","steps":["trace[579784325] 'process raft request' (duration: 265.076795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:45.771884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.569503ms","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-08-27T20:00:45.771917Z","caller":"traceutil/trace.go:171","msg":"trace[62988719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21917; }","duration":"124.643884ms","start":"2026-08-27T20:00:45.647263Z","end":"2026-08-27T20:00:45.771907Z","steps":["trace[62988719] 'agreement among raft nodes before linearized reading' (duration: 124.511732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:00:45.772418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.166795ms","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-08-27T20:00:45.77251Z","caller":"traceutil/trace.go:171","msg":"trace[2051898071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21917; }","duration":"124.377129ms","start":"2026-08-27T20:00:45.648108Z","end":"2026-08-27T20:00:45.772486Z","steps":["trace[2051898071] 'agreement among raft nodes before linearized reading' (duration: 124.104233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:54.74912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.343425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T20:01:54.749224Z","caller":"traceutil/trace.go:171","msg":"trace[1989109134] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:22563; }","duration":"122.480568ms","start":"2026-08-27T20:01:54.626724Z","end":"2026-08-27T20:01:54.749204Z","steps":["trace[1989109134] 'range keys from in-memory index tree' (duration: 122.288994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:54.749285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.154576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T20:01:54.74937Z","caller":"traceutil/trace.go:171","msg":"trace[1913114572] range","detail":"{range_begin:/registry/jobs/openstack/octavia-rabbit-init; range_end:; response_count:0; response_revision:22563; }","duration":"125.257318ms","start":"2026-08-27T20:01:54.624092Z","end":"2026-08-27T20:01:54.749349Z","steps":["trace[1913114572] 'range keys from in-memory index tree' (duration: 125.033013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:54.749366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.48544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-27T20:01:54.749396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.494359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T20:01:54.749424Z","caller":"traceutil/trace.go:171","msg":"trace[189357660] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:22563; }","duration":"123.544701ms","start":"2026-08-27T20:01:54.625863Z","end":"2026-08-27T20:01:54.749408Z","steps":["trace[189357660] 'range keys from in-memory index tree' (duration: 123.405848ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:01:54.749449Z","caller":"traceutil/trace.go:171","msg":"trace[1533811411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22563; }","duration":"182.586192ms","start":"2026-08-27T20:01:54.566849Z","end":"2026-08-27T20:01:54.749435Z","steps":["trace[1533811411] 'range keys from in-memory index tree' (duration: 182.387937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:01:54.749591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.852606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-db-sync\" ","response":"range_response_count:1 size:5065"} {"level":"info","ts":"2026-08-27T20:01:54.74962Z","caller":"traceutil/trace.go:171","msg":"trace[1840281426] range","detail":"{range_begin:/registry/jobs/openstack/octavia-db-sync; range_end:; response_count:1; response_revision:22563; }","duration":"122.904796ms","start":"2026-08-27T20:01:54.626706Z","end":"2026-08-27T20:01:54.749611Z","steps":["trace[1840281426] 'range keys from in-memory index tree' (duration: 122.713212ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:01:54.74967Z","caller":"traceutil/trace.go:171","msg":"trace[1037639487] transaction","detail":"{read_only:false; response_revision:22564; number_of_response:1; }","duration":"105.17114ms","start":"2026-08-27T20:01:54.644452Z","end":"2026-08-27T20:01:54.749624Z","steps":["trace[1037639487] 'process raft request' (duration: 84.288653ms)","trace[1037639487] 'compare' (duration: 20.349735ms)"],"step_count":2} {"level":"info","ts":"2026-08-27T20:04:53.016572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21550} {"level":"info","ts":"2026-08-27T20:04:53.133934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21550,"took":"114.218437ms","hash":2697900427,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":41869312,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-27T20:04:53.133984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2697900427,"revision":21550,"compact-revision":19272} {"level":"info","ts":"2026-08-27T20:04:56.57132Z","caller":"traceutil/trace.go:171","msg":"trace[916764860] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"130.230457ms","start":"2026-08-27T20:04:56.441072Z","end":"2026-08-27T20:04:56.571303Z","steps":["trace[916764860] 'process raft request' (duration: 129.900779ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:06:07.647985Z","caller":"traceutil/trace.go:171","msg":"trace[1397448578] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"115.964852ms","start":"2026-08-27T20:06:07.531945Z","end":"2026-08-27T20:06:07.64791Z","steps":["trace[1397448578] 'process raft request' (duration: 115.809239ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:06:07.672759Z","caller":"traceutil/trace.go:171","msg":"trace[1328654910] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"140.242126ms","start":"2026-08-27T20:06:07.532497Z","end":"2026-08-27T20:06:07.672739Z","steps":["trace[1328654910] 'process raft request' (duration: 140.147274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-27T20:06:13.095498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.976945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-27T20:06:13.09556Z","caller":"traceutil/trace.go:171","msg":"trace[669084371] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25356; }","duration":"102.078366ms","start":"2026-08-27T20:06:12.993463Z","end":"2026-08-27T20:06:13.095541Z","steps":["trace[669084371] 'count revisions from in-memory index tree' (duration: 101.910413ms)"],"step_count":1} {"level":"info","ts":"2026-08-27T20:07:06.488996Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006808.wal"} {"level":"info","ts":"2026-08-27T20:09:53.023566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24373} {"level":"info","ts":"2026-08-27T20:09:53.174754Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24373,"took":"146.305361ms","hash":3962265862,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":34054144,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-27T20:09:53.174822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3962265862,"revision":24373,"compact-revision":21550} {"level":"info","ts":"2026-08-27T20:14:28.896099Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6814e3e3d6b4d713","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-27T20:14:28.906286Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-27T20:14:28.906426Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-27T20:14:53.04149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26999} {"level":"info","ts":"2026-08-27T20:14:53.187748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26999,"took":"141.378172ms","hash":495766470,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":29564928,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-27T20:14:53.187815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":495766470,"revision":26999,"compact-revision":24373}