{"level":"warn","ts":"2026-06-27T00:07:51.975428Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-27T00:07:51.975547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-06-27T00:07:51.975638Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-27T00:07:51.975836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-27T00:07:51.975858Z","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-06-27T00:07:51.976679Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-06-27T00:07:51.976776Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-06-27T00:07:51.981793Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.762165ms"} {"level":"info","ts":"2026-06-27T00:07:51.992605Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-06-27T00:07:51.99269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-06-27T00:07:51.99271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-06-27T00:07:51.992721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-27T00:07:51.992735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-06-27T00:07:51.992814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-06-27T00:07:52.003604Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-27T00:07:52.004923Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-27T00:07:52.007106Z","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-06-27T00:07:52.011294Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-27T00:07:52.01185Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-27T00:07:52.012011Z","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-06-27T00:07:52.012895Z","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-06-27T00:07:52.013178Z","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-06-27T00:07:52.013379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-06-27T00:07:52.013658Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-06-27T00:07:52.016915Z","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-06-27T00:07:52.017105Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-27T00:07:52.017151Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-06-27T00:07:52.017338Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-27T00:07:52.017423Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-27T00:07:52.493491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-06-27T00:07:52.493574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-27T00:07:52.493626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-06-27T00:07:52.493656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-06-27T00:07:52.493669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-27T00:07:52.493694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-06-27T00:07:52.493709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-06-27T00:07:52.494778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-06-27T00:07:52.494781Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-27T00:07:52.494822Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T00:07:52.494898Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-27T00:07:52.49504Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-27T00:07:52.495105Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-27T00:07:52.49608Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T00:07:52.496241Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T00:07:52.496307Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-27T00:07:52.501089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-27T00:07:52.501313Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"warn","ts":"2026-06-27T00:08:16.129315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.464967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T00:08:16.12947Z","caller":"traceutil/trace.go:171","msg":"trace[874603826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"123.672562ms","start":"2026-06-27T00:08:16.005764Z","end":"2026-06-27T00:08:16.129436Z","steps":["trace[874603826] 'range keys from in-memory index tree' (duration: 123.368194ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:29.9836Z","caller":"traceutil/trace.go:171","msg":"trace[1080724082] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"101.599614ms","start":"2026-06-27T00:08:29.881962Z","end":"2026-06-27T00:08:29.983562Z","steps":["trace[1080724082] 'process raft request' (duration: 101.371887ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:29.983651Z","caller":"traceutil/trace.go:171","msg":"trace[1044054515] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"101.550402ms","start":"2026-06-27T00:08:29.882071Z","end":"2026-06-27T00:08:29.983621Z","steps":["trace[1044054515] 'process raft request' (duration: 101.369228ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:29.983967Z","caller":"traceutil/trace.go:171","msg":"trace[1285758663] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"101.853989ms","start":"2026-06-27T00:08:29.882074Z","end":"2026-06-27T00:08:29.983928Z","steps":["trace[1285758663] 'process raft request' (duration: 101.44868ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:29.984226Z","caller":"traceutil/trace.go:171","msg":"trace[661633827] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"102.088875ms","start":"2026-06-27T00:08:29.882122Z","end":"2026-06-27T00:08:29.984211Z","steps":["trace[661633827] 'process raft request' (duration: 101.746377ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:29.984377Z","caller":"traceutil/trace.go:171","msg":"trace[514947173] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"100.618399ms","start":"2026-06-27T00:08:29.883744Z","end":"2026-06-27T00:08:29.984362Z","steps":["trace[514947173] 'process raft request' (duration: 100.358123ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:35.894035Z","caller":"traceutil/trace.go:171","msg":"trace[49282759] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"142.479611ms","start":"2026-06-27T00:08:35.751525Z","end":"2026-06-27T00:08:35.894004Z","steps":["trace[49282759] 'process raft request' (duration: 141.881276ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:36.180202Z","caller":"traceutil/trace.go:171","msg":"trace[1470015175] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"171.475802ms","start":"2026-06-27T00:08:36.008697Z","end":"2026-06-27T00:08:36.180173Z","steps":["trace[1470015175] 'read index received' (duration: 171.206514ms)","trace[1470015175] 'applied index is now lower than readState.Index' (duration: 268.347µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:08:36.180274Z","caller":"traceutil/trace.go:171","msg":"trace[1686409704] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"266.970555ms","start":"2026-06-27T00:08:35.913264Z","end":"2026-06-27T00:08:36.180235Z","steps":["trace[1686409704] 'process raft request' (duration: 266.701098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:08:36.180385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.676096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T00:08:36.18044Z","caller":"traceutil/trace.go:171","msg":"trace[371226253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"171.744467ms","start":"2026-06-27T00:08:36.008676Z","end":"2026-06-27T00:08:36.180421Z","steps":["trace[371226253] 'agreement among raft nodes before linearized reading' (duration: 171.627375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:08:36.180416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.264316ms","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-06-27T00:08:36.180768Z","caller":"traceutil/trace.go:171","msg":"trace[1461880491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"110.48316ms","start":"2026-06-27T00:08:36.070089Z","end":"2026-06-27T00:08:36.180573Z","steps":["trace[1461880491] 'agreement among raft nodes before linearized reading' (duration: 110.250055ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:36.347664Z","caller":"traceutil/trace.go:171","msg":"trace[1836803030] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"115.269377ms","start":"2026-06-27T00:08:36.232355Z","end":"2026-06-27T00:08:36.347624Z","steps":["trace[1836803030] 'read index received' (duration: 18.110353ms)","trace[1836803030] 'applied index is now lower than readState.Index' (duration: 97.157874ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:08:36.347785Z","caller":"traceutil/trace.go:171","msg":"trace[750833395] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"160.917451ms","start":"2026-06-27T00:08:36.186822Z","end":"2026-06-27T00:08:36.347739Z","steps":["trace[750833395] 'process raft request' (duration: 63.623505ms)","trace[750833395] 'compare' (duration: 97.001359ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T00:08:36.347904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.512812ms","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-06-27T00:08:36.347993Z","caller":"traceutil/trace.go:171","msg":"trace[957740413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"115.656156ms","start":"2026-06-27T00:08:36.232315Z","end":"2026-06-27T00:08:36.347971Z","steps":["trace[957740413] 'agreement among raft nodes before linearized reading' (duration: 115.440181ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:08:36.87307Z","caller":"traceutil/trace.go:171","msg":"trace[1126272780] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"426.025661ms","start":"2026-06-27T00:08:36.447008Z","end":"2026-06-27T00:08:36.873034Z","steps":["trace[1126272780] 'process raft request' (duration: 415.208416ms)","trace[1126272780] 'compare' (duration: 10.451726ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:08:36.873234Z","caller":"traceutil/trace.go:171","msg":"trace[1877627851] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"407.031717ms","start":"2026-06-27T00:08:36.466126Z","end":"2026-06-27T00:08:36.873158Z","steps":["trace[1877627851] 'read index received' (duration: 396.085759ms)","trace[1877627851] 'applied index is now lower than readState.Index' (duration: 10.939958ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:08:36.873402Z","caller":"traceutil/trace.go:171","msg":"trace[1399815513] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"415.537485ms","start":"2026-06-27T00:08:36.457777Z","end":"2026-06-27T00:08:36.873314Z","steps":["trace[1399815513] 'process raft request' (duration: 415.189326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:08:36.873679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.519168ms","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-06-27T00:08:36.873751Z","caller":"traceutil/trace.go:171","msg":"trace[146070517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"407.666422ms","start":"2026-06-27T00:08:36.466071Z","end":"2026-06-27T00:08:36.873737Z","steps":["trace[146070517] 'agreement among raft nodes before linearized reading' (duration: 407.290913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:08:36.87378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T00:08:36.44698Z","time spent":"426.165905ms","remote":"127.0.0.1:55180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T00:08:36.873829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T00:08:36.466021Z","time spent":"407.796055ms","remote":"127.0.0.1:55180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-27T00:08:36.873869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T00:08:36.457749Z","time spent":"415.75292ms","remote":"127.0.0.1:55420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-27T00:09:48.492959Z","caller":"traceutil/trace.go:171","msg":"trace[332436379] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"145.089415ms","start":"2026-06-27T00:09:48.347834Z","end":"2026-06-27T00:09:48.492924Z","steps":["trace[332436379] 'read index received' (duration: 144.685985ms)","trace[332436379] 'applied index is now lower than readState.Index' (duration: 401.86µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:09:48.493084Z","caller":"traceutil/trace.go:171","msg":"trace[2067553636] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"187.33574ms","start":"2026-06-27T00:09:48.305703Z","end":"2026-06-27T00:09:48.493039Z","steps":["trace[2067553636] 'process raft request' (duration: 187.013903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:09:48.493162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.290759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T00:09:48.493218Z","caller":"traceutil/trace.go:171","msg":"trace[951803516] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1288; }","duration":"145.427552ms","start":"2026-06-27T00:09:48.347774Z","end":"2026-06-27T00:09:48.493202Z","steps":["trace[951803516] 'agreement among raft nodes before linearized reading' (duration: 145.302229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:10:39.397762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.206543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eafdacc6-2c3d-4ed7-9344-9c4bca9a1307\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-27T00:10:39.39787Z","caller":"traceutil/trace.go:171","msg":"trace[1266293882] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eafdacc6-2c3d-4ed7-9344-9c4bca9a1307; range_end:; response_count:1; response_revision:1978; }","duration":"186.357067ms","start":"2026-06-27T00:10:39.211491Z","end":"2026-06-27T00:10:39.397848Z","steps":["trace[1266293882] 'range keys from in-memory index tree' (duration: 186.022449ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:10:39.903136Z","caller":"traceutil/trace.go:171","msg":"trace[179900442] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"228.724073ms","start":"2026-06-27T00:10:39.674373Z","end":"2026-06-27T00:10:39.903097Z","steps":["trace[179900442] 'process raft request' (duration: 228.556689ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:10:39.903134Z","caller":"traceutil/trace.go:171","msg":"trace[834038488] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"228.864656ms","start":"2026-06-27T00:10:39.6742Z","end":"2026-06-27T00:10:39.903065Z","steps":["trace[834038488] 'process raft request' (duration: 213.916584ms)","trace[834038488] 'compare' (duration: 14.617113ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:10:52.068111Z","caller":"traceutil/trace.go:171","msg":"trace[704183362] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"105.05114ms","start":"2026-06-27T00:10:51.963033Z","end":"2026-06-27T00:10:52.068085Z","steps":["trace[704183362] 'process raft request' (duration: 104.783423ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:10:56.130408Z","caller":"traceutil/trace.go:171","msg":"trace[823675973] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"108.158945ms","start":"2026-06-27T00:10:56.022206Z","end":"2026-06-27T00:10:56.130365Z","steps":["trace[823675973] 'process raft request' (duration: 107.961451ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:10:56.368362Z","caller":"traceutil/trace.go:171","msg":"trace[2116011715] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"232.136545ms","start":"2026-06-27T00:10:56.136184Z","end":"2026-06-27T00:10:56.368321Z","steps":["trace[2116011715] 'process raft request' (duration: 229.181593ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:10:56.368708Z","caller":"traceutil/trace.go:171","msg":"trace[1826147988] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"111.416774ms","start":"2026-06-27T00:10:56.257281Z","end":"2026-06-27T00:10:56.368697Z","steps":["trace[1826147988] 'process raft request' (duration: 110.957364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:11:28.48883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.060606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T00:11:28.488951Z","caller":"traceutil/trace.go:171","msg":"trace[654451257] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2291; }","duration":"123.259091ms","start":"2026-06-27T00:11:28.365668Z","end":"2026-06-27T00:11:28.488928Z","steps":["trace[654451257] 'count revisions from in-memory index tree' (duration: 122.944344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:11:28.488941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.396364ms","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-06-27T00:11:28.489018Z","caller":"traceutil/trace.go:171","msg":"trace[307640053] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2291; }","duration":"123.514327ms","start":"2026-06-27T00:11:28.365483Z","end":"2026-06-27T00:11:28.488997Z","steps":["trace[307640053] 'range keys from in-memory index tree' (duration: 123.135898ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:11:28.591779Z","caller":"traceutil/trace.go:171","msg":"trace[127540180] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"110.162484ms","start":"2026-06-27T00:11:28.481584Z","end":"2026-06-27T00:11:28.591747Z","steps":["trace[127540180] 'process raft request' (duration: 109.98584ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:11:28.687299Z","caller":"traceutil/trace.go:171","msg":"trace[1789438075] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2380; }","duration":"178.786344ms","start":"2026-06-27T00:11:28.508476Z","end":"2026-06-27T00:11:28.687263Z","steps":["trace[1789438075] 'read index received' (duration: 83.073589ms)","trace[1789438075] 'applied index is now lower than readState.Index' (duration: 95.711145ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:11:28.687399Z","caller":"traceutil/trace.go:171","msg":"trace[891652973] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"204.198729ms","start":"2026-06-27T00:11:28.483152Z","end":"2026-06-27T00:11:28.687351Z","steps":["trace[891652973] 'process raft request' (duration: 203.907752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:11:28.687746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.240215ms","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-06-27T00:11:28.687833Z","caller":"traceutil/trace.go:171","msg":"trace[351089308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2293; }","duration":"179.355397ms","start":"2026-06-27T00:11:28.508433Z","end":"2026-06-27T00:11:28.687789Z","steps":["trace[351089308] 'agreement among raft nodes before linearized reading' (duration: 178.952588ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:11:28.720957Z","caller":"traceutil/trace.go:171","msg":"trace[1137004517] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"112.437659ms","start":"2026-06-27T00:11:28.608483Z","end":"2026-06-27T00:11:28.720921Z","steps":["trace[1137004517] 'process raft request' (duration: 93.725426ms)","trace[1137004517] 'compare' (duration: 18.495838ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:11:34.983034Z","caller":"traceutil/trace.go:171","msg":"trace[1098526974] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"274.638423ms","start":"2026-06-27T00:11:34.708369Z","end":"2026-06-27T00:11:34.983008Z","steps":["trace[1098526974] 'process raft request' (duration: 274.49284ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:11:35.011734Z","caller":"traceutil/trace.go:171","msg":"trace[949043250] linearizableReadLoop","detail":"{readStateIndex:2417; appliedIndex:2415; }","duration":"267.470611ms","start":"2026-06-27T00:11:34.744239Z","end":"2026-06-27T00:11:35.01171Z","steps":["trace[949043250] 'read index received' (duration: 238.553674ms)","trace[949043250] 'applied index is now lower than readState.Index' (duration: 28.914977ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:11:35.011734Z","caller":"traceutil/trace.go:171","msg":"trace[1711500168] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"271.454047ms","start":"2026-06-27T00:11:34.740241Z","end":"2026-06-27T00:11:35.011695Z","steps":["trace[1711500168] 'process raft request' (duration: 265.453802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:11:35.01198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.721937ms","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-06-27T00:11:35.012024Z","caller":"traceutil/trace.go:171","msg":"trace[1615968459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2328; }","duration":"267.83067ms","start":"2026-06-27T00:11:34.74418Z","end":"2026-06-27T00:11:35.01201Z","steps":["trace[1615968459] 'agreement among raft nodes before linearized reading' (duration: 267.622075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:15:26.585369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.278812ms","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-06-27T00:15:26.585458Z","caller":"traceutil/trace.go:171","msg":"trace[2129086271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3957; }","duration":"112.393615ms","start":"2026-06-27T00:15:26.473049Z","end":"2026-06-27T00:15:26.585442Z","steps":["trace[2129086271] 'range keys from in-memory index tree' (duration: 112.039485ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:16:55.933302Z","caller":"traceutil/trace.go:171","msg":"trace[965542890] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"139.357268ms","start":"2026-06-27T00:16:55.793909Z","end":"2026-06-27T00:16:55.933266Z","steps":["trace[965542890] 'process raft request' (duration: 108.383284ms)","trace[965542890] 'compare' (duration: 30.80351ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:16:55.95797Z","caller":"traceutil/trace.go:171","msg":"trace[420782896] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"153.049356ms","start":"2026-06-27T00:16:55.804885Z","end":"2026-06-27T00:16:55.957935Z","steps":["trace[420782896] 'process raft request' (duration: 152.862111ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:17:08.642159Z","caller":"traceutil/trace.go:171","msg":"trace[847812954] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"253.602343ms","start":"2026-06-27T00:17:08.388488Z","end":"2026-06-27T00:17:08.642091Z","steps":["trace[847812954] 'process raft request' (duration: 218.875985ms)","trace[847812954] 'compare' (duration: 34.259707ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:17:08.642163Z","caller":"traceutil/trace.go:171","msg":"trace[465379904] linearizableReadLoop","detail":"{readStateIndex:5609; appliedIndex:5608; }","duration":"217.450892ms","start":"2026-06-27T00:17:08.424678Z","end":"2026-06-27T00:17:08.642129Z","steps":["trace[465379904] 'read index received' (duration: 182.716664ms)","trace[465379904] 'applied index is now lower than readState.Index' (duration: 34.733078ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:17:08.642351Z","caller":"traceutil/trace.go:171","msg":"trace[1862374009] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"208.566698ms","start":"2026-06-27T00:17:08.433767Z","end":"2026-06-27T00:17:08.642334Z","steps":["trace[1862374009] 'process raft request' (duration: 208.271401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:17:08.642394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.679737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T00:17:08.642481Z","caller":"traceutil/trace.go:171","msg":"trace[925782639] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"205.735349ms","start":"2026-06-27T00:17:08.436735Z","end":"2026-06-27T00:17:08.64247Z","steps":["trace[925782639] 'process raft request' (duration: 205.547014ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:17:08.64254Z","caller":"traceutil/trace.go:171","msg":"trace[276009964] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5406; }","duration":"217.844261ms","start":"2026-06-27T00:17:08.424624Z","end":"2026-06-27T00:17:08.642468Z","steps":["trace[276009964] 'agreement among raft nodes before linearized reading' (duration: 217.545084ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:17:16.782879Z","caller":"traceutil/trace.go:171","msg":"trace[1575255726] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"110.162926ms","start":"2026-06-27T00:17:16.672681Z","end":"2026-06-27T00:17:16.782844Z","steps":["trace[1575255726] 'process raft request' (duration: 109.993662ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:17:16.78315Z","caller":"traceutil/trace.go:171","msg":"trace[787754026] transaction","detail":"{read_only:false; response_revision:5565; number_of_response:1; }","duration":"108.600747ms","start":"2026-06-27T00:17:16.674511Z","end":"2026-06-27T00:17:16.783112Z","steps":["trace[787754026] 'process raft request' (duration: 108.28442ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:17:52.530238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2946} {"level":"info","ts":"2026-06-27T00:17:52.603789Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2946,"took":"71.33342ms","hash":818003587,"current-db-size-bytes":56373248,"current-db-size":"56 MB","current-db-size-in-use-bytes":33513472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-27T00:17:52.603878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":818003587,"revision":2946,"compact-revision":-1} {"level":"info","ts":"2026-06-27T00:22:52.538544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5885} {"level":"info","ts":"2026-06-27T00:22:52.623866Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5885,"took":"83.580527ms","hash":2364723483,"current-db-size-bytes":56373248,"current-db-size":"56 MB","current-db-size-in-use-bytes":26423296,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-27T00:22:52.623954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2364723483,"revision":5885,"compact-revision":2946} {"level":"info","ts":"2026-06-27T00:23:37.515327Z","caller":"traceutil/trace.go:171","msg":"trace[430250048] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"261.107604ms","start":"2026-06-27T00:23:37.254188Z","end":"2026-06-27T00:23:37.515296Z","steps":["trace[430250048] 'process raft request' (duration: 245.329312ms)","trace[430250048] 'compare' (duration: 15.646049ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:23:37.68533Z","caller":"traceutil/trace.go:171","msg":"trace[1787785080] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"430.991604ms","start":"2026-06-27T00:23:37.254307Z","end":"2026-06-27T00:23:37.685298Z","steps":["trace[1787785080] 'process raft request' (duration: 403.486751ms)","trace[1787785080] 'compare' (duration: 27.045683ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T00:23:37.685509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T00:23:37.254282Z","time spent":"431.129408ms","remote":"127.0.0.1:55180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-27T00:23:37.685927Z","caller":"traceutil/trace.go:171","msg":"trace[743231880] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"196.116535ms","start":"2026-06-27T00:23:37.489797Z","end":"2026-06-27T00:23:37.685913Z","steps":["trace[743231880] 'process raft request' (duration: 195.209473ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:23:37.68606Z","caller":"traceutil/trace.go:171","msg":"trace[1196661636] linearizableReadLoop","detail":"{readStateIndex:8780; appliedIndex:8778; }","duration":"420.711157ms","start":"2026-06-27T00:23:37.265322Z","end":"2026-06-27T00:23:37.686033Z","steps":["trace[1196661636] 'read index received' (duration: 234.196633ms)","trace[1196661636] 'applied index is now lower than readState.Index' (duration: 186.510844ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T00:23:37.686332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.063395ms","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-06-27T00:23:37.686389Z","caller":"traceutil/trace.go:171","msg":"trace[1958818854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8479; }","duration":"295.150196ms","start":"2026-06-27T00:23:37.391222Z","end":"2026-06-27T00:23:37.686373Z","steps":["trace[1958818854] 'agreement among raft nodes before linearized reading' (duration: 294.957281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:23:37.686515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.170948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224092"} {"level":"info","ts":"2026-06-27T00:23:37.686612Z","caller":"traceutil/trace.go:171","msg":"trace[1654838782] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8479; }","duration":"421.320292ms","start":"2026-06-27T00:23:37.265274Z","end":"2026-06-27T00:23:37.686594Z","steps":["trace[1654838782] 'agreement among raft nodes before linearized reading' (duration: 420.852561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:23:37.686692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T00:23:37.265253Z","time spent":"421.428215ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":224115,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-27T00:23:49.930449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.173277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-27T00:23:49.930533Z","caller":"traceutil/trace.go:171","msg":"trace[725677178] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8844; }","duration":"187.066087ms","start":"2026-06-27T00:23:49.743452Z","end":"2026-06-27T00:23:49.930518Z","steps":["trace[725677178] 'read index received' (duration: 9.608252ms)","trace[725677178] 'applied index is now lower than readState.Index' (duration: 177.456845ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:23:49.930583Z","caller":"traceutil/trace.go:171","msg":"trace[1380558569] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"203.804691ms","start":"2026-06-27T00:23:49.726731Z","end":"2026-06-27T00:23:49.930536Z","steps":["trace[1380558569] 'process raft request' (duration: 26.370297ms)","trace[1380558569] 'compare' (duration: 177.086675ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T00:23:49.930673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.260222ms","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-06-27T00:23:49.930694Z","caller":"traceutil/trace.go:171","msg":"trace[2043389791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8541; }","duration":"187.372664ms","start":"2026-06-27T00:23:49.743315Z","end":"2026-06-27T00:23:49.930688Z","steps":["trace[2043389791] 'agreement among raft nodes before linearized reading' (duration: 187.267832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:23:49.930764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.049248ms","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-06-27T00:23:49.930833Z","caller":"traceutil/trace.go:171","msg":"trace[2036457361] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8541; }","duration":"185.15959ms","start":"2026-06-27T00:23:49.745656Z","end":"2026-06-27T00:23:49.930815Z","steps":["trace[2036457361] 'agreement among raft nodes before linearized reading' (duration: 184.959945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:23:49.930932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.284525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cbh6s\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-27T00:23:49.930973Z","caller":"traceutil/trace.go:171","msg":"trace[1602530865] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cbh6s; range_end:; response_count:1; response_revision:8541; }","duration":"171.362058ms","start":"2026-06-27T00:23:49.7596Z","end":"2026-06-27T00:23:49.930962Z","steps":["trace[1602530865] 'agreement among raft nodes before linearized reading' (duration: 171.181773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:23:49.931016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.861276ms","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-06-27T00:23:49.93104Z","caller":"traceutil/trace.go:171","msg":"trace[1260945988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8541; }","duration":"182.905577ms","start":"2026-06-27T00:23:49.748128Z","end":"2026-06-27T00:23:49.931033Z","steps":["trace[1260945988] 'agreement among raft nodes before linearized reading' (duration: 182.845296ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:23:50.68119Z","caller":"traceutil/trace.go:171","msg":"trace[1906195841] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"152.336799ms","start":"2026-06-27T00:23:50.528807Z","end":"2026-06-27T00:23:50.681143Z","steps":["trace[1906195841] 'process raft request' (duration: 152.157233ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:23:51.53829Z","caller":"traceutil/trace.go:171","msg":"trace[726055791] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"152.679075ms","start":"2026-06-27T00:23:51.385578Z","end":"2026-06-27T00:23:51.538257Z","steps":["trace[726055791] 'process raft request' (duration: 152.529652ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:24:14.852873Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002325.wal"} {"level":"info","ts":"2026-06-27T00:24:18.921895Z","caller":"traceutil/trace.go:171","msg":"trace[25750496] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"104.962495ms","start":"2026-06-27T00:24:18.816906Z","end":"2026-06-27T00:24:18.921868Z","steps":["trace[25750496] 'process raft request' (duration: 104.819381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:24:19.120417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.159242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T00:24:19.120539Z","caller":"traceutil/trace.go:171","msg":"trace[97377132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8755; }","duration":"113.345586ms","start":"2026-06-27T00:24:19.007171Z","end":"2026-06-27T00:24:19.120517Z","steps":["trace[97377132] 'range keys from in-memory index tree' (duration: 113.055199ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:24:31.51415Z","caller":"traceutil/trace.go:171","msg":"trace[1135278371] linearizableReadLoop","detail":"{readStateIndex:9176; appliedIndex:9175; }","duration":"113.833358ms","start":"2026-06-27T00:24:31.400295Z","end":"2026-06-27T00:24:31.514128Z","steps":["trace[1135278371] 'read index received' (duration: 55.748716ms)","trace[1135278371] 'applied index is now lower than readState.Index' (duration: 58.083942ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:24:31.514255Z","caller":"traceutil/trace.go:171","msg":"trace[1378949648] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"154.05616ms","start":"2026-06-27T00:24:31.360162Z","end":"2026-06-27T00:24:31.514219Z","steps":["trace[1378949648] 'process raft request' (duration: 95.948746ms)","trace[1378949648] 'compare' (duration: 57.924459ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T00:24:31.514322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.021473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-06-27T00:24:31.514346Z","caller":"traceutil/trace.go:171","msg":"trace[903606927] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8858; }","duration":"114.142386ms","start":"2026-06-27T00:24:31.400197Z","end":"2026-06-27T00:24:31.51434Z","steps":["trace[903606927] 'agreement among raft nodes before linearized reading' (duration: 114.015103ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:24:31.760574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.585401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-27T00:24:31.761091Z","caller":"traceutil/trace.go:171","msg":"trace[1149179303] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"203.402071ms","start":"2026-06-27T00:24:31.557282Z","end":"2026-06-27T00:24:31.760684Z","steps":["trace[1149179303] 'process raft request' (duration: 77.620294ms)","trace[1149179303] 'compare' (duration: 125.234073ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:24:31.763295Z","caller":"traceutil/trace.go:171","msg":"trace[382196999] linearizableReadLoop","detail":"{readStateIndex:9178; appliedIndex:9176; }","duration":"181.04574ms","start":"2026-06-27T00:24:31.582218Z","end":"2026-06-27T00:24:31.763264Z","steps":["trace[382196999] 'read index received' (duration: 52.99544ms)","trace[382196999] 'applied index is now lower than readState.Index' (duration: 128.04872ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:24:31.763725Z","caller":"traceutil/trace.go:171","msg":"trace[1741179868] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"201.378202ms","start":"2026-06-27T00:24:31.562328Z","end":"2026-06-27T00:24:31.763706Z","steps":["trace[1741179868] 'process raft request' (duration: 200.755176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:24:31.764047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.807449ms","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-06-27T00:24:31.7641Z","caller":"traceutil/trace.go:171","msg":"trace[2044664085] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8860; }","duration":"181.901042ms","start":"2026-06-27T00:24:31.582182Z","end":"2026-06-27T00:24:31.764083Z","steps":["trace[2044664085] 'agreement among raft nodes before linearized reading' (duration: 181.80764ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:24:32.454929Z","caller":"traceutil/trace.go:171","msg":"trace[1593961048] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"114.983766ms","start":"2026-06-27T00:24:32.339919Z","end":"2026-06-27T00:24:32.454903Z","steps":["trace[1593961048] 'process raft request' (duration: 114.801712ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:24:32.651795Z","caller":"traceutil/trace.go:171","msg":"trace[1471345007] transaction","detail":"{read_only:false; response_revision:8868; number_of_response:1; }","duration":"126.72988ms","start":"2026-06-27T00:24:32.52504Z","end":"2026-06-27T00:24:32.65177Z","steps":["trace[1471345007] 'process raft request' (duration: 49.936395ms)","trace[1471345007] 'compare' (duration: 76.684332ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:25:56.468577Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-27T00:25:56.475482Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-27T00:25:56.475632Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-27T00:27:52.545341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8059} {"level":"info","ts":"2026-06-27T00:27:52.607217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8059,"took":"59.515802ms","hash":4294213888,"current-db-size-bytes":56373248,"current-db-size":"56 MB","current-db-size-in-use-bytes":28860416,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-27T00:27:52.60729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4294213888,"revision":8059,"compact-revision":5885} {"level":"info","ts":"2026-06-27T00:31:25.803331Z","caller":"traceutil/trace.go:171","msg":"trace[1672525537] transaction","detail":"{read_only:false; response_revision:12032; number_of_response:1; }","duration":"148.259723ms","start":"2026-06-27T00:31:25.655047Z","end":"2026-06-27T00:31:25.803307Z","steps":["trace[1672525537] 'process raft request' (duration: 148.057808ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:32:52.554113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10471} {"level":"info","ts":"2026-06-27T00:32:52.6408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10471,"took":"83.096516ms","hash":1286510814,"current-db-size-bytes":56373248,"current-db-size":"56 MB","current-db-size-in-use-bytes":26365952,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-27T00:32:52.640878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1286510814,"revision":10471,"compact-revision":8059} {"level":"info","ts":"2026-06-27T00:37:52.561117Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12603} {"level":"info","ts":"2026-06-27T00:37:52.633147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12603,"took":"69.186506ms","hash":2792513995,"current-db-size-bytes":56373248,"current-db-size":"56 MB","current-db-size-in-use-bytes":26812416,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-27T00:37:52.633212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2792513995,"revision":12603,"compact-revision":10471} {"level":"info","ts":"2026-06-27T00:37:55.13899Z","caller":"traceutil/trace.go:171","msg":"trace[1964256677] transaction","detail":"{read_only:false; response_revision:14633; number_of_response:1; }","duration":"120.351875ms","start":"2026-06-27T00:37:55.018611Z","end":"2026-06-27T00:37:55.138963Z","steps":["trace[1964256677] 'process raft request' (duration: 120.189151ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:38:11.021809Z","caller":"traceutil/trace.go:171","msg":"trace[1429353508] transaction","detail":"{read_only:false; response_revision:14730; number_of_response:1; }","duration":"388.030345ms","start":"2026-06-27T00:38:10.633747Z","end":"2026-06-27T00:38:11.021777Z","steps":["trace[1429353508] 'process raft request' (duration: 387.867312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:38:11.022012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-27T00:38:10.633728Z","time spent":"388.185219ms","remote":"127.0.0.1:55122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-27T00:38:19.718523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.799011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-27T00:38:19.718735Z","caller":"traceutil/trace.go:171","msg":"trace[1030619738] transaction","detail":"{read_only:false; response_revision:14898; number_of_response:1; }","duration":"124.981736ms","start":"2026-06-27T00:38:19.59373Z","end":"2026-06-27T00:38:19.718712Z","steps":["trace[1030619738] 'process raft request' (duration: 11.870757ms)","trace[1030619738] 'compare' (duration: 112.618487ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:38:20.023975Z","caller":"traceutil/trace.go:171","msg":"trace[1087419350] linearizableReadLoop","detail":"{readStateIndex:15417; appliedIndex:15416; }","duration":"200.76225ms","start":"2026-06-27T00:38:19.82319Z","end":"2026-06-27T00:38:20.023953Z","steps":["trace[1087419350] 'read index received' (duration: 200.572116ms)","trace[1087419350] 'applied index is now lower than readState.Index' (duration: 189.254µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:38:20.024023Z","caller":"traceutil/trace.go:171","msg":"trace[1120304428] transaction","detail":"{read_only:false; response_revision:14901; number_of_response:1; }","duration":"214.571424ms","start":"2026-06-27T00:38:19.809429Z","end":"2026-06-27T00:38:20.024Z","steps":["trace[1120304428] 'process raft request' (duration: 214.37999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:38:20.024172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.942735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T00:38:20.024226Z","caller":"traceutil/trace.go:171","msg":"trace[59232779] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:14901; }","duration":"201.059618ms","start":"2026-06-27T00:38:19.823149Z","end":"2026-06-27T00:38:20.024208Z","steps":["trace[59232779] 'agreement among raft nodes before linearized reading' (duration: 200.943215ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:38:20.024319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.632582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-27T00:38:20.024396Z","caller":"traceutil/trace.go:171","msg":"trace[2061796708] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:14901; }","duration":"175.745755ms","start":"2026-06-27T00:38:19.848636Z","end":"2026-06-27T00:38:20.024382Z","steps":["trace[2061796708] 'agreement among raft nodes before linearized reading' (duration: 175.53384ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:39:58.812675Z","caller":"traceutil/trace.go:171","msg":"trace[605463672] linearizableReadLoop","detail":"{readStateIndex:16529; appliedIndex:16528; }","duration":"120.298443ms","start":"2026-06-27T00:39:58.692328Z","end":"2026-06-27T00:39:58.812626Z","steps":["trace[605463672] 'read index received' (duration: 99.298664ms)","trace[605463672] 'applied index is now lower than readState.Index' (duration: 20.996769ms)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:39:58.812787Z","caller":"traceutil/trace.go:171","msg":"trace[1888908809] transaction","detail":"{read_only:false; response_revision:15982; number_of_response:1; }","duration":"137.501869ms","start":"2026-06-27T00:39:58.67526Z","end":"2026-06-27T00:39:58.812762Z","steps":["trace[1888908809] 'process raft request' (duration: 116.320696ms)","trace[1888908809] 'compare' (duration: 20.727693ms)"],"step_count":2} {"level":"warn","ts":"2026-06-27T00:39:58.81307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.68765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-06-27T00:39:58.813006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.396726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T00:39:58.813118Z","caller":"traceutil/trace.go:171","msg":"trace[712657451] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15982; }","duration":"115.752353ms","start":"2026-06-27T00:39:58.697353Z","end":"2026-06-27T00:39:58.813105Z","steps":["trace[712657451] 'agreement among raft nodes before linearized reading' (duration: 115.610019ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:39:58.813161Z","caller":"traceutil/trace.go:171","msg":"trace[1132366558] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15982; }","duration":"118.57057ms","start":"2026-06-27T00:39:58.694569Z","end":"2026-06-27T00:39:58.813139Z","steps":["trace[1132366558] 'agreement among raft nodes before linearized reading' (duration: 118.390446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:39:58.813168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.875607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-27T00:39:58.813192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.294344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-06-27T00:39:58.813244Z","caller":"traceutil/trace.go:171","msg":"trace[725143310] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15982; }","duration":"118.98933ms","start":"2026-06-27T00:39:58.694231Z","end":"2026-06-27T00:39:58.81322Z","steps":["trace[725143310] 'agreement among raft nodes before linearized reading' (duration: 118.766165ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:39:58.813286Z","caller":"traceutil/trace.go:171","msg":"trace[160966610] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15982; }","duration":"118.417856ms","start":"2026-06-27T00:39:58.694843Z","end":"2026-06-27T00:39:58.813261Z","steps":["trace[160966610] 'agreement among raft nodes before linearized reading' (duration: 118.087938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:39:58.813465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.130403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-27T00:39:58.813527Z","caller":"traceutil/trace.go:171","msg":"trace[596059115] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15982; }","duration":"121.233695ms","start":"2026-06-27T00:39:58.69228Z","end":"2026-06-27T00:39:58.813514Z","steps":["trace[596059115] 'agreement among raft nodes before linearized reading' (duration: 120.557719ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:39:59.721335Z","caller":"traceutil/trace.go:171","msg":"trace[1676224262] transaction","detail":"{read_only:false; response_revision:15984; number_of_response:1; }","duration":"113.079317ms","start":"2026-06-27T00:39:59.608217Z","end":"2026-06-27T00:39:59.721297Z","steps":["trace[1676224262] 'process raft request' (duration: 112.688778ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:42:52.569849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14622} {"level":"info","ts":"2026-06-27T00:42:52.648256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14622,"took":"74.42006ms","hash":401724785,"current-db-size-bytes":56373248,"current-db-size":"56 MB","current-db-size-in-use-bytes":32473088,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-27T00:42:52.648314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":401724785,"revision":14622,"compact-revision":12603} {"level":"info","ts":"2026-06-27T00:45:47.45504Z","caller":"traceutil/trace.go:171","msg":"trace[1316425810] transaction","detail":"{read_only:false; response_revision:18539; number_of_response:1; }","duration":"109.624903ms","start":"2026-06-27T00:45:47.345376Z","end":"2026-06-27T00:45:47.455001Z","steps":["trace[1316425810] 'process raft request' (duration: 109.362757ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:45:47.597537Z","caller":"traceutil/trace.go:171","msg":"trace[2026120958] linearizableReadLoop","detail":"{readStateIndex:19173; appliedIndex:19172; }","duration":"142.58773ms","start":"2026-06-27T00:45:47.454923Z","end":"2026-06-27T00:45:47.597511Z","steps":["trace[2026120958] 'read index received' (duration: 141.855872ms)","trace[2026120958] 'applied index is now lower than readState.Index' (duration: 730.418µs)"],"step_count":2} {"level":"info","ts":"2026-06-27T00:45:47.597647Z","caller":"traceutil/trace.go:171","msg":"trace[1015587106] transaction","detail":"{read_only:false; response_revision:18540; number_of_response:1; }","duration":"163.493957ms","start":"2026-06-27T00:45:47.434144Z","end":"2026-06-27T00:45:47.597638Z","steps":["trace[1015587106] 'process raft request' (duration: 163.18633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:45:47.597997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.862061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-s5c4v\" ","response":"range_response_count:1 size:22090"} {"level":"info","ts":"2026-06-27T00:45:47.59811Z","caller":"traceutil/trace.go:171","msg":"trace[1732625225] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-s5c4v; range_end:; response_count:1; response_revision:18540; }","duration":"158.011044ms","start":"2026-06-27T00:45:47.440075Z","end":"2026-06-27T00:45:47.598086Z","steps":["trace[1732625225] 'agreement among raft nodes before linearized reading' (duration: 157.667466ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:45:47.626327Z","caller":"traceutil/trace.go:171","msg":"trace[66836771] transaction","detail":"{read_only:false; response_revision:18541; number_of_response:1; }","duration":"160.486744ms","start":"2026-06-27T00:45:47.465819Z","end":"2026-06-27T00:45:47.626306Z","steps":["trace[66836771] 'process raft request' (duration: 160.179167ms)"],"step_count":1} {"level":"warn","ts":"2026-06-27T00:45:47.6268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.061066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-27T00:45:47.62686Z","caller":"traceutil/trace.go:171","msg":"trace[421544345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18541; }","duration":"139.164328ms","start":"2026-06-27T00:45:47.487681Z","end":"2026-06-27T00:45:47.626845Z","steps":["trace[421544345] 'agreement among raft nodes before linearized reading' (duration: 138.898412ms)"],"step_count":1} {"level":"info","ts":"2026-06-27T00:47:52.575974Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17225} {"level":"info","ts":"2026-06-27T00:47:52.673201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17225,"took":"92.983162ms","hash":761422577,"current-db-size-bytes":56373248,"current-db-size":"56 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-27T00:47:52.673462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":761422577,"revision":17225,"compact-revision":14622} {"level":"info","ts":"2026-06-27T00:48:00.086175Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"51a5c416a4d98c86","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-27T00:48:00.092814Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-27T00:48:00.092994Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-27T00:52:52.580969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19298} {"level":"info","ts":"2026-06-27T00:52:52.674869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19298,"took":"90.375465ms","hash":2959290611,"current-db-size-bytes":56373248,"current-db-size":"56 MB","current-db-size-in-use-bytes":24993792,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-27T00:52:52.674927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2959290611,"revision":19298,"compact-revision":17225}